[ https://issues.jboss.org/browse/TEIID-2502?page=com.atlassian.jira.plugin... ] luca gioppo commented on TEIID-2502: ------------------------------------ Here is the trace 10:31:24,820 DEBUG [org.teiid.COMMAND_LOG] (Worker1_QueryProcessorQueue30) START DATA SRC COMMAND: startTime=2013-05-16 10:31:24.82 requestID=vtrcecGayv27.12 sourceCommandID=0 txID=null modelName=twistDB translatorName=oracle sessionID=vtrcecGayv27 principal=user@teiid-security sql=SELECT g_0.ID_PT_LOCINC AS c_0, g_0.FK_INCIDENTE AS c_1, g_0.GEOMETRIA AS c_2 FROM twistDB.SISS_SDO_PT_LOCINC AS g_0 LIMIT 100 10:31:24,823 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue30) Source-specific command: SELECT c_0, c_1, c_2 FROM (SELECT g_0.ID_PT_LOCINC AS c_0, g_0.FK_INCIDENTE AS c_1, g_0.GEOMETRIA AS c_2 FROM "SISS"."SISS_SDO_PT_LOCINC" g_0) WHERE ROWNUM <= 100 10:31:24,825 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue29) Request Thread 10:31:25,289 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue30) vtrcecGayv27.12.0.1 10:31:25,290 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue30) vtrcecGayv27.12.0.1 10:31:25,290 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue30) vtrcecGayv27.12.0.1 10:31:25,297 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue30) vtrcecGayv27.12.0.1 10:31:25,298 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue31) Request Thread 10:31:25,300 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue31) QueryProcessor: closing processor 10:31:25,300 DEBUG [org.teiid.PROCESSOR] (Worker1_QueryProcessorQueue32) Running task for parent thread 10:31:25,301 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue31) [RequestWorkItem.sendResultsIfNeeded] requestID: 10:31:25,303 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue32) vtrcecGayv27.12.0.1 10:31:25,304 DEBUG [org.teiid.TRANSPORT] (Worker0_QueryProcessorQueue31) send message: MessageHolder: key=40 contents=ResultsMessage rowCount=100 finalRow=100 10:31:25,306 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue32) vtrcecGayv27.12.0.1 10:31:25,310 DEBUG [org.teiid.COMMAND_LOG] (Worker1_QueryProcessorQueue32) END SRC COMMAND: endTime=2013-05-16 10:31:25.309 requestID=vtrcecGayv27.12 sourceCommandID=0 txID=null modelName=twistDB translatorName=oracle sessionID=vtrcecGayv27 principal=user@teiid-security finalRowCount=100 10:31:25,316 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue32) vtrcecGayv27.12.0.1 10:31:25,321 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue32) vtrcecGayv27.12.0.1 10:31:25,327 DEBUG [org.teiid.TRANSPORT] (Worker0_QueryProcessorQueue31) Unhandled exception, closing client instance: java.io.NotSerializableException: oracle.jdbc.driver.T4CConnection at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1164) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.defaultWriteFields(ObjectOutputStream.java:1518) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1483) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1400) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1158) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:330) [rt.jar:1.6.0_25] at org.teiid.client.BatchSerializer$ColumnSerializer.writeObject(BatchSerializer.java:439) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.BatchSerializer$ObjectColumnSerializer.writeObject(BatchSerializer.java:245) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.BatchSerializer$ObjectColumnSerializer.writeObject(BatchSerializer.java:230) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.BatchSerializer$ColumnSerializer.writeColumn(BatchSerializer.java:424) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.BatchSerializer$ObjectColumnSerializer.writeColumn(BatchSerializer.java:215) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.BatchSerializer.writeBatch(BatchSerializer.java:721) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.ResultsMessage.writeExternal(ResultsMessage.java:277) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at java.io.ObjectOutputStream.writeExternalData(ObjectOutputStream.java:1429) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1398) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1158) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:330) [rt.jar:1.6.0_25] at org.teiid.net.socket.Message.writeExternal(Message.java:56) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at java.io.ObjectOutputStream.writeExternalData(ObjectOutputStream.java:1429) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1398) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1158) [rt.jar:1.6.0_25] at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:330) [rt.jar:1.6.0_25] at org.teiid.transport.ObjectEncoder.handleDownstream(ObjectEncoder.java:115) [teiid-runtime-8.2.0.Final.jar:8.2.0.Final] at org.jboss.netty.channel.Channels.write(Channels.java:611) [netty-3.2.6.Final.jar:] at org.jboss.netty.channel.Channels.write(Channels.java:578) [netty-3.2.6.Final.jar:] at org.jboss.netty.channel.AbstractChannel.write(AbstractChannel.java:251) [netty-3.2.6.Final.jar:] at org.teiid.transport.SSLAwareChannelHandler$ObjectChannelImpl.write(SSLAwareChannelHandler.java:94) [teiid-runtime-8.2.0.Final.jar:8.2.0.Final] at org.teiid.transport.SocketClientInstance.send(SocketClientInstance.java:82) [teiid-runtime-8.2.0.Final.jar:8.2.0.Final] at org.teiid.transport.ServerWorkItem.sendResult(ServerWorkItem.java:137) [teiid-runtime-8.2.0.Final.jar:8.2.0.Final] at org.teiid.transport.ServerWorkItem$1.onCompletion(ServerWorkItem.java:107) [teiid-runtime-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.util.ResultsFuture.done(ResultsFuture.java:130) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.util.ResultsFuture.access$200(ResultsFuture.java:37) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.client.util.ResultsFuture$1.receiveResults(ResultsFuture.java:75) [teiid-client-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.RequestWorkItem.sendResultsIfNeeded(RequestWorkItem.java:748) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.RequestWorkItem$1.flushBatchDirect(RequestWorkItem.java:567) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.query.processor.BatchCollector.flushBatch(BatchCollector.java:191) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:166) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.RequestWorkItem.processMore(RequestWorkItem.java:384) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:288) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:49) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:216) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:250) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.ThreadReuseExecutor$RunnableWrapper.run(ThreadReuseExecutor.java:123) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at org.teiid.dqp.internal.process.ThreadReuseExecutor$3.run(ThreadReuseExecutor.java:298) [teiid-engine-8.2.0.Final.jar:8.2.0.Final] at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [rt.jar:1.6.0_25] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [rt.jar:1.6.0_25] at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_25] 10:31:25,428 DEBUG [org.teiid.TRANSPORT] (Worker0_QueryProcessorQueue31) Channel closed 10:31:25,430 DEBUG [org.teiid.SECURITY] (Worker0_QueryProcessorQueue31) closeSession 10:31:25,432 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue31) cancelQuery for requestID=vtrcecGayv27.12 10:31:25,435 DEBUG [org.teiid.TXN_LOG] (Worker0_QueryProcessorQueue31) before cancelTransactions:org.teiid.dqp.internal.process.TransactionServerImpl@f2882ad(vtrcecGayv27,true) 10:31:25,439 DEBUG [org.teiid.TXN_LOG] (Worker0_QueryProcessorQueue31) after cancelTransactions : null 10:31:25,442 DEBUG [org.teiid.TXN_LOG] (Worker0_QueryProcessorQueue31) before cancelTransactions:org.teiid.dqp.internal.process.TransactionServerImpl@f2882ad(vtrcecGayv27,false) 10:31:25,446 DEBUG [org.teiid.TXN_LOG] (Worker0_QueryProcessorQueue31) after cancelTransactions : null 10:31:25,448 DEBUG [org.teiid.PROCESSOR] (Worker1_QueryProcessorQueue33) Request Thread -- This message is automatically generated by JIRA. If you think it was sent incorrectly, please contact your JIRA administrators For more information on JIRA, see: http://www.atlassian.com/software/jira