IDLE Connections and Pool ConnectionDestroyedCount near zero
skajotde Aug 29, 2007 2:14 PM
Hello
I have seeking on forum but with no effects.
JBoss 4.0.3SP1
Posgtres 8.x
I have problem with production serwer. Per one day all connections from pool are excused and connections not come back to pool (they are IDLE - I checked with ps aux | grep posgtres)
According to jmx-console all connections are "in use" (InUseConnectionCount) and none of them are closed (ConnectionDestroyedCount, near zero, but sometime incease, but very slow)
On my testing serwer there is No problem. All connections come back to the pool and ConnectionDestroyedCount from jmx is instantly grow up.
log for debugging:
<category name="org.jboss.resource" additivity="false" > <priority value="TRACE" class="org.jboss.logging.XLevel"/> <appender-ref ref="STUDENTLOGDEBUG"/> </category> <category name="org.jboss" additivity="false" > <priority value="DEBUG"/> <appender-ref ref="STUDENTLOGDEBUG"/> </category> <category name="org.jboss.tm" additivity="false" > <priority value="TRACE" class="org.jboss.logging.XLevel"/> <appender-ref ref="STUDENTLOGDEBUG"/> </category> <category name="org.hibernate.jdbc.ConnectionManager" additivity="false" > <priority value="TRACE" class="org.jboss.logging.XLevel"/> <appender-ref ref="STUDENTLOGDEBUG"/> </category> <category name="org.hibernate.SQL" additivity="false" > <priority value="ERROR"/> <appender-ref ref="STUDENTLOGDEBUG"/> </category>
There are logs for production serwer for one click (as you will see connection is closed via entitymanager):
======================== Production Environment: ======================== 2007-08-29 19:24:54,323 TRACE [org.jboss.tm.TransactionImpl] Created new instance for tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,323 TRACE [org.jboss.tm.TxManager] began tx: TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,323 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] GETTING NEW EntityManager 2007-08-29 19:24:54,323 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] logLivedSession.get() was NULL 2007-08-29 19:24:54,323 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] LONG LIVED NOT FOUND 2007-08-29 19:24:54,323 TRACE [org.jboss.tm.TransactionImpl] registerSynchronization(): Entered, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] status=STATUS_ACTIVE 2007-08-29 19:24:54,324 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection 2007-08-29 19:24:54,324 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Pre-enlist: org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=null] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,324 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Get synchronizer org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=null] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,324 TRACE [org.jboss.tm.TransactionImpl] registerSynchronization(): Entered, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] status=STATUS_ACTIVE 2007-08-29 19:24:54,325 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Enlisting resource org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=false}] 2007-08-29 19:24:54,325 TRACE [org.jboss.tm.TransactionImpl] enlistResource(): Entered, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] status=STATUS_ACTIVE xaRes=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a 2007-08-29 19:24:54,325 TRACE [org.jboss.tm.TransactionImpl] startResource(XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=1, localId=8248]) entered: org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a flags=0 2007-08-29 19:24:54,325 TRACE [org.jboss.tm.TransactionImpl] startResource(XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=1, localId=8248]) leaving: org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a flags=0 2007-08-29 19:24:54,325 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Enlisted resource org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=false}] 2007-08-29 19:24:54,325 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Check enlisted org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=true}] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,325 TRACE [org.jboss.tm.TransactionImpl] registerSynchronization(): Entered, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] status=STATUS_ACTIVE 2007-08-29 19:24:54,326 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection 2007-08-29 19:24:54,326 DEBUG [org.hibernate.jdbc.ConnectionManager] closing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)] 2007-08-29 19:24:54,326 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] connectionClosed called mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 2007-08-29 19:24:54,326 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] unregisterConnection: 0 handles left 2007-08-29 19:24:54,326 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] isManagedConnectionFree=false mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 2007-08-29 19:24:54,326 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection 2007-08-29 19:24:54,326 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Pre-enlist: org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=true}] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,326 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Get synchronizer org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=true}] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,326 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Check enlisted org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=true}] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,327 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection 2007-08-29 19:24:54,327 DEBUG [org.hibernate.jdbc.ConnectionManager] closing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)] 2007-08-29 19:24:54,327 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] connectionClosed called mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 2007-08-29 19:24:54,327 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] unregisterConnection: 0 handles left 2007-08-29 19:24:54,327 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] isManagedConnectionFree=false mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 2007-08-29 19:24:54,327 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection 2007-08-29 19:24:54,327 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Pre-enlist: org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=true}] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,327 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Get synchronizer org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=true}] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,327 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Check enlisted org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a txSync=TxSync1299245257{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] wasTrackByTx=true enlisted=true}] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,328 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection 2007-08-29 19:24:54,328 DEBUG [org.hibernate.jdbc.ConnectionManager] closing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)] 2007-08-29 19:24:54,328 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] connectionClosed called mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 2007-08-29 19:24:54,328 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] unregisterConnection: 0 handles left 2007-08-29 19:24:54,328 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] isManagedConnectionFree=false mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@12f59857 2007-08-29 19:24:54,333 TRACE [org.jboss.tm.TransactionImpl] Committing, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248], status=STATUS_ACTIVE 2007-08-29 19:24:54,333 TRACE [org.jboss.tm.TransactionImpl] calling sync 0, org.jboss.ejb3.entity.ManagedEntityManagerFactory$SessionSynchronization@557c3ff4 tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,333 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] ************** flushing..... 2007-08-29 19:24:54,333 TRACE [org.jboss.tm.TransactionImpl] calling sync 1, org.jboss.resource.connectionmanager.TransactionSynchronizer@694e00de tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,333 TRACE [org.jboss.tm.TransactionImpl] calling sync 2, org.hibernate.transaction.CacheSynchronization tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,333 TRACE [org.jboss.tm.TransactionImpl] Before completion done, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248], status=STATUS_ACTIVE 2007-08-29 19:24:54,333 TRACE [org.jboss.tm.TransactionImpl] endresources(org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a): state=1 2007-08-29 19:24:54,333 TRACE [org.jboss.tm.TransactionImpl] endResource(XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=1, localId=8248]) entered: org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a flag=67108864 2007-08-29 19:24:54,333 TRACE [org.jboss.tm.TransactionImpl] endResource(XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=1, localId=8248]) leaving: org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a flag=67108864 2007-08-29 19:24:54,334 TRACE [org.jboss.tm.TransactionImpl] One phase commit TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248]: One resource. 2007-08-29 19:24:54,334 TRACE [org.jboss.tm.TransactionImpl] Committing resource org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@39e4817a state=3 2007-08-29 19:24:54,334 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] ************** closing entity managersession ************** 2007-08-29 19:24:54,334 TRACE [org.jboss.tm.TransactionImpl] Committed OK, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:54,334 TRACE [org.jboss.tm.TxManager] commited tx: TransactionImpl:XidImpl[FormatId=257, GlobalId=amon/8248, BranchQual=, localId=8248] 2007-08-29 19:24:58,668 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 150000 2007-08-29 19:24:58,674 DEBUG [org.jboss.resource.adapter.jdbc.local.LocalManagedConnectionFactory] Using properties: {user=some, password=--hidden--} 2007-08-29 19:24:58,679 DEBUG [org.jboss.resource.adapter.jdbc.local.LocalManagedConnectionFactory] Using properties: {user=some, password=--hidden--} 2007-08-29 19:24:58,683 DEBUG [org.jboss.resource.adapter.jdbc.local.LocalManagedConnectionFactory] Using properties: {user=some, password=--hidden--} 2007-08-29 19:24:58,687 DEBUG [org.jboss.resource.adapter.jdbc.local.LocalManagedConnectionFactory] Using properties: {user=some, password=--hidden--}
Logs from testing server
============= Testing Env ============ 2007-08-29 19:56:44,340 TRACE [org.jboss.tm.TransactionImpl] Created new instance for tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,340 TRACE [org.jboss.tm.TxManager] began tx: TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,340 TRACE [org.jboss.resource.connectionmanager.CachedConnectionManager] user tx started, key: null 2007-08-29 19:56:44,348 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] GETTING NEW EntityManager 2007-08-29 19:56:44,348 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] logLivedSession.get() was NULL 2007-08-29 19:56:44,348 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] LONG LIVED NOT FOUND 2007-08-29 19:56:44,348 TRACE [org.jboss.tm.TransactionImpl] registerSynchronization(): Entered, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] status=STATUS_ACTIVE 2007-08-29 19:56:44,349 TRACE [org.jboss.tm.TransactionImpl] registerSynchronization(): Entered, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] status=STATUS_ACTIVE 2007-08-29 19:56:44,349 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection 2007-08-29 19:56:44,349 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] subject: null 2007-08-29 19:56:44,349 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] getManagedConnection trackByTx=true tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,349 TRACE [org.jboss.resource.connectionmanager.JBossManagedConnectionPool] supplying ManagedConnection from pool: org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=false trackByTx=false mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=null] 2007-08-29 19:56:44,349 TRACE [org.jboss.resource.connectionmanager.JBossManagedConnectionPool] Got connection from pool tracked by transaction org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=false mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=null] tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] [InUse/Available/Max]: [1/399/400] 2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.JBossManagedConnectionPool] Using connection from pool tracked by transaction org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=null] tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] [InUse/Available/Max]: [1/399/400] 2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Pre-enlist: org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=null] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62]2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Get synchronizer org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=null] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,350 TRACE [org.jboss.tm.TransactionImpl] registerSynchronization(): Entered, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] status=STATUS_ACTIVE 2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Enlisting resource org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=TxSync23129503{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] wasTrackByTx=true enlisted=false}] 2007-08-29 19:56:44,350 TRACE [org.jboss.tm.TransactionImpl] enlistResource(): Entered, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] status=STATUS_ACTIVE xaRes=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 2007-08-29 19:56:44,350 TRACE [org.jboss.tm.TransactionImpl] startResource(XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=1, localId=62]) entered: org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 flags=0 2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] start, xid: XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=1, localId=62], flags: 0 2007-08-29 19:56:44,350 TRACE [org.jboss.tm.TransactionImpl] startResource(XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=1, localId=62]) leaving: org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 flags=0 2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Enlisted resource org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=TxSync23129503{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] wasTrackByTx=true enlisted=false}] 2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Check enlisted org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=TxSync23129503{tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] wasTrackByTx=true enlisted=true}] threadTx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.CachedConnectionManager] registering connection from org.jboss.resource.connectionmanager.TxConnectionManager@e62a39, connection : org.jboss.resource.adapter.jdbc.WrappedConnection@907a06, key: null 2007-08-29 19:56:44,359 TRACE [org.jboss.tm.TransactionImpl] Committing, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62], status=STATUS_ACTIVE 2007-08-29 19:56:44,359 TRACE [org.jboss.tm.TransactionImpl] calling sync 0, org.jboss.ejb3.entity.ManagedEntityManagerFactory$SessionSynchronization@2cc7b9 tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,359 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] ************** flushing..... 2007-08-29 19:56:44,359 TRACE [org.jboss.tm.TransactionImpl] calling sync 1, org.hibernate.transaction.CacheSynchronization tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,359 TRACE [org.jboss.tm.TransactionImpl] calling sync 2, org.jboss.resource.connectionmanager.TransactionSynchronizer@1ec49f4 tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,359 TRACE [org.jboss.tm.TransactionImpl] Before completion done, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62], status=STATUS_ACTIVE 2007-08-29 19:56:44,359 TRACE [org.jboss.tm.TransactionImpl] endresources(org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9): state=1 2007-08-29 19:56:44,359 TRACE [org.jboss.tm.TransactionImpl] endResource(XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=1, localId=62]) entered: org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 flag=67108864 2007-08-29 19:56:44,359 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] end on xid: XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=1, localId=62] called with flags 67108864 2007-08-29 19:56:44,360 TRACE [org.jboss.tm.TransactionImpl] endResource(XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=1, localId=62]) leaving: org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 flag=67108864 2007-08-29 19:56:44,360 TRACE [org.jboss.tm.TransactionImpl] One phase commit TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62]: One resource. 2007-08-29 19:56:44,360 TRACE [org.jboss.tm.TransactionImpl] Committing resource org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 state=3 2007-08-29 19:56:44,360 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] ************** closing entity managersession ************** 2007-08-29 19:56:44,360 DEBUG [org.hibernate.jdbc.ConnectionManager] closing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)] 2007-08-29 19:56:44,360 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] connectionClosed called mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 2007-08-29 19:56:44,360 TRACE [org.jboss.resource.connectionmanager.CachedConnectionManager] unregistering connection from org.jboss.resource.connectionmanager.TxConnectionManager@e62a39, object: org.jboss.resource.adapter.jdbc.WrappedConnection@907a06, key: null 2007-08-29 19:56:44,360 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] unregisterConnection: 0 handles left 2007-08-29 19:56:44,360 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] isManagedConnectionFree=false mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 2007-08-29 19:56:44,360 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection 2007-08-29 19:56:44,360 TRACE [org.jboss.resource.connectionmanager.JBossManagedConnectionPool] putting ManagedConnection back into pool kill=false cl=org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=false mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=null] 2007-08-29 19:56:44,360 TRACE [org.jboss.resource.connectionmanager.JBossManagedConnectionPool] Returning connection to pool org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@19e3c22 handles=0 lastUse=1188410204360 permit=false trackByTx=false mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource@17e00e9 txSync=null] [InUse/Available/Max]: [0/400/400] 2007-08-29 19:56:44,360 TRACE [org.jboss.tm.TransactionImpl] Committed OK, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62] 2007-08-29 19:56:44,360 TRACE [org.jboss.tm.TxManager] commited tx: TransactionImpl:XidImpl[FormatId=257, GlobalId=serwer27/62, BranchQual=, localId=62]
As you see, in the first case connection NOT return to the pool but in second, yes.
Could you help me?
--
Kamil