[teiid-issues] [JBoss JIRA] (TEIID-4303) Insert into temporary table fails

Bram Gadeyne (JIRA) issues at jboss.org
Tue Jun 28 10:07:00 EDT 2016


    [ https://issues.jboss.org/browse/TEIID-4303?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13257873#comment-13257873 ] 

Bram Gadeyne commented on TEIID-4303:
-------------------------------------

There are several:

1) 

2016-06-28 08:51:58,811 DEBUG [org.teiid.SECURITY] (NIO1)  Keep-alive ping received for session: 3bZw8VxEr4t7
2016-06-28 08:52:31,721 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper)  ARJUNA012117: TransactionReaper::check timeout for TX 0:ffff7f000101:2f4a8c3a:576d4165:72fc in state  RUN
2016-06-28 08:52:31,722 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper Worker 2)  ARJUNA012095: Abort of action id 0:ffff7f000101:2f4a8c3a:576d4165:72fc invoked while multiple threads active within it.
2016-06-28 08:52:31,722 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper Worker 2)  ARJUNA012108: CheckedAction::check - atomic action 0:ffff7f000101:2f4a8c3a:576d4165:72fc aborting with 1 threads active!
2016-06-28 08:52:32,221 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper)  ARJUNA012117: TransactionReaper::check timeout for TX 0:ffff7f000101:2f4a8c3a:576d4165:72fc in state  CANCEL
2016-06-28 08:52:32,222 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper)  ARJUNA012378: ReaperElement appears to be wedged: sun.misc.Unsafe.park(Native Method)
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836)
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:870)
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1199)
java.util.concurrent.locks.ReentrantLock$FairSync.lock(ReentrantLock.java:224)
java.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:285)
org.jboss.jca.adapters.jdbc.BaseWrapperManagedConnection.lock(BaseWrapperManagedConnection.java:378)
org.jboss.jca.adapters.jdbc.xa.XAManagedConnection.end(XAManagedConnection.java:290)
org.jboss.jca.core.tx.jbossts.XAResourceWrapperImpl.end(XAResourceWrapperImpl.java:118)
com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord.topLevelAbort(XAResourceRecord.java:318)
com.arjuna.ats.arjuna.coordinator.BasicAction.doAbort(BasicAction.java:2993)
com.arjuna.ats.arjuna.coordinator.BasicAction.doAbort(BasicAction.java:2972)
com.arjuna.ats.arjuna.coordinator.BasicAction.Abort(BasicAction.java:1675)
com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.cancel(TwoPhaseCoordinator.java:127)
com.arjuna.ats.arjuna.AtomicAction.cancel(AtomicAction.java:215)
com.arjuna.ats.arjuna.coordinator.TransactionReaper.doCancellations(TransactionReaper.java:381)
com.arjuna.ats.internal.arjuna.coordinator.ReaperWorkerThread.run(ReaperWorkerThread.java:78)

2016-06-28 08:52:32,722 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper)  ARJUNA012117: TransactionReaper::check timeout for TX 0:ffff7f000101:2f4a8c3a:576d4165:72fc in state  CANCEL_INTERRUPTED
2016-06-28 08:52:32,722 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper)  ARJUNA012120: TransactionReaper::check worker Thread[Transaction Reaper Worker 2,5,main] not responding to interrupt when cancelling TX 0:ffff7f000101:2f4a8c3a:576d4165:72fc -- worker marked as zombie and TX scheduled for mark-as-rollback
2016-06-28 08:52:32,723 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper)  ARJUNA012110: TransactionReaper::check successfuly marked TX 0:ffff7f000101:2f4a8c3a:576d4165:72fc as rollback only

2) 

doCancellations worker while retrieving data

