[jboss-user] [JCA/JBoss] - IDLE Connections and Pool ConnectionDestroyedCount near zero
skajotde
do-not-reply at jboss.com
Wed Aug 29 14:14:15 EDT 2007
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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 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 at 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 at 39e4817a flags=0
| 2007-08-29 19:24:54,325 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Enlisted resource org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 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 at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 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 at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 6ad81f66[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 12f59857 handles=0 lastUse=1188408214191 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 41086d8b context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 4bef641c xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=false trackByTx=false mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=false mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at 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 at 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 at 17e00e9 flags=0
| 2007-08-29 19:56:44,350 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] Enlisted resource org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at e62a39, connection : org.jboss.resource.adapter.jdbc.WrappedConnection at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 19e3c22
| 2007-08-29 19:56:44,360 TRACE [org.jboss.resource.connectionmanager.CachedConnectionManager] unregistering connection from org.jboss.resource.connectionmanager.TxConnectionManager at e62a39, object: org.jboss.resource.adapter.jdbc.WrappedConnection at 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 at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410195037 permit=true trackByTx=false mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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 at e82810[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection at 19e3c22 handles=0 lastUse=1188410204360 permit=false trackByTx=false mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool at 13ce184 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool at 1f9cdda xaResource=org.jboss.resource.connectionmanager.TxConnectionManager$LocalXAResource at 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
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4079297#4079297
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4079297
More information about the jboss-user
mailing list