Aleksey Sushko created ARIES-1279:
-------------------------------------
Summary: Aries Transactions: Emergency closes JDBC connection
before the transaction is rolled back
Key: ARIES-1279
URL: https://issues.apache.org/jira/browse/ARIES-1279
Project: Aries
Issue Type: Bug
Components: Transaction
Environment: org.apache.aries.transaction.jdbc:2.1.0
org.apache.aries.transaction.manager:1.1.0
org.apache.geronimo.components.geronimo-connector:3.1.1
Reporter: Aleksey Sushko
ManagedJDBCConnection emergency closes JDBC connection before the transaction
is rolled back.
Create a service of the JDBC DataSource.
{code}
<bean id="dataSourceOragnizer" class="oracle.jdbc.pool.OracleDataSource">
<cm:managed-properties
persistent-id="oracle_oragnizer"
update-strategy="component-managed"
update-method="update"/>
</bean>
<service ref="dataSourceOragnizer" interface="javax.sql.DataSource">
<service-properties>
<entry key="datasource.name" value="db-organizer"/>
<entry key="aries.xa.name" value="db-organizer"/>
<entry key="aries.xa.username" value="test"/>
<entry key="aries.xa.password" value="test"/>
<entry key="aries.xa.partitionStrategy" value="by-connector-properties"/>
</service-properties>
</service>
{code}
In SQL query has a bug.
{code}
2014-12-08 01:04:20,214 | TRACE | l Console Thread |
TransactionCachingInterceptor | nd.TransactionCachingInterceptor 97 | 135
- org.apache.geronimo.components.geronimo-connector - 3.1.1 | supplying
connection from tx cache for tx caching interceptor
org.apache.geronimo.connector.outbound.TransactionCachingInterceptor@c4b525e
handle: nullManagedConnectionInfo:
org.apache.geronimo.connector.outbound.ManagedConnectionInfo@5fc3d460. mc:
org.tranql.connector.jdbc.ManagedJDBCConnection@31ac1bbe]
2014-12-08 01:04:20,220 | WARN | l Console Thread |
GeronimoConnectionEventListener | .GeronimoConnectionEventListener 89 | 135
- org.apache.geronimo.components.geronimo-connector - 3.1.1 |
connectionErrorOccurred called with null
java.sql.SQLException: ORA-00904: "DOC_NUMBER": invalid identifier
...
{code}
Further, there is an emergency closing the connection
{code}
2014-12-08 01:04:20,227 | TRACE | l Console Thread |
TransactionCachingInterceptor | nd.TransactionCachingInterceptor 117 | 135
- org.apache.geronimo.components.geronimo-connector - 3.1.1 | destroying
connection for tx caching interceptor
org.apache.geronimo.connector.outbound.TransactionCachingInterceptor@c4b525e
handle: nullManagedConnectionInfo:
org.apache.geronimo.connector.outbound.ManagedConnectionInfo@5fc3d460. mc:
org.tranql.connector.jdbc.ManagedJDBCConnection@31ac1bbe]
2014-12-08 01:04:20,227 | TRACE | l Console Thread |
TransactionEnlistingInterceptor | .TransactionEnlistingInterceptor 96 | 135
- org.apache.geronimo.components.geronimo-connector - 3.1.1 | Delisting
connection handle: nullManagedConnectionInfo:
org.apache.geronimo.connector.outbound.ManagedConnectionInfo@5fc3d460. mc:
org.tranql.connector.jdbc.ManagedJDBCConnection@31ac1bbe] with XAResource
org.apache.geronimo.connector.outbound.LocalXAResource@58abcff3 in transaction:
org.apache.geronimo.transaction.manager.TransactionImpl@76d51962
java.lang.Exception: stack trace
at
org.apache.geronimo.connector.outbound.TransactionEnlistingInterceptor.returnConnection(TransactionEnlistingInterceptor.java:96)[135:org.apache.geronimo.components.geronimo-connector:3.1.1]
at
org.apache.geronimo.connector.outbound.TransactionCachingInterceptor.returnConnection(TransactionCachingInterceptor.java:119)[135:org.apache.geronimo.components.geronimo-connector:3.1.1]
at
org.apache.geronimo.connector.outbound.ConnectionHandleInterceptor.returnConnection(ConnectionHandleInterceptor.java:71)[135:org.apache.geronimo.components.geronimo-connector:3.1.1]
at
org.apache.geronimo.connector.outbound.TCCLInterceptor.returnConnection(TCCLInterceptor.java:50)[135:org.apache.geronimo.components.geronimo-connector:3.1.1]
at
org.apache.geronimo.connector.outbound.ConnectionTrackingInterceptor.returnConnection(ConnectionTrackingInterceptor.java:91)[135:org.apache.geronimo.components.geronimo-connector:3.1.1]
at
org.apache.geronimo.connector.outbound.GeronimoConnectionEventListener.connectionErrorOccurred(GeronimoConnectionEventListener.java:95)[135:org.apache.geronimo.components.geronimo-connector:3.1.1]
at
org.tranql.connector.AbstractManagedConnection.unfilteredConnectionError(AbstractManagedConnection.java:126)[133:org.apache.aries.transaction.jdbc:2.1.0]
at
org.tranql.connector.AbstractManagedConnection.connectionError(AbstractManagedConnection.java:115)[133:org.apache.aries.transaction.jdbc:2.1.0]
at
org.tranql.connector.jdbc.ConnectionHandle.connectionError(ConnectionHandle.java:112)[133:org.apache.aries.transaction.jdbc:2.1.0]
at
org.tranql.connector.jdbc.PreparedStatementHandle.execute(PreparedStatementHandle.java:179)[133:org.apache.aries.transaction.jdbc:2.1.0]
...
2014-12-08 01:04:20,230 | TRACE | l Console Thread |
tSinglePoolConnectionInterceptor | tSinglePoolConnectionInterceptor 106 | 135
- org.apache.geronimo.components.geronimo-connector - 3.1.1 | returning
connection null for MCI ManagedConnectionInfo:
org.apache.geronimo.connector.outbound.ManagedConnectionInfo@5fc3d460. mc:
org.tranql.connector.jdbc.ManagedJDBCConnection@31ac1bbe] and MC
org.tranql.connector.jdbc.ManagedJDBCConnection@31ac1bbe to pool
org.apache.geronimo.connector.outbound.SinglePoolConnectionInterceptor@1a51706c
2014-12-08 01:04:20,230 | INFO | l Console Thread |
SinglePoolConnectionInterceptor | .SinglePoolConnectionInterceptor 146 | 135
- org.apache.geronimo.components.geronimo-connector - 3.1.1 | Removing
ManagedConnectionInfo:
org.apache.geronimo.connector.outbound.ManagedConnectionInfo@5fc3d460. mc:
org.tranql.connector.jdbc.ManagedJDBCConnection@31ac1bbe] from pool
org.apache.geronimo.connector.outbound.SinglePoolConnectionInterceptor@1a51706c
2014-12-08 01:04:20,231 | TRACE | l Console Thread |
tSinglePoolConnectionInterceptor | tSinglePoolConnectionInterceptor 183 | 135
- org.apache.geronimo.components.geronimo-connector - 3.1.1 | Discarding
connection in pool
org.apache.geronimo.connector.outbound.SinglePoolConnectionInterceptor@1a51706c
handle: nullManagedConnectionInfo:
org.apache.geronimo.connector.outbound.ManagedConnectionInfo@5fc3d460. mc:
org.tranql.connector.jdbc.ManagedJDBCConnection@31ac1bbe]
{code}
Further, there is rolled back. The system tries again to close the connection.
{code}
2014-12-08 01:04:20,246 | ERROR | l Console Thread | RollbackTask
| transaction.manager.RollbackTask 68 | 69 -
org.apache.aries.transaction.manager - 1.1.0 | Unexpected exception committing
org.apache.geronimo.connector.outbound.LocalXAResource@58abcff3; continuing to
commit other RMs
javax.transaction.xa.XAException
at
org.apache.geronimo.connector.outbound.LocalXAResource.rollback(LocalXAResource.java:88)[135:org.apache.geronimo.components.geronimo-connector:3.1.1]
at
org.apache.geronimo.transaction.manager.RollbackTask.run(RollbackTask.java:64)[69:org.apache.aries.transaction.manager:1.1.0]
at
org.apache.geronimo.transaction.manager.TransactionImpl.rollbackResources(TransactionImpl.java:599)[69:org.apache.aries.transaction.manager:1.1.0]
at
org.apache.geronimo.transaction.manager.TransactionImpl.rollback(TransactionImpl.java:498)[69:org.apache.aries.transaction.manager:1.1.0]
at
org.apache.geronimo.transaction.manager.TransactionManagerImpl.rollback(TransactionManagerImpl.java:265)[69:org.apache.aries.transaction.manager:1.1.0]
at
myapp.impl.commands.ImportDoc.doExecute(ImportDoc.java:165)[141:documents-reload:2.2.0.SNAPSHOT]
at
org.apache.karaf.shell.console.OsgiCommandSupport.execute(OsgiCommandSupport.java:38)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.felix.gogo.commands.basic.AbstractCommand.execute(AbstractCommand.java:35)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.felix.gogo.runtime.CommandProxy.execute(CommandProxy.java:78)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.felix.gogo.runtime.Closure.executeCmd(Closure.java:474)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.felix.gogo.runtime.Closure.executeStatement(Closure.java:400)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.felix.gogo.runtime.Pipe.run(Pipe.java:108)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.felix.gogo.runtime.Closure.execute(Closure.java:183)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.felix.gogo.runtime.Closure.execute(Closure.java:120)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.felix.gogo.runtime.CommandSessionImpl.execute(CommandSessionImpl.java:89)[16:org.apache.karaf.shell.console:2.3.8]
at
org.apache.karaf.shell.console.jline.Console.run(Console.java:184)[16:org.apache.karaf.shell.console:2.3.8]
at java.lang.Thread.run(Thread.java:745)[:1.7.0_72]
Caused by: javax.resource.spi.LocalTransactionException: Unable to rollback
at
org.tranql.connector.jdbc.ManagedJDBCConnection.localTransactionRollback(ManagedJDBCConnection.java:113)[133:org.apache.aries.transaction.jdbc:2.1.0]
at
org.tranql.connector.AbstractManagedConnection$LocalTransactionImpl.rollback(AbstractManagedConnection.java:203)[133:org.apache.aries.transaction.jdbc:2.1.0]
at
org.apache.geronimo.connector.outbound.LocalXAResource.rollback(LocalXAResource.java:86)[135:org.apache.geronimo.components.geronimo-connector:3.1.1]
... 16 more
Caused by: java.sql.SQLException: Closed Connection
at
oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:113)[132:wrap_mvn_com.oracle_ojdbc14_10.2.0.5:0]
at
oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:147)[132:wrap_mvn_com.oracle_ojdbc14_10.2.0.5:0]
at
oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:209)[132:wrap_mvn_com.oracle_ojdbc14_10.2.0.5:0]
at
oracle.jdbc.driver.PhysicalConnection.rollback(PhysicalConnection.java:1195)[132:wrap_mvn_com.oracle_ojdbc14_10.2.0.5:0]
at
oracle.jdbc.OracleConnectionWrapper.rollback(OracleConnectionWrapper.java:122)[132:wrap_mvn_com.oracle_ojdbc14_10.2.0.5:0]
at
org.tranql.connector.jdbc.ManagedJDBCConnection.localTransactionRollback(ManagedJDBCConnection.java:111)[133:org.apache.aries.transaction.jdbc:2.1.0]
... 18 more
{code}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
