Attachment 902527 Details for Bug 1105121 – Server log with the exception

Server log with the exception

server.log (text/x-log), 39.04 KB, created by Filip Elias on 2014-06-05 12:37:06 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Filip Elias

Created: 2014-06-05 12:37:06 UTC

Size: 39.04 KB

patch

obsolete

>14:09:35,742 DEBUG [org.teiid.TRANSPORT] (Worker6_QueryProcessorQueue21) send message: MessageHolder: key=1282 contents=ResultsMessage rowCount=0 finalRow=-1
>14:09:36,160 DEBUG [org.teiid.TRANSPORT] (New I/O worker #1) processing message:MessageHolder: key=1283 contents=Invoke interface org.teiid.client.DQP.executeRequest
>14:09:36,161 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #1) 	START USER COMMAND:	startTime=2014-06-05 14:09:36.161	requestID=Ef2Z7E0246by.5	txID=null	sessionID=Ef2Z7E0246by	applicationName=JDBC	principal=user@teiid-security	vdbName=mongo	vdbVersion=1	sql=SELECT BQT1.SmallA.IntKey FROM BQT1.SmallA, BQT1.SmallB WHERE BQT1.SmallA.IntKey= lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) ORDER BY IntKey
>14:09:36,162 DEBUG [org.teiid.PROCESSOR] (Worker6_QueryProcessorQueue22) Request Thread Ef2Z7E0246by.5 with state NEW
>14:09:36,163 DEBUG [org.teiid.PROCESSOR] (Worker6_QueryProcessorQueue22) Ef2Z7E0246by.5 Non-cachable command.
>14:09:36,163 DEBUG [org.teiid.PROCESSOR] (Worker6_QueryProcessorQueue22) Ef2Z7E0246by.5 executing  SELECT BQT1.SmallA.IntKey FROM BQT1.SmallA, BQT1.SmallB WHERE BQT1.SmallA.IntKey= lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) ORDER BY IntKey
>14:09:36,185 INFO  [org.teiid.PLANNER] (Worker6_QueryProcessorQueue22) 
>============================================================================
>USER COMMAND:
>SELECT BQT1.SmallA.IntKey FROM BQT1.SmallA, BQT1.SmallB WHERE BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) ORDER BY BQT1.SmallA.IntKey
>
>----------------------------------------------------------------------------
>OPTIMIZE: 
>SELECT BQT1.SmallA.IntKey FROM BQT1.SmallA, BQT1.SmallB WHERE BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) ORDER BY BQT1.SmallA.IntKey
>
>----------------------------------------------------------------------------
>GENERATE CANONICAL: 
>SELECT BQT1.SmallA.IntKey FROM BQT1.SmallA, BQT1.SmallB WHERE BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) ORDER BY BQT1.SmallA.IntKey
>
>CANONICAL PLAN: 
>Sort(groups=[BQT1.SmallA], props={SORT_ORDER=ORDER BY BQT1.SmallA.IntKey})
>  Project(groups=[BQT1.SmallA], props={PROJECT_COLS=[BQT1.SmallA.IntKey]})
>    Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey)})
>      Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, JOIN_CRITERIA=[]})
>        Source(groups=[BQT1.SmallA])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING PlaceAccess
>
>AFTER: 
>Sort(groups=[BQT1.SmallA], props={SORT_ORDER=ORDER BY BQT1.SmallA.IntKey})
>  Project(groups=[BQT1.SmallA], props={PROJECT_COLS=[BQT1.SmallA.IntKey]})
>    Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey)})
>      Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, JOIN_CRITERIA=[]})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000})
>          Source(groups=[BQT1.SmallA])
>        Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000})
>          Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING PushSelectCriteria
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA], props={PROJECT_COLS=[BQT1.SmallA.IntKey]})
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, JOIN_CRITERIA=[]})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey)})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000})
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING PushNonJoinCriteria
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, JOIN_CRITERIA=[]})
>      Select(groups=[BQT1.SmallA])
>        Access(groups=[BQT1.SmallA])
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING CleanCriteria
>
>AFTER: 
>Sort(groups=[BQT1.SmallA], props={SORT_ORDER=ORDER BY BQT1.SmallA.IntKey, OUTPUT_COLS=null})
>  Project(groups=[BQT1.SmallA], props={PROJECT_COLS=[BQT1.SmallA.IntKey], OUTPUT_COLS=null})
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, JOIN_CRITERIA=[], OUTPUT_COLS=null})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=null})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null})
>          Source(groups=[BQT1.SmallA], props={OUTPUT_COLS=null})
>      Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null})
>        Source(groups=[BQT1.SmallB], props={OUTPUT_COLS=null})
>
>
>============================================================================
>EXECUTING RaiseAccess
>LOW Relational Planner Function metadata indicates it cannot be pusheddown. BQT1 - lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) was not pushed
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, JOIN_CRITERIA=[], OUTPUT_COLS=null})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=null})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null})
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null})
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING CopyCriteria
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, JOIN_CRITERIA=[], OUTPUT_COLS=null, IS_COPIED=true})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=null, IS_COPIED=true})
>        Access(groups=[BQT1.SmallA])
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING CleanCriteria
>
>AFTER: 
>Sort(groups=[BQT1.SmallA], props={SORT_ORDER=ORDER BY BQT1.SmallA.IntKey, OUTPUT_COLS=null})
>  Project(groups=[BQT1.SmallA], props={PROJECT_COLS=[BQT1.SmallA.IntKey], OUTPUT_COLS=null})
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, JOIN_CRITERIA=[], OUTPUT_COLS=null, IS_COPIED=true})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=null, IS_COPIED=true})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null})
>          Source(groups=[BQT1.SmallA], props={OUTPUT_COLS=null})
>      Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null})
>        Source(groups=[BQT1.SmallB], props={OUTPUT_COLS=null})
>
>
>============================================================================
>EXECUTING PlanJoins
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA], props={PROJECT_COLS=[BQT1.SmallA.IntKey], OUTPUT_COLS=null})
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=null, IS_COPIED=true, EST_CARDINALITY=-1.0})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null, EST_CARDINALITY=-1.0})
>          Source(groups=[BQT1.SmallA], props={OUTPUT_COLS=null, EST_COL_STATS={BQT1.smalla.INTNUM=[-1.0, -1.0], BQT1.smalla.BIGDECIMALVALUE=[-1.0, -1.0], BQT1.smalla.BIGINTEGERVALUE=[-1.0, -1.0], BQT1.smalla.BOOLEANVALUE=[-1.0, -1.0], ...}, EST_CARDINALITY=-1.0})
>      Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null, EST_CARDINALITY=-1.0})
>        Source(groups=[BQT1.SmallB], props={OUTPUT_COLS=null, EST_COL_STATS={BQT1.smallb.INTNUM=[-1.0, -1.0], BQT1.smallb.BIGDECIMALVALUE=[-1.0, -1.0], BQT1.smallb.BIGINTEGERVALUE=[-1.0, -1.0], BQT1.smallb.BOOLEANVALUE=[-1.0, -1.0], ...}, EST_CARDINALITY=-1.0})
>
>
>============================================================================
>EXECUTING PushSelectCriteria
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB])
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=null, IS_COPIED=true, EST_CARDINALITY=-1.0})
>        Access(groups=[BQT1.SmallA])
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING RaiseAccess
>LOW Relational Planner Function metadata indicates it cannot be pusheddown. BQT1 - lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) was not pushed
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=null, IS_COPIED=true, EST_CARDINALITY=-1.0})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null, EST_CARDINALITY=-1.0})
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null, EST_CARDINALITY=-1.0})
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING ChooseJoinStrategy
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP})
>      Select(groups=[BQT1.SmallA])
>        Access(groups=[BQT1.SmallA])
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING ChooseDependent
>LOW Relational Planner parent join is CROSS or FULL OUTER - Rejecting dependent join Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null, EST_CARDINALITY=-1.0}
>LOW Relational Planner parent join is CROSS or FULL OUTER - Rejecting dependent join Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null, EST_CARDINALITY=-1.0}
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP})
>      Select(groups=[BQT1.SmallA])
>        Access(groups=[BQT1.SmallA])
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING AssignOutputElements
>
>AFTER: 
>Sort(groups=[BQT1.SmallA], props={SORT_ORDER=ORDER BY BQT1.SmallA.IntKey, OUTPUT_COLS=[BQT1.SmallA.IntKey]})
>  Project(groups=[BQT1.SmallA], props={PROJECT_COLS=[BQT1.SmallA.IntKey], OUTPUT_COLS=[BQT1.SmallA.IntKey]})
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, OUTPUT_COLS=[BQT1.SmallA.IntKey]})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=[BQT1.SmallA.IntKey], IS_COPIED=true, EST_CARDINALITY=-1.0})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey], EST_CARDINALITY=-1.0})
>          Source(groups=[BQT1.SmallA], props={OUTPUT_COLS=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey], EST_COL_STATS={BQT1.smalla.INTNUM=[-1.0, -1.0], BQT1.smalla.BIGDECIMALVALUE=[-1.0, -1.0], BQT1.smalla.BIGINTEGERVALUE=[-1.0, -1.0], BQT1.smalla.BOOLEANVALUE=[-1.0, -1.0], ...}, EST_CARDINALITY=-1.0})
>      Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=[], EST_CARDINALITY=-1.0})
>        Source(groups=[BQT1.SmallB], props={OUTPUT_COLS=[], EST_COL_STATS={BQT1.smallb.INTNUM=[-1.0, -1.0], BQT1.smallb.BIGDECIMALVALUE=[-1.0, -1.0], BQT1.smallb.BIGINTEGERVALUE=[-1.0, -1.0], BQT1.smallb.BOOLEANVALUE=[-1.0, -1.0], ...}, EST_CARDINALITY=-1.0})
>
>
>============================================================================
>EXECUTING CalculateCost
>
>AFTER: 
>Sort(groups=[BQT1.SmallA], props={SORT_ORDER=ORDER BY BQT1.SmallA.IntKey, OUTPUT_COLS=[BQT1.SmallA.IntKey], EST_CARDINALITY=-1.0})
>  Project(groups=[BQT1.SmallA], props={PROJECT_COLS=[BQT1.SmallA.IntKey], OUTPUT_COLS=[BQT1.SmallA.IntKey], EST_CARDINALITY=-1.0})
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, OUTPUT_COLS=[BQT1.SmallA.IntKey], EST_CARDINALITY=-1.0})
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=[BQT1.SmallA.IntKey], IS_COPIED=true, EST_CARDINALITY=-1.0})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey], EST_CARDINALITY=-1.0})
>          Source(groups=[BQT1.SmallA], props={OUTPUT_COLS=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey], EST_COL_STATS={BQT1.smalla.INTNUM=[-1.0, -1.0], BQT1.smalla.BIGDECIMALVALUE=[-1.0, -1.0], BQT1.smalla.BIGINTEGERVALUE=[-1.0, -1.0], BQT1.smalla.BOOLEANVALUE=[-1.0, -1.0], ...}, EST_CARDINALITY=-1.0})
>      Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=[], EST_CARDINALITY=-1.0})
>        Source(groups=[BQT1.SmallB], props={OUTPUT_COLS=[], EST_COL_STATS={BQT1.smallb.INTNUM=[-1.0, -1.0], BQT1.smallb.BIGDECIMALVALUE=[-1.0, -1.0], BQT1.smallb.BIGINTEGERVALUE=[-1.0, -1.0], BQT1.smallb.BOOLEANVALUE=[-1.0, -1.0], ...}, EST_CARDINALITY=-1.0})
>
>
>============================================================================
>EXECUTING ImplementJoinStrategy
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, OUTPUT_COLS=[BQT1.SmallA.IntKey], EST_CARDINALITY=-1.0})
>      Select(groups=[BQT1.SmallA])
>        Access(groups=[BQT1.SmallA])
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING MergeCriteria
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB])
>      Select(groups=[BQT1.SmallA], props={SELECT_CRITERIA=BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey), OUTPUT_COLS=[BQT1.SmallA.IntKey], IS_COPIED=true, EST_CARDINALITY=-1.0})
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey], EST_CARDINALITY=-1.0})
>          Source(groups=[BQT1.SmallA], props={OUTPUT_COLS=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey], EST_COL_STATS={BQT1.smalla.INTNUM=[-1.0, -1.0], BQT1.smalla.BIGDECIMALVALUE=[-1.0, -1.0], BQT1.smalla.BIGINTEGERVALUE=[-1.0, -1.0], BQT1.smalla.BOOLEANVALUE=[-1.0, -1.0], ...}, EST_CARDINALITY=-1.0})
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING PlanSorts
>
>AFTER: 
>Sort(groups=[BQT1.SmallA], props={SORT_ORDER=ORDER BY BQT1.SmallA.IntKey, OUTPUT_COLS=[BQT1.SmallA.IntKey], EST_CARDINALITY=-1.0})
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB], props={JOIN_TYPE=CROSS JOIN, JOIN_STRATEGY=NESTED_LOOP, OUTPUT_COLS=[BQT1.SmallA.IntKey], EST_CARDINALITY=-1.0})
>      Select(groups=[BQT1.SmallA])
>        Access(groups=[BQT1.SmallA])
>          Source(groups=[BQT1.SmallA])
>      Access(groups=[BQT1.SmallB])
>        Source(groups=[BQT1.SmallB])
>
>
>============================================================================
>EXECUTING CollapseSource
>
>AFTER: 
>Sort(groups=[BQT1.SmallA])
>  Project(groups=[BQT1.SmallA])
>    Join(groups=[BQT1.SmallA, BQT1.SmallB])
>      Select(groups=[BQT1.SmallA])
>        Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey], EST_CARDINALITY=-1.0, ATOMIC_REQUEST=SELECT BQT1.SmallA.IntKey, BQT1.SmallA.StringKey FROM BQT1.SmallA})
>      Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=[], EST_CARDINALITY=-1.0, ATOMIC_REQUEST=SELECT 1 FROM BQT1.SmallB})
>
>
>============================================================================
>CONVERTING PLAN TREE TO PROCESS TREE
>
>PROCESS PLAN = 
>SortNode(0) output=[BQT1.SmallA.IntKey] [SORT] [BQT1.SmallA.IntKey]
>  ProjectNode(1) output=[BQT1.SmallA.IntKey] [BQT1.SmallA.IntKey]
>    JoinNode(2) [NESTED LOOP JOIN] [CROSS JOIN] output=[BQT1.SmallA.IntKey]
>      SelectNode(3) output=[BQT1.SmallA.IntKey] BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey)
>        AccessNode(4) output=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey] SELECT BQT1.smalla.INTKEY, BQT1.smalla.STRINGKEY FROM BQT1.smalla
>      AccessNode(5) output=[] SELECT 1 FROM BQT1.smallb
>
>============================================================================
>
>----------------------------------------------------------------------------
>OPTIMIZATION COMPLETE:
>PROCESSOR PLAN:
>SortNode(0) output=[BQT1.SmallA.IntKey] [SORT] [BQT1.SmallA.IntKey]
>  ProjectNode(1) output=[BQT1.SmallA.IntKey] [BQT1.SmallA.IntKey]
>    JoinNode(2) [NESTED LOOP JOIN] [CROSS JOIN] output=[BQT1.SmallA.IntKey]
>      SelectNode(3) output=[BQT1.SmallA.IntKey] BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey)
>        AccessNode(4) output=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey] SELECT BQT1.smalla.INTKEY, BQT1.smalla.STRINGKEY FROM BQT1.smalla
>      AccessNode(5) output=[] SELECT 1 FROM BQT1.smallb
>
>============================================================================
>
>14:09:36,204 DEBUG [org.teiid.PLANNER] (Worker6_QueryProcessorQueue22) [QueryAnnotation<Relational Planner, LOW,Function metadata indicates it cannot be pusheddown. BQT1,lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) was not pushed>, QueryAnnotation<Relational Planner, LOW,Function metadata indicates it cannot be pusheddown. BQT1,lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey) was not pushed>, QueryAnnotation<Relational Planner, LOW,parent join is CROSS or FULL OUTER,Rejecting dependent join Access(groups=[BQT1.SmallA], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null, EST_CARDINALITY=-1.0}>, QueryAnnotation<Relational Planner, LOW,parent join is CROSS or FULL OUTER,Rejecting dependent join Access(groups=[BQT1.SmallB], props={MODEL_ID=Schema name=BQT1, nameInSource=null, uuid=tid:9607f5eba9f9-001f3aec-00000000, OUTPUT_COLS=null, EST_CARDINALITY=-1.0}>]
>14:09:36,205 DEBUG [org.teiid.PROCESSOR] (Worker6_QueryProcessorQueue22) ProcessTree for Ef2Z7E0246by.5 SortNode(0) output=[BQT1.SmallA.IntKey] [SORT] [BQT1.SmallA.IntKey]
>  ProjectNode(1) output=[BQT1.SmallA.IntKey] [BQT1.SmallA.IntKey]
>    JoinNode(2) [NESTED LOOP JOIN] [CROSS JOIN] output=[BQT1.SmallA.IntKey]
>      SelectNode(3) output=[BQT1.SmallA.IntKey] BQT1.SmallA.IntKey = lookup('BQT1.SmallB', 'IntKey', 'StringKey', BQT1.SmallA.StringKey)
>        AccessNode(4) output=[BQT1.SmallA.IntKey, BQT1.SmallA.StringKey] SELECT BQT1.smalla.INTKEY, BQT1.smalla.STRINGKEY FROM BQT1.smalla
>      AccessNode(5) output=[] SELECT 1 FROM BQT1.smallb
>
>14:09:36,205 DEBUG [org.teiid.TXN_LOG] (Worker6_QueryProcessorQueue22) before getOrCreateTransactionContext:org.teiid.dqp.internal.process.TransactionServerImpl@4ec8d4aa(Ef2Z7E0246by)
>14:09:36,206 DEBUG [org.teiid.TXN_LOG] (Worker6_QueryProcessorQueue22) after getOrCreateTransactionContext : Ef2Z7E0246by NONE ID:NONE
>14:09:36,206 DEBUG [org.teiid.BUFFER_MGR] (Worker6_QueryProcessorQueue22) Creating TupleBuffer: 18 [BQT1.SmallA.IntKey] [class java.lang.Integer] batch size 2048 of type FINAL
>14:09:36,207 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue22) Ef2Z7E0246by.5.4.12 Create State
>14:09:36,207 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue22) Ef2Z7E0246by.5.5.13 Create State
>14:09:36,207 DEBUG [org.teiid.PROCESSOR] (Worker7_QueryProcessorQueue23) Running task for parent thread Worker6_QueryProcessorQueue22
>14:09:36,208 DEBUG [org.teiid.BUFFER_MGR] (Worker6_QueryProcessorQueue22) Creating TupleBuffer: 19 [BQT1.SmallA.IntKey] [class java.lang.Integer] batch size 2048 of type PROCESSOR
>14:09:36,208 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue23) Ef2Z7E0246by.5.4.12 Processing NEW request: SELECT BQT1.smalla.INTKEY, BQT1.smalla.STRINGKEY FROM BQT1.smalla
>14:09:36,208 DEBUG [org.teiid.PROCESSOR] (Worker8_QueryProcessorQueue24) Running task for parent thread Worker6_QueryProcessorQueue22
>14:09:36,208 DEBUG [org.teiid.PROCESSOR] (Worker6_QueryProcessorQueue22) Created intermediate sort buffer 19
>14:09:36,209 DEBUG [org.teiid.CONNECTOR] (Worker8_QueryProcessorQueue24) Ef2Z7E0246by.5.5.13 Processing NEW request: SELECT 1 FROM BQT1.smallb
>14:09:36,209 DEBUG [org.teiid.BUFFER_MGR] (Worker6_QueryProcessorQueue22) Ef2Z7E0246by.5.4.12 Blocking on source query Ef2Z7E0246by.5.4.12
>14:09:36,209 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (Worker7_QueryProcessorQueue23) teiid-mongodb-ds: getConnection(null, null) [0/20]
>14:09:36,209 DEBUG [org.teiid.BUFFER_MGR] (Worker6_QueryProcessorQueue22) Ef2Z7E0246by.5 Blocking on source request(s).
>14:09:36,209 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (Worker8_QueryProcessorQueue24) teiid-mongodb-ds: getConnection(null, null) [0/20]
>14:09:36,209 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue23) Ef2Z7E0246by.5.4.12 Obtained execution
>14:09:36,209 DEBUG [org.teiid.BUFFER_MGR] (Worker6_QueryProcessorQueue22) Creating TupleBuffer: 20 [] [] batch size 2048 of type PROCESSOR
>14:09:36,210 DEBUG [org.teiid.CONNECTOR] (Worker8_QueryProcessorQueue24) Ef2Z7E0246by.5.5.13 Obtained execution
>14:09:36,210 DEBUG [org.teiid.BUFFER_MGR] (Worker6_QueryProcessorQueue22) Ef2Z7E0246by.5.5.13 Blocking on source query Ef2Z7E0246by.5.5.13
>14:09:36,210 DEBUG [org.teiid.COMMAND_LOG] (Worker7_QueryProcessorQueue23) 	START DATA SRC COMMAND:	startTime=2014-06-05 14:09:36.21	requestID=Ef2Z7E0246by.5	sourceCommandID=4	executionID=12	txID=null	modelName=BQT1	translatorName=mongodb	sessionID=Ef2Z7E0246by	principal=user@teiid-security	sql=SELECT BQT1.smalla.INTKEY, BQT1.smalla.STRINGKEY FROM BQT1.smalla
>14:09:36,210 DEBUG [org.teiid.COMMAND_LOG] (Worker8_QueryProcessorQueue24) 	START DATA SRC COMMAND:	startTime=2014-06-05 14:09:36.21	requestID=Ef2Z7E0246by.5	sourceCommandID=5	executionID=13	txID=null	modelName=BQT1	translatorName=mongodb	sessionID=Ef2Z7E0246by	principal=user@teiid-security	sql=SELECT 1 FROM BQT1.smallb
>14:09:36,210 INFO  [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue23) SELECT smalla.INTKEY, smalla.STRINGKEY FROM smalla
>14:09:36,210 INFO  [org.teiid.CONNECTOR] (Worker8_QueryProcessorQueue24) SELECT 1 FROM smallb
>14:09:36,211 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue23) $project:{ "_m0" : "$INTKEY" , "_m1" : "$STRINGKEY"}
>14:09:36,211 DEBUG [org.teiid.CONNECTOR] (Worker8_QueryProcessorQueue24) $project:{ }
>14:09:36,213 DEBUG [org.teiid.COMMAND_LOG] (Worker8_QueryProcessorQueue24) 	ERROR SRC COMMAND:	endTime=2014-06-05 14:09:36.213	requestID=Ef2Z7E0246by.5	sourceCommandID=5	executionID=13	txID=null	modelName=BQT1	translatorName=mongodb	sessionID=Ef2Z7E0246by	principal=user@teiid-security	finalRowCount=null
>14:09:36,217 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue23) Ef2Z7E0246by.5.4.12 Executed command
>14:09:36,218 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue23) Ef2Z7E0246by.5.4.12 Processing MORE request
>14:09:36,218 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue23) Ef2Z7E0246by.5.4.12 Getting results from connector
>14:09:36,213 ERROR [org.teiid.CONNECTOR] (Worker8_QueryProcessorQueue24) Connector worker process failed for atomic-request=Ef2Z7E0246by.5.5.13: com.mongodb.CommandFailureException: { "serverUsed" : "localhost/127.0.0.1:27017" , "errmsg" : "exception: $projection requires at least one output field" , "code" : 16403 , "ok" : 0.0}
>	at com.mongodb.CommandResult.getException(CommandResult.java:71)
>	at com.mongodb.CommandResult.throwOnError(CommandResult.java:110)
>	at com.mongodb.DBCollection.aggregate(DBCollection.java:1308)
>	at org.teiid.translator.mongodb.MongoDBQueryExecution.execute(MongoDBQueryExecution.java:97)
>	at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:312) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:301) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource$1.call(DataTierTupleSource.java:113) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource$1.call(DataTierTupleSource.java:110) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25]
>	at org.teiid.dqp.internal.process.FutureWork.run(FutureWork.java:58) [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]
>
>14:09:36,210 DEBUG [org.teiid.BUFFER_MGR] (Worker6_QueryProcessorQueue22) Ef2Z7E0246by.5 Blocking on source request(s).
>14:09:36,223 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue23) Ef2Z7E0246by.5.4.12 Obtained last batch, total row count: 50
>14:09:36,224 DEBUG [org.teiid.PROCESSOR] (Worker6_QueryProcessorQueue22) Request Thread Ef2Z7E0246by.5 - processor blocked
>14:09:36,224 DEBUG [org.teiid.PROCESSOR] (Worker7_QueryProcessorQueue25) Request Thread Ef2Z7E0246by.5 with state PROCESSING
>14:09:36,225 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.4.12 Remove State
>14:09:36,225 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.4.12 Processing Close : SELECT BQT1.smalla.INTKEY, BQT1.smalla.STRINGKEY FROM BQT1.smalla
>14:09:36,225 DEBUG [org.teiid.COMMAND_LOG] (Worker7_QueryProcessorQueue25) 	END SRC COMMAND:	endTime=2014-06-05 14:09:36.225	requestID=Ef2Z7E0246by.5	sourceCommandID=4	executionID=12	txID=null	modelName=BQT1	translatorName=mongodb	sessionID=Ef2Z7E0246by	principal=user@teiid-security	finalRowCount=50
>14:09:36,225 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.4.12 Closed execution
>14:09:36,226 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (Worker7_QueryProcessorQueue25) teiid-mongodb-ds: returnConnection(2d6688a, false) [2/20]
>14:09:36,226 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.4.12 Closed connection
>14:09:36,226 DEBUG [org.teiid.PROCESSOR.MATVIEWS] (Worker7_QueryProcessorQueue25) org.teiid.query.tempdata.GlobalTableStoreImpl$MatTableInfo@9a21af5 setting matState to LOADING null 1401970176226 old values FAILED_LOAD false
>14:09:36,226 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker7_QueryProcessorQueue25) TEIID30013 Loading materialized view table #CODE_BQT1.SMALLB.STRINGKEY.INTKEY
>14:09:36,227 DEBUG [org.teiid.BUFFER_MGR] (Worker7_QueryProcessorQueue25) Creating STree: 21
>14:09:36,227 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.6.14 Create State
>14:09:36,227 DEBUG [org.teiid.BUFFER_MGR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.6.14 Blocking on source query Ef2Z7E0246by.5.6.14
>14:09:36,227 DEBUG [org.teiid.PROCESSOR] (Worker6_QueryProcessorQueue26) Running task for parent thread Worker7_QueryProcessorQueue25
>14:09:36,228 DEBUG [org.teiid.BUFFER_MGR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5 Blocking on source request(s).
>14:09:36,228 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue26) Ef2Z7E0246by.5.6.14 Processing NEW request: SELECT BQT1.smallb.STRINGKEY, BQT1.smallb.INTKEY FROM BQT1.smallb
>14:09:36,228 DEBUG [org.teiid.BUFFER_MGR] (Worker7_QueryProcessorQueue25) Removing TupleBuffer: 19
>14:09:36,228 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (Worker6_QueryProcessorQueue26) teiid-mongodb-ds: getConnection(null, null) [1/20]
>14:09:36,228 DEBUG [org.teiid.COMMAND_LOG] (Worker7_QueryProcessorQueue25) 	ERROR USER COMMAND:	endTime=2014-06-05 14:09:36.228	requestID=Ef2Z7E0246by.5	txID=null	sessionID=Ef2Z7E0246by	principal=user@teiid-security	vdbName=mongo	vdbVersion=1	finalRowCount=null
>14:09:36,228 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue26) Ef2Z7E0246by.5.6.14 Obtained execution
>14:09:36,230 DEBUG [org.teiid.COMMAND_LOG] (Worker6_QueryProcessorQueue26) 	START DATA SRC COMMAND:	startTime=2014-06-05 14:09:36.23	requestID=Ef2Z7E0246by.5	sourceCommandID=6	executionID=14	txID=null	modelName=BQT1	translatorName=mongodb	sessionID=Ef2Z7E0246by	principal=user@teiid-security	sql=SELECT BQT1.smallb.STRINGKEY, BQT1.smallb.INTKEY FROM BQT1.smallb
>14:09:36,230 INFO  [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue26) SELECT smallb.STRINGKEY, smallb.INTKEY FROM smallb
>14:09:36,231 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue26) $project:{ "_m0" : "$STRINGKEY" , "_m1" : "$INTKEY"}
>14:09:36,228 ERROR [org.teiid.PROCESSOR] (Worker7_QueryProcessorQueue25) TEIID30019 Unexpected exception for request Ef2Z7E0246by.5: com.mongodb.CommandFailureException: { "serverUsed" : "localhost/127.0.0.1:27017" , "errmsg" : "exception: $projection requires at least one output field" , "code" : 16403 , "ok" : 0.0}
>	at com.mongodb.CommandResult.getException(CommandResult.java:71)
>	at com.mongodb.CommandResult.throwOnError(CommandResult.java:110)
>	at com.mongodb.DBCollection.aggregate(DBCollection.java:1308)
>	at org.teiid.translator.mongodb.MongoDBQueryExecution.execute(MongoDBQueryExecution.java:97)
>	at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:312) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:301) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource$1.call(DataTierTupleSource.java:113) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource$1.call(DataTierTupleSource.java:110) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25]
>	at org.teiid.dqp.internal.process.FutureWork.run(FutureWork.java:58) [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]
>
>14:09:36,232 DEBUG [org.teiid.PROCESSOR] (Worker7_QueryProcessorQueue25) QueryProcessor: closing processor
>14:09:36,233 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.5.13 Remove State
>14:09:36,233 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.5.13 Processing Close : SELECT 1 FROM BQT1.smallb
>14:09:36,233 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.5.13 Closed execution
>14:09:36,233 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (Worker7_QueryProcessorQueue25) teiid-mongodb-ds: returnConnection(5b3d0412, false) [2/20]
>14:09:36,233 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.5.13 Closed connection
>14:09:36,233 DEBUG [org.teiid.PROCESSOR] (Worker7_QueryProcessorQueue25) Removing tuplesource for the request Ef2Z7E0246by.5
>14:09:36,233 DEBUG [org.teiid.BUFFER_MGR] (Worker7_QueryProcessorQueue25) Removing TupleBuffer: 18
>14:09:36,234 DEBUG [org.teiid.PROCESSOR.MATVIEWS] (Worker7_QueryProcessorQueue25) org.teiid.query.tempdata.GlobalTableStoreImpl$MatTableInfo@9a21af5 setting matState to FAILED_LOAD null 1401970176234 old values LOADING false
>14:09:36,234 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue26) Ef2Z7E0246by.5.6.14 Executed command
>14:09:36,234 DEBUG [org.teiid.PROCESSOR] (Worker7_QueryProcessorQueue25) QueryProcessor: closing processor
>14:09:36,234 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue26) Ef2Z7E0246by.5.6.14 Processing MORE request
>14:09:36,234 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue26) Ef2Z7E0246by.5.6.14 Getting results from connector
>14:09:36,234 DEBUG [org.teiid.CONNECTOR] (Worker6_QueryProcessorQueue26) Ef2Z7E0246by.5.6.14 Obtained last batch, total row count: 50
>14:09:36,234 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.6.14 Remove State
>14:09:36,234 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.6.14 Processing Close : SELECT BQT1.smallb.STRINGKEY, BQT1.smallb.INTKEY FROM BQT1.smallb
>14:09:36,234 DEBUG [org.teiid.COMMAND_LOG] (Worker7_QueryProcessorQueue25) 	END SRC COMMAND:	endTime=2014-06-05 14:09:36.234	requestID=Ef2Z7E0246by.5	sourceCommandID=6	executionID=14	txID=null	modelName=BQT1	translatorName=mongodb	sessionID=Ef2Z7E0246by	principal=user@teiid-security	finalRowCount=50
>14:09:36,235 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.6.14 Closed execution
>14:09:36,235 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (Worker7_QueryProcessorQueue25) teiid-mongodb-ds: returnConnection(2d6688a, false) [1/20]
>14:09:36,235 DEBUG [org.teiid.CONNECTOR] (Worker7_QueryProcessorQueue25) Ef2Z7E0246by.5.6.14 Closed connection
>14:09:36,235 DEBUG [org.teiid.PROCESSOR] (Worker7_QueryProcessorQueue25) Sending error to client Ef2Z7E0246by.5: com.mongodb.CommandFailureException: { "serverUsed" : "localhost/127.0.0.1:27017" , "errmsg" : "exception: $projection requires at least one output field" , "code" : 16403 , "ok" : 0.0}
>	at com.mongodb.CommandResult.getException(CommandResult.java:71)
>	at com.mongodb.CommandResult.throwOnError(CommandResult.java:110)
>	at com.mongodb.DBCollection.aggregate(DBCollection.java:1308)
>	at org.teiid.translator.mongodb.MongoDBQueryExecution.execute(MongoDBQueryExecution.java:97)
>	at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:312) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:301) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource$1.call(DataTierTupleSource.java:113) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at org.teiid.dqp.internal.process.DataTierTupleSource$1.call(DataTierTupleSource.java:110) [teiid-engine-8.4.1-redhat-9.jar:8.4.1-redhat-9]
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25]
>	at org.teiid.dqp.internal.process.FutureWork.run(FutureWork.java:58) [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]
>
>14:09:36,236 DEBUG [org.teiid.TRANSPORT] (Worker7_QueryProcessorQueue25) send message: MessageHolder: key=1283 contents=ResultsMessage rowCount=0 finalRow=-1

Actions: View

Attachments on bug 1105121: 902527