2016-06-28 08:54:35,563 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Processing MORE request
2016-06-28 08:54:35,563 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Getting results from connector
2016-06-28 08:54:35,564 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Obtained results from connector, current row count: 3072
2016-06-28 08:54:35,564 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Processing MORE request
2016-06-28 08:54:35,565 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Getting results from connector
2016-06-28 08:54:35,565 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper Worker 2)  ARJUNA012113: TransactionReaper::doCancellations worker Thread[Transaction Reaper Worker 2,5,main] missed interrupt when cancelling TX 0:ffff7f000101:2f4a8c3a:576d4165:72fc -- exiting as zombie (zombie count decremented to 0)
2016-06-28 08:54:35,570 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Obtained results from connector, current row count: 3328
2016-06-28 08:54:35,570 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Processing MORE request
2016-06-28 08:54:35,570 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Getting results from connector
2016-06-28 08:54:35,571 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Obtained results from connector, current row count: 3584
2016-06-28 08:54:35,571 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Processing MORE request
2016-06-28 08:54:35,571 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Getting results from connector
2016-06-28 08:54:35,572 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Obtained results from connector, current row count: 3840




> Insert into temporary table fails
> ---------------------------------
>
>                 Key: TEIID-4303
>                 URL: https://issues.jboss.org/browse/TEIID-4303
>             Project: Teiid
>          Issue Type: Bug
>    Affects Versions: 9.0
>            Reporter: Bram Gadeyne
>            Assignee: Steven Hawkins
>         Attachments: debugplan.txt
>
>
> When executing a insert query in a temporary table the execution fails with the following error:
> 2016-06-28 08:54:35,700 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Closed execution
> 2016-06-28 08:54:35,700 DEBUG [org.teiid.CONNECTOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 3bZw8VxEr4t7.26.4.30 Closed connection
> 2016-06-28 08:54:35,700 DEBUG [org.teiid.COMMAND_LOG] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7    END SRC COMMAND:        endTime=2016-06-28 08:54:35.7   requestID=3bZw8VxEr4t7.26       sourceCommandID=4       executionID=30  txID=TransactionImple < ac, BasicAction: 0:ffff7f000101:2f4a8c3a:576d4165:72fc status: ActionStatus.ABORTED >   modelName=iziswh        translatorName=sybase   sessionID=3bZw8VxEr4t7  principal=veerle at teiid-security finalRowCount=21504     cpuTime(ns)=120503397
> 2016-06-28 08:54:35,701 INFO  [org.teiid.COMMAND_LOG] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7    ERROR USER COMMAND:     endTime=2016-06-28 08:54:35.701 requestID=3bZw8VxEr4t7.26       txID=TransactionImple < ac, BasicAction: 0:ffff7f000101:2f4a8c3a:576d4165:72fc status: ActionStatus.ABORTED >   sessionID=3bZw8VxEr4t7  principal=veerle at teiid-security vdbName=vdb3    vdbVersion=14   finalRowCount=null
> 2016-06-28 08:54:35,701 WARN  [org.teiid.PROCESSOR] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 TEIID30020 Processing exception for request 3bZw8VxEr4t7.26 'TEIID30223 javax.transaction.RollbackException: ARJUNA016083: Can't register synchronization because the transaction is in aborted state'. Originally TeiidProcessingException TransactionImple.java:396. Enable more detailed logging to see the entire stacktrace.
> 2016-06-28 08:54:35,701 WARN  [com.arjuna.ats.arjuna] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7 ARJUNA012077: Abort called on already aborted atomic action 0:ffff7f000101:2f4a8c3a:576d4165:72fc
> 2016-06-28 08:54:35,702 INFO  [org.teiid.COMMAND_LOG] (Worker12_QueryProcessorQueue243) 3bZw8VxEr4t7    END USER COMMAND:       endTime=2016-06-28 08:54:35.702 requestID=3bZw8VxEr4t7.26       txID=null       sessionID=3bZw8VxEr4t7  principal=veerle at teiid-security vdbName=vdb3    vdbVersion=14   finalRowCount=0



--
This message was sent by Atlassian JIRA
(v6.4.11#64026)


More information about the teiid-issues mailing list