Attachment 876366 Details for Bug 1078277 – Server log with the exception

Server log with the exception

server.log (text/x-log), 23.50 KB, created by Filip Elias on 2014-03-19 14:21:25 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Filip Elias

Created: 2014-03-19 14:21:25 UTC

Size: 23.50 KB

patch

obsolete

>15:12:34,914 DEBUG [org.teiid.SECURITY] (New I/O worker #2) Logon successful for " user "
>15:12:34,916 DEBUG [org.teiid.SECURITY] (New I/O worker #2) Logon successful, created session: sessionid=zIdv4tzs7Isu; userName=user@teiid-security; vdbName=ddl; vdbVersion=1; createdTime=Wed Mar 19 15:12:34 CET 2014; applicationName=JDBC; clientHostName=felias; clientHardwareAddress=null; IPAddress=127.0.1.1; securityDomain=teiid-security; lastPingTime=Wed Mar 19 15:12:34 CET 2014
>15:12:34,920 DEBUG [org.teiid.TRANSPORT] (New I/O worker #2) send message: MessageHolder: key=102 contents=javax.crypto.SealedObject@34abdac6
>15:12:37,153 DEBUG [org.teiid.TRANSPORT] (New I/O worker #2) processing message:MessageHolder: key=103 contents=Invoke interface org.teiid.client.DQP.executeRequest
>15:12:37,164 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #2) 	START USER COMMAND:	startTime=2014-03-19 15:12:37.163	requestID=zIdv4tzs7Isu.0	txID=null	sessionID=zIdv4tzs7Isu	applicationName=JDBC	principal=user@teiid-security	vdbName=ddl	vdbVersion=1	sql=select a.id from modelA.mysql.functionTest a where a.value=modelB.testBool()
>15:12:37,166 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue0) Request Thread zIdv4tzs7Isu.0 with state NEW
>15:12:37,166 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue0) zIdv4tzs7Isu.0 Non-cachable command.
>15:12:37,166 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue0) zIdv4tzs7Isu.0 executing  select a.id from modelA.mysql.functionTest a where a.value=modelB.testBool()
>15:12:37,258 INFO  [org.teiid.PLANNER] (Worker0_QueryProcessorQueue0) 
>============================================================================
>USER COMMAND:
>SELECT a.id FROM modelA.mysql.functionTest AS a WHERE a."value" = modelB.testBool() LIMIT 100
>
>----------------------------------------------------------------------------
>OPTIMIZE: 
>SELECT a.id FROM modelA.mysql.functionTest AS a WHERE a."value" = modelB.testBool() LIMIT 100
>
>----------------------------------------------------------------------------
>GENERATE CANONICAL: 
>SELECT a.id FROM modelA.mysql.functionTest AS a WHERE a."value" = modelB.testBool() LIMIT 100
>
>CANONICAL PLAN: 
>TupleLimit(groups=[], props={MAX_TUPLE_LIMIT=100})
>  Project(groups=[modelA.mysql.functionTest AS a], props={PROJECT_COLS=[a.id]})
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool()})
>      Source(groups=[modelA.mysql.functionTest AS a])
>
>
>============================================================================
>EXECUTING PlaceAccess
>
>AFTER: 
>TupleLimit(groups=[], props={MAX_TUPLE_LIMIT=100})
>  Project(groups=[modelA.mysql.functionTest AS a], props={PROJECT_COLS=[a.id]})
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool()})
>      Access(groups=[modelA.mysql.functionTest AS a], props={MODEL_ID=Schema name=modelA, nameInSource=null, uuid=tid:b4f21f9c200d-c04b6a38-00000000})
>        Source(groups=[modelA.mysql.functionTest AS a])
>
>
>============================================================================
>EXECUTING PushSelectCriteria
>
>AFTER: 
>TupleLimit(groups=[])
>  Project(groups=[modelA.mysql.functionTest AS a], props={PROJECT_COLS=[a.id]})
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool()})
>      Access(groups=[modelA.mysql.functionTest AS a], props={MODEL_ID=Schema name=modelA, nameInSource=null, uuid=tid:b4f21f9c200d-c04b6a38-00000000})
>        Source(groups=[modelA.mysql.functionTest AS a])
>
>
>============================================================================
>EXECUTING CleanCriteria
>
>AFTER: 
>TupleLimit(groups=[], props={MAX_TUPLE_LIMIT=100, OUTPUT_COLS=null})
>  Project(groups=[modelA.mysql.functionTest AS a], props={PROJECT_COLS=[a.id], OUTPUT_COLS=null})
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool(), OUTPUT_COLS=null})
>      Access(groups=[modelA.mysql.functionTest AS a], props={MODEL_ID=Schema name=modelA, nameInSource=null, uuid=tid:b4f21f9c200d-c04b6a38-00000000, OUTPUT_COLS=null})
>        Source(groups=[modelA.mysql.functionTest AS a], props={OUTPUT_COLS=null})
>
>
>============================================================================
>EXECUTING RaiseAccess
>LOW Relational Planner modelB.testBool function not supported by source modelA - modelB.testBool() was not pushed
>
>AFTER: 
>TupleLimit(groups=[])
>  Project(groups=[modelA.mysql.functionTest AS a])
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool(), OUTPUT_COLS=null})
>      Access(groups=[modelA.mysql.functionTest AS a], props={MODEL_ID=Schema name=modelA, nameInSource=null, uuid=tid:b4f21f9c200d-c04b6a38-00000000, OUTPUT_COLS=null})
>        Source(groups=[modelA.mysql.functionTest AS a])
>
>
>============================================================================
>EXECUTING PushLimit
>
>AFTER: 
>Project(groups=[modelA.mysql.functionTest AS a], props={PROJECT_COLS=[a.id], OUTPUT_COLS=null})
>  TupleLimit(groups=[], props={MAX_TUPLE_LIMIT=100, OUTPUT_COLS=null})
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool(), OUTPUT_COLS=null})
>      Access(groups=[modelA.mysql.functionTest AS a])
>        Source(groups=[modelA.mysql.functionTest AS a])
>
>
>============================================================================
>EXECUTING AssignOutputElements
>
>AFTER: 
>Project(groups=[modelA.mysql.functionTest AS a], props={PROJECT_COLS=[a.id], OUTPUT_COLS=[a.id]})
>  TupleLimit(groups=[], props={MAX_TUPLE_LIMIT=100, OUTPUT_COLS=[a.id]})
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool(), OUTPUT_COLS=[a.id]})
>      Access(groups=[modelA.mysql.functionTest AS a], props={MODEL_ID=Schema name=modelA, nameInSource=null, uuid=tid:b4f21f9c200d-c04b6a38-00000000, OUTPUT_COLS=[a."value", a.id]})
>        Source(groups=[modelA.mysql.functionTest AS a], props={OUTPUT_COLS=[a."value", a.id]})
>
>
>============================================================================
>EXECUTING CalculateCost
>
>AFTER: 
>Project(groups=[modelA.mysql.functionTest AS a], props={PROJECT_COLS=[a.id], OUTPUT_COLS=[a.id], EST_CARDINALITY=100.0, EST_COL_STATS={a.id=[100.0, 1.0]}})
>  TupleLimit(groups=[], props={MAX_TUPLE_LIMIT=100, OUTPUT_COLS=[a.id], EST_CARDINALITY=100.0, EST_COL_STATS={a.id=[100.0, 0.0]}})
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool(), OUTPUT_COLS=[a.id], EST_CARDINALITY=-1.0})
>      Access(groups=[modelA.mysql.functionTest AS a], props={MODEL_ID=Schema name=modelA, nameInSource=null, uuid=tid:b4f21f9c200d-c04b6a38-00000000, OUTPUT_COLS=[a."value", a.id], EST_CARDINALITY=-1.0})
>        Source(groups=[modelA.mysql.functionTest AS a], props={OUTPUT_COLS=[a."value", a.id], EST_COL_STATS={a."value"=[-1.0, -1.0], a.id=[-1.0, -1.0]}, EST_CARDINALITY=-1.0})
>
>
>============================================================================
>EXECUTING MergeCriteria
>
>AFTER: 
>Project(groups=[modelA.mysql.functionTest AS a])
>  TupleLimit(groups=[])
>    Select(groups=[modelA.mysql.functionTest AS a], props={SELECT_CRITERIA=a."value" = modelB.testBool(), OUTPUT_COLS=[a.id], EST_CARDINALITY=-1.0})
>      Access(groups=[modelA.mysql.functionTest AS a], props={MODEL_ID=Schema name=modelA, nameInSource=null, uuid=tid:b4f21f9c200d-c04b6a38-00000000, OUTPUT_COLS=[a."value", a.id], EST_CARDINALITY=-1.0})
>        Source(groups=[modelA.mysql.functionTest AS a], props={OUTPUT_COLS=[a."value", a.id], EST_COL_STATS={a."value"=[-1.0, -1.0], a.id=[-1.0, -1.0]}, EST_CARDINALITY=-1.0})
>
>
>============================================================================
>EXECUTING PlanSorts
>
>AFTER: 
>Project(groups=[modelA.mysql.functionTest AS a])
>  TupleLimit(groups=[])
>    Select(groups=[modelA.mysql.functionTest AS a])
>      Access(groups=[modelA.mysql.functionTest AS a])
>        Source(groups=[modelA.mysql.functionTest AS a])
>
>
>============================================================================
>EXECUTING CollapseSource
>
>AFTER: 
>Project(groups=[modelA.mysql.functionTest AS a])
>  TupleLimit(groups=[])
>    Select(groups=[modelA.mysql.functionTest AS a])
>      Access(groups=[modelA.mysql.functionTest AS a], props={MODEL_ID=Schema name=modelA, nameInSource=null, uuid=tid:b4f21f9c200d-c04b6a38-00000000, OUTPUT_COLS=[a."value", a.id], EST_CARDINALITY=-1.0, ATOMIC_REQUEST=SELECT a."value", a.id FROM modelA.mysql.functionTest AS a})
>
>
>============================================================================
>CONVERTING PLAN TREE TO PROCESS TREE
>
>PROCESS PLAN = 
>ProjectNode(0) output=[a.id] [a.id]
>  LimitNode(1) output=[a.id] limit 100
>    SelectNode(2) output=[a.id] a."value" = modelB.testBool()
>      AccessNode(3) output=[a."value", a.id] SELECT g_0."value", g_0.id FROM modelA.mysql.functionTest AS g_0
>
>============================================================================
>
>----------------------------------------------------------------------------
>OPTIMIZATION COMPLETE:
>PROCESSOR PLAN:
>ProjectNode(0) output=[a.id] [a.id]
>  LimitNode(1) output=[a.id] limit 100
>    SelectNode(2) output=[a.id] a."value" = modelB.testBool()
>      AccessNode(3) output=[a."value", a.id] SELECT g_0."value", g_0.id FROM modelA.mysql.functionTest AS g_0
>
>============================================================================
>
>15:12:37,260 DEBUG [org.teiid.PLANNER] (Worker0_QueryProcessorQueue0) [QueryAnnotation<Relational Planner, LOW,modelB.testBool function not supported by source modelA,modelB.testBool() was not pushed>]
>15:12:37,260 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue0) ProcessTree for zIdv4tzs7Isu.0 ProjectNode(0) output=[a.id] [a.id]
>  LimitNode(1) output=[a.id] limit 100
>    SelectNode(2) output=[a.id] a."value" = modelB.testBool()
>      AccessNode(3) output=[a."value", a.id] SELECT g_0."value", g_0.id FROM modelA.mysql.functionTest AS g_0
>
>15:12:37,261 DEBUG [org.teiid.TXN_LOG] (Worker0_QueryProcessorQueue0) before getOrCreateTransactionContext:org.teiid.dqp.internal.process.TransactionServerImpl@665f27f6(zIdv4tzs7Isu)
>15:12:37,261 DEBUG [org.teiid.TXN_LOG] (Worker0_QueryProcessorQueue0) after getOrCreateTransactionContext : zIdv4tzs7Isu NONE ID:NONE
>15:12:37,266 DEBUG [org.teiid.BUFFER_MGR] (Worker0_QueryProcessorQueue0) Creating TupleBuffer: 0 [a.id] [class java.lang.Long] batch size 2048 of type PROCESSOR
>15:12:37,268 DEBUG [org.teiid.CONNECTOR] (Worker0_QueryProcessorQueue0) zIdv4tzs7Isu.0.3.0 Create State
>15:12:37,279 DEBUG [org.teiid.BUFFER_MGR] (Worker0_QueryProcessorQueue0) zIdv4tzs7Isu.0.3.0 Blocking on source query zIdv4tzs7Isu.0.3.0
>15:12:37,279 DEBUG [org.teiid.BUFFER_MGR] (Worker0_QueryProcessorQueue0) zIdv4tzs7Isu.0 Blocking on source request(s).
>15:12:37,279 DEBUG [org.teiid.PROCESSOR] (Worker1_QueryProcessorQueue1) Running task for parent thread Worker0_QueryProcessorQueue0
>15:12:37,279 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue0) Request Thread zIdv4tzs7Isu.0 - processor blocked
>15:12:37,279 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue1) zIdv4tzs7Isu.0.3.0 Processing NEW request: SELECT g_0."value", g_0.id FROM modelA.mysql.functionTest AS g_0
>15:12:37,280 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (Worker1_QueryProcessorQueue1) mysql: getConnection(null, WrappedConnectionRequestInfo@21d97ba[userName=root]) [0/20]
>15:12:37,281 INFO  [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue1) MySQLExecutionFactory Commit=true;DatabaseProductName=MySQL;DatabaseProductVersion=5.5.32-0ubuntu0.13.04.1;DriverMajorVersion=5;DriverMajorVersion=1;DriverName=MySQL-AB JDBC Driver;DriverVersion=mysql-connector-java-5.1.21 ( Revision: ${bzr.revision-id} );IsolationLevel=2
>15:12:37,284 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue1) zIdv4tzs7Isu.0.3.0 Obtained execution
>15:12:37,285 DEBUG [org.teiid.COMMAND_LOG] (Worker1_QueryProcessorQueue1) 	START DATA SRC COMMAND:	startTime=2014-03-19 15:12:37.285	requestID=zIdv4tzs7Isu.0	sourceCommandID=3	executionID=0	txID=null	modelName=modelA	translatorName=mysql	sessionID=zIdv4tzs7Isu	principal=user@teiid-security	sql=SELECT g_0."value", g_0.id FROM modelA.mysql.functionTest AS g_0
>15:12:37,288 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue1) Source-specific command: SELECT g_0.`value`, g_0.`id` FROM `mysql`.`functionTest` AS g_0
>15:12:37,289 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue1) zIdv4tzs7Isu.0.3.0 Executed command
>15:12:37,289 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue1) zIdv4tzs7Isu.0.3.0 Processing MORE request
>15:12:37,289 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue1) zIdv4tzs7Isu.0.3.0 Getting results from connector
>15:12:37,290 DEBUG [org.teiid.CONNECTOR] (Worker1_QueryProcessorQueue1) zIdv4tzs7Isu.0.3.0 Obtained last batch, total row count: 3
>15:12:37,291 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue2) Request Thread zIdv4tzs7Isu.0 with state PROCESSING
>15:12:37,291 DEBUG [org.teiid.CONNECTOR] (Worker0_QueryProcessorQueue2) zIdv4tzs7Isu.0.3.0 Remove State
>15:12:37,292 DEBUG [org.teiid.CONNECTOR] (Worker0_QueryProcessorQueue2) zIdv4tzs7Isu.0.3.0 Processing Close : SELECT g_0."value", g_0.id FROM modelA.mysql.functionTest AS g_0
>15:12:37,292 DEBUG [org.teiid.COMMAND_LOG] (Worker0_QueryProcessorQueue2) 	END SRC COMMAND:	endTime=2014-03-19 15:12:37.292	requestID=zIdv4tzs7Isu.0	sourceCommandID=3	executionID=0	txID=null	modelName=modelA	translatorName=mysql	sessionID=zIdv4tzs7Isu	principal=user@teiid-security	finalRowCount=3
>15:12:37,292 DEBUG [org.teiid.CONNECTOR] (Worker0_QueryProcessorQueue2) zIdv4tzs7Isu.0.3.0 Closed execution
>15:12:37,295 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (Worker0_QueryProcessorQueue2) mysql: returnConnection(34014067, false) [1/20]
>15:12:37,295 DEBUG [org.teiid.CONNECTOR] (Worker0_QueryProcessorQueue2) zIdv4tzs7Isu.0.3.0 Closed connection
>15:12:37,296 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue2) QueryProcessor: closing processor
>15:12:37,296 DEBUG [org.teiid.COMMAND_LOG] (Worker0_QueryProcessorQueue2) 	ERROR USER COMMAND:	endTime=2014-03-19 15:12:37.296	requestID=zIdv4tzs7Isu.0	txID=null	sessionID=zIdv4tzs7Isu	principal=user@teiid-security	vdbName=ddl	vdbVersion=1	finalRowCount=null
>15:12:37,296 WARN  [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue2) TEIID30020 Processing exception for request zIdv4tzs7Isu.0 'TEIID30312 Unable to evaluate right expression of a."value" = modelB.testBool()'. Originally ExpressionEvaluationException 'TEIID30341 Function modelB.testBool is marked in the function metadata as a function that must be evaluated at the source.' FunctionDescriptor.java:195.: org.teiid.api.exception.query.ExpressionEvaluationException: TEIID30312 Unable to evaluate right expression of a."value" = modelB.testBool()
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:290) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluateTVL(Evaluator.java:215) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:204) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.SelectNode.nextBatchDirect(SelectNode.java:110) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:278) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.LimitNode.nextBatchDirect(LimitNode.java:101) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:278) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.ProjectNode.nextBatchDirect(ProjectNode.java:146) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:278) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.RelationalPlan.nextBatch(RelationalPlan.java:149) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:149) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:112) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:157) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:139) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.RequestWorkItem.processMore(RequestWorkItem.java:435) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:320) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:51) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:248) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:269) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.ThreadReuseExecutor$RunnableWrapper.run(ThreadReuseExecutor.java:119) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.ThreadReuseExecutor$3.run(ThreadReuseExecutor.java:214) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25]
>	at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25]
>Caused by: org.teiid.api.exception.query.ExpressionEvaluationException: TEIID30328 Unable to evaluate modelB.testBool(): TEIID30341 Function modelB.testBool is marked in the function metadata as a function that must be evaluated at the source.
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:608) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:288) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	... 23 more
>Caused by: org.teiid.api.exception.query.FunctionExecutionException: TEIID30341 Function modelB.testBool is marked in the function metadata as a function that must be evaluated at the source.
>	at org.teiid.query.function.FunctionDescriptor.checkNotPushdown(FunctionDescriptor.java:195) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:1163) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.internalEvaluate(Evaluator.java:636) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:606) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	... 24 more
>
>15:12:37,300 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue2) Removing tuplesource for the request zIdv4tzs7Isu.0
>15:12:37,300 DEBUG [org.teiid.BUFFER_MGR] (Worker0_QueryProcessorQueue2) Removing TupleBuffer: 0
>15:12:37,301 DEBUG [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue2) Sending error to client zIdv4tzs7Isu.0: org.teiid.api.exception.query.ExpressionEvaluationException: TEIID30312 Unable to evaluate right expression of a."value" = modelB.testBool()
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:290) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluateTVL(Evaluator.java:215) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:204) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.SelectNode.nextBatchDirect(SelectNode.java:110) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:278) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.LimitNode.nextBatchDirect(LimitNode.java:101) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:278) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.ProjectNode.nextBatchDirect(ProjectNode.java:146) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:278) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.relational.RelationalPlan.nextBatch(RelationalPlan.java:149) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:149) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:112) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:157) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:139) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.RequestWorkItem.processMore(RequestWorkItem.java:435) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:320) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:51) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:248) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:269) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.ThreadReuseExecutor$RunnableWrapper.run(ThreadReuseExecutor.java:119) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.ThreadReuseExecutor$3.run(ThreadReuseExecutor.java:214) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25]
>	at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25]
>Caused by: org.teiid.api.exception.query.ExpressionEvaluationException: TEIID30328 Unable to evaluate modelB.testBool(): TEIID30341 Function modelB.testBool is marked in the function metadata as a function that must be evaluated at the source.
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:608) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:288) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	... 23 more
>Caused by: org.teiid.api.exception.query.FunctionExecutionException: TEIID30341 Function modelB.testBool is marked in the function metadata as a function that must be evaluated at the source.
>	at org.teiid.query.function.FunctionDescriptor.checkNotPushdown(FunctionDescriptor.java:195) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:1163) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.internalEvaluate(Evaluator.java:636) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:606) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	... 24 more
>
>15:12:37,306 DEBUG [org.teiid.TRANSPORT] (Worker0_QueryProcessorQueue2) send message: MessageHolder: key=103 contents=ResultsMessage rowCount=0 finalRow=-1

Actions: View

Attachments on bug 1078277: 876364 | 876366