[romagnolli]

When I run a query against the VDB (select * FROM  "MS_SQL_AdventureWorks2016_view"."Employee") using SQuirreL SQL Client , who is using JDBC connection I see the following logs - the query is processed successfully:

10:25:24,103 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #6) QmtNpM2Hj8a7     START USER COMMAND:    startTime=2017-01-13 10:25:24.103    requestID=QmtNpM2Hj8a7.10    txID=null    sessionID=QmtNpM2Hj8a7    applicationName=JDBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select * FROM  "MS_SQL_AdventureWorks2016_view"."Employee"

10:25:24,108 DEBUG [org.teiid.COMMAND_LOG] (Worker9_QueryProcessorQueue76) QmtNpM2Hj8a7     START DATA SRC COMMAND:    startTime=2017-01-13 10:25:24.108    requestID=QmtNpM2Hj8a7.10    sourceCommandID=0    executionID=4    txID=null    modelName=MS_SQL_AdventureWorks2016_source    translatorName=sqlserver    sessionID=QmtNpM2Hj8a7    principal=teiid@teiid-security    sql=SELECT g_0.BusinessEntityID, g_0.NationalIDNumber, g_0.LoginID, g_0.OrganizationLevel, g_0.JobTitle, g_0.BirthDate, g_0.MaritalStatus, g_0.Gender, g_0.HireDate, g_0.VacationHours, g_0.SickLeaveHours, g_0.rowguid, g_0.ModifiedDate FROM MS_SQL_AdventureWorks2016_source.Employee AS g_0

10:25:24,727 DEBUG [org.teiid.COMMAND_LOG] (Worker8_QueryProcessorQueue77) QmtNpM2Hj8a7     END SRC COMMAND:    endTime=2017-01-13 10:25:24.727    requestID=QmtNpM2Hj8a7.10    sourceCommandID=0    executionID=4    txID=null    modelName=MS_SQL_AdventureWorks2016_source    translatorName=sqlserver    sessionID=QmtNpM2Hj8a7    principal=teiid@teiid-security    finalRowCount=290

10:25:24,752 DEBUG [org.teiid.COMMAND_LOG] (Worker9_QueryProcessorQueue79) QmtNpM2Hj8a7     END USER COMMAND:    endTime=2017-01-13 10:25:24.752    requestID=QmtNpM2Hj8a7.10    txID=null    sessionID=QmtNpM2Hj8a7    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=290

When I try to create an ODBC query in Excel (trying to visualize data from "MS_SQL_AdventureWorks2016_view"."Shift" table), I see the following logs:

10:42:15,574 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #29) dZNjr5iVN/6O     START USER COMMAND:    startTime=2017-01-13 10:42:15.574    requestID=dZNjr5iVN/6O.0    txID=null    sessionID=dZNjr5iVN/6O    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select teiid_session_set('resolve_groupby_positional', true)

10:42:15,576 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #29) dZNjr5iVN/6O     END USER COMMAND:    endTime=2017-01-13 10:42:15.576    requestID=dZNjr5iVN/6O.0    txID=null    sessionID=dZNjr5iVN/6O    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,579 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #29) dZNjr5iVN/6O     START USER COMMAND:    startTime=2017-01-13 10:42:15.579    requestID=dZNjr5iVN/6O.1    txID=null    sessionID=dZNjr5iVN/6O    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select oid, typbasetype from pg_type where typname = 'lo'

10:42:15,586 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue80) dZNjr5iVN/6O     START USER COMMAND:    startTime=2017-01-13 10:42:15.585    requestID=dZNjr5iVN/6O.2    txID=null    sessionID=dZNjr5iVN/6O    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:15,591 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue80) dZNjr5iVN/6O     END USER COMMAND:    endTime=2017-01-13 10:42:15.591    requestID=dZNjr5iVN/6O.2    txID=null    sessionID=dZNjr5iVN/6O    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,591 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue80) dZNjr5iVN/6O     START USER COMMAND:    startTime=2017-01-13 10:42:15.591    requestID=dZNjr5iVN/6O.3    txID=null    sessionID=dZNjr5iVN/6O    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:15,593 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue80) dZNjr5iVN/6O     END USER COMMAND:    endTime=2017-01-13 10:42:15.593    requestID=dZNjr5iVN/6O.3    txID=null    sessionID=dZNjr5iVN/6O    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,594 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue80) dZNjr5iVN/6O     END USER COMMAND:    endTime=2017-01-13 10:42:15.594    requestID=dZNjr5iVN/6O.1    txID=null    sessionID=dZNjr5iVN/6O    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,598 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #29) dZNjr5iVN/6O     START USER COMMAND:    startTime=2017-01-13 10:42:15.598    requestID=dZNjr5iVN/6O.4    txID=null    sessionID=dZNjr5iVN/6O    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select 63

10:42:15,600 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue81) dZNjr5iVN/6O     END USER COMMAND:    endTime=2017-01-13 10:42:15.6    requestID=dZNjr5iVN/6O.4    txID=null    sessionID=dZNjr5iVN/6O    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,685 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #31) 3+P2rcM9AzsL     START USER COMMAND:    startTime=2017-01-13 10:42:15.685    requestID=3+P2rcM9AzsL.0    txID=null    sessionID=3+P2rcM9AzsL    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select teiid_session_set('resolve_groupby_positional', true)

10:42:15,686 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #31) 3+P2rcM9AzsL     END USER COMMAND:    endTime=2017-01-13 10:42:15.686    requestID=3+P2rcM9AzsL.0    txID=null    sessionID=3+P2rcM9AzsL    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,688 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #31) 3+P2rcM9AzsL     START USER COMMAND:    startTime=2017-01-13 10:42:15.688    requestID=3+P2rcM9AzsL.1    txID=null    sessionID=3+P2rcM9AzsL    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select oid, typbasetype from pg_type where typname = 'lo'

10:42:15,691 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue82) 3+P2rcM9AzsL     START USER COMMAND:    startTime=2017-01-13 10:42:15.691    requestID=3+P2rcM9AzsL.2    txID=null    sessionID=3+P2rcM9AzsL    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:15,692 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue82) 3+P2rcM9AzsL     END USER COMMAND:    endTime=2017-01-13 10:42:15.692    requestID=3+P2rcM9AzsL.2    txID=null    sessionID=3+P2rcM9AzsL    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,692 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue82) 3+P2rcM9AzsL     START USER COMMAND:    startTime=2017-01-13 10:42:15.692    requestID=3+P2rcM9AzsL.3    txID=null    sessionID=3+P2rcM9AzsL    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:15,693 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue82) 3+P2rcM9AzsL     END USER COMMAND:    endTime=2017-01-13 10:42:15.693    requestID=3+P2rcM9AzsL.3    txID=null    sessionID=3+P2rcM9AzsL    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,694 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue82) 3+P2rcM9AzsL     END USER COMMAND:    endTime=2017-01-13 10:42:15.694    requestID=3+P2rcM9AzsL.1    txID=null    sessionID=3+P2rcM9AzsL    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:15,697 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #31) 3+P2rcM9AzsL     START USER COMMAND:    startTime=2017-01-13 10:42:15.697    requestID=3+P2rcM9AzsL.4    txID=null    sessionID=3+P2rcM9AzsL    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select NULL, NULL, NULL

10:42:15,699 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue83) 3+P2rcM9AzsL     END USER COMMAND:    endTime=2017-01-13 10:42:15.699    requestID=3+P2rcM9AzsL.4    txID=null    sessionID=3+P2rcM9AzsL    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:18,784 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #2) fTv1FmO/PvoG     START USER COMMAND:    startTime=2017-01-13 10:42:18.784    requestID=fTv1FmO/PvoG.0    txID=null    sessionID=fTv1FmO/PvoG    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select teiid_session_set('resolve_groupby_positional', true)

10:42:18,785 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #2) fTv1FmO/PvoG     END USER COMMAND:    endTime=2017-01-13 10:42:18.785    requestID=fTv1FmO/PvoG.0    txID=null    sessionID=fTv1FmO/PvoG    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:18,787 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #2) fTv1FmO/PvoG     START USER COMMAND:    startTime=2017-01-13 10:42:18.786    requestID=fTv1FmO/PvoG.1    txID=null    sessionID=fTv1FmO/PvoG    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select oid, typbasetype from pg_type where typname = 'lo'

10:42:18,789 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue84) fTv1FmO/PvoG     START USER COMMAND:    startTime=2017-01-13 10:42:18.789    requestID=fTv1FmO/PvoG.2    txID=null    sessionID=fTv1FmO/PvoG    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:18,790 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue84) fTv1FmO/PvoG     END USER COMMAND:    endTime=2017-01-13 10:42:18.79    requestID=fTv1FmO/PvoG.2    txID=null    sessionID=fTv1FmO/PvoG    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:18,791 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue84) fTv1FmO/PvoG     START USER COMMAND:    startTime=2017-01-13 10:42:18.791    requestID=fTv1FmO/PvoG.3    txID=null    sessionID=fTv1FmO/PvoG    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:18,791 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue84) fTv1FmO/PvoG     END USER COMMAND:    endTime=2017-01-13 10:42:18.791    requestID=fTv1FmO/PvoG.3    txID=null    sessionID=fTv1FmO/PvoG    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:18,792 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue84) fTv1FmO/PvoG     END USER COMMAND:    endTime=2017-01-13 10:42:18.792    requestID=fTv1FmO/PvoG.1    txID=null    sessionID=fTv1FmO/PvoG    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:18,792 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #2) fTv1FmO/PvoG     START USER COMMAND:    startTime=2017-01-13 10:42:18.792    requestID=fTv1FmO/PvoG.4    txID=null    sessionID=fTv1FmO/PvoG    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select relname, nspname, relkind from pg_catalog.pg_class c, pg_catalog.pg_namespace n where relkind in ('r', 'v') and nspname not in ('pg_catalog', 'information_schema', 'pg_toast', 'pg_temp_1') and n.oid = relnamespace order by nspname, relname

10:42:18,799 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue85) fTv1FmO/PvoG     START USER COMMAND:    startTime=2017-01-13 10:42:18.798    requestID=fTv1FmO/PvoG.5    txID=null    sessionID=fTv1FmO/PvoG    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:18,799 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue85) fTv1FmO/PvoG     END USER COMMAND:    endTime=2017-01-13 10:42:18.799    requestID=fTv1FmO/PvoG.5    txID=null    sessionID=fTv1FmO/PvoG    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:18,800 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue85) fTv1FmO/PvoG     START USER COMMAND:    startTime=2017-01-13 10:42:18.8    requestID=fTv1FmO/PvoG.6    txID=null    sessionID=fTv1FmO/PvoG    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:18,800 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue85) fTv1FmO/PvoG     END USER COMMAND:    endTime=2017-01-13 10:42:18.8    requestID=fTv1FmO/PvoG.6    txID=null    sessionID=fTv1FmO/PvoG    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:18,801 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue85) fTv1FmO/PvoG     START USER COMMAND:    startTime=2017-01-13 10:42:18.801    requestID=fTv1FmO/PvoG.7    txID=null    sessionID=fTv1FmO/PvoG    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:18,801 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue85) fTv1FmO/PvoG     END USER COMMAND:    endTime=2017-01-13 10:42:18.801    requestID=fTv1FmO/PvoG.7    txID=null    sessionID=fTv1FmO/PvoG    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:18,804 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue85) fTv1FmO/PvoG     END USER COMMAND:    endTime=2017-01-13 10:42:18.804    requestID=fTv1FmO/PvoG.4    txID=null    sessionID=fTv1FmO/PvoG    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=42

10:42:25,819 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #4) xQipG0+3mDJ8     START USER COMMAND:    startTime=2017-01-13 10:42:25.819    requestID=xQipG0+3mDJ8.0    txID=null    sessionID=xQipG0+3mDJ8    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select teiid_session_set('resolve_groupby_positional', true)

10:42:25,821 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #4) xQipG0+3mDJ8     END USER COMMAND:    endTime=2017-01-13 10:42:25.821    requestID=xQipG0+3mDJ8.0    txID=null    sessionID=xQipG0+3mDJ8    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,823 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #4) xQipG0+3mDJ8     START USER COMMAND:    startTime=2017-01-13 10:42:25.823    requestID=xQipG0+3mDJ8.1    txID=null    sessionID=xQipG0+3mDJ8    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select oid, typbasetype from pg_type where typname = 'lo'

10:42:25,828 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue86) xQipG0+3mDJ8     START USER COMMAND:    startTime=2017-01-13 10:42:25.828    requestID=xQipG0+3mDJ8.2    txID=null    sessionID=xQipG0+3mDJ8    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,831 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue86) xQipG0+3mDJ8     END USER COMMAND:    endTime=2017-01-13 10:42:25.831    requestID=xQipG0+3mDJ8.2    txID=null    sessionID=xQipG0+3mDJ8    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,831 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue86) xQipG0+3mDJ8     START USER COMMAND:    startTime=2017-01-13 10:42:25.831    requestID=xQipG0+3mDJ8.3    txID=null    sessionID=xQipG0+3mDJ8    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,832 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue86) xQipG0+3mDJ8     END USER COMMAND:    endTime=2017-01-13 10:42:25.832    requestID=xQipG0+3mDJ8.3    txID=null    sessionID=xQipG0+3mDJ8    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,834 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue86) xQipG0+3mDJ8     END USER COMMAND:    endTime=2017-01-13 10:42:25.834    requestID=xQipG0+3mDJ8.1    txID=null    sessionID=xQipG0+3mDJ8    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,834 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #4) xQipG0+3mDJ8     START USER COMMAND:    startTime=2017-01-13 10:42:25.834    requestID=xQipG0+3mDJ8.4    txID=null    sessionID=xQipG0+3mDJ8    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select 63

10:42:25,838 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue87) xQipG0+3mDJ8     END USER COMMAND:    endTime=2017-01-13 10:42:25.838    requestID=xQipG0+3mDJ8.4    txID=null    sessionID=xQipG0+3mDJ8    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,849 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #5) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.849    requestID=mg69fjzznKHO.0    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select teiid_session_set('resolve_groupby_positional', true)

10:42:25,851 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #5) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.851    requestID=mg69fjzznKHO.0    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,855 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #5) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.855    requestID=mg69fjzznKHO.1    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select oid, typbasetype from pg_type where typname = 'lo'

10:42:25,860 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue88) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.86    requestID=mg69fjzznKHO.2    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,861 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue88) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.861    requestID=mg69fjzznKHO.2    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,862 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue88) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.862    requestID=mg69fjzznKHO.3    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,863 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue88) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.863    requestID=mg69fjzznKHO.3    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,864 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue88) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.864    requestID=mg69fjzznKHO.1    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,867 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #5) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.867    requestID=mg69fjzznKHO.4    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select n.nspname, c.relname, a.attname, a.atttypid, t.typname, a.attnum, a.attlen, a.atttypmod, a.attnotnull, c.relhasrules, c.relkind, c.oid, pg_get_expr(d.adbin, d.adrelid), case t.typtype when 'd' then t.typbasetype else 0 end, t.typtypmod, c.relhasoids from (((pg_catalog.pg_class c inner join pg_catalog.pg_namespace n on n.oid = c.relnamespace and c.relname like 'Shift' and n.nspname like 'MS\_SQL\_AdventureWorks2016\_view') inner join pg_catalog.pg_attribute a on (not a.attisdropped) and a.attnum > 0 and a.attrelid = c.oid) inner join pg_catalog.pg_type t on t.oid = a.atttypid) left outer join pg_attrdef d on a.atthasdef and d.adrelid = a.attrelid and d.adnum = a.attnum order by n.nspname, c.relname, attnum

10:42:25,886 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.886    requestID=mg69fjzznKHO.5    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,886 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.886    requestID=mg69fjzznKHO.5    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,887 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.887    requestID=mg69fjzznKHO.6    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,888 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.888    requestID=mg69fjzznKHO.6    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,888 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.888    requestID=mg69fjzznKHO.7    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,888 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.888    requestID=mg69fjzznKHO.7    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,889 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.889    requestID=mg69fjzznKHO.8    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,889 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.889    requestID=mg69fjzznKHO.8    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,889 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.889    requestID=mg69fjzznKHO.9    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,890 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.89    requestID=mg69fjzznKHO.9    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,890 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.89    requestID=mg69fjzznKHO.10    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,891 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.891    requestID=mg69fjzznKHO.10    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,891 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.891    requestID=mg69fjzznKHO.11    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,891 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.891    requestID=mg69fjzznKHO.11    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,892 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.892    requestID=mg69fjzznKHO.12    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,893 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.893    requestID=mg69fjzznKHO.12    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,893 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.893    requestID=mg69fjzznKHO.13    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,893 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.893    requestID=mg69fjzznKHO.13    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,894 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.894    requestID=mg69fjzznKHO.14    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,894 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.894    requestID=mg69fjzznKHO.14    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,894 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.894    requestID=mg69fjzznKHO.15    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,895 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.895    requestID=mg69fjzznKHO.15    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,895 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.895    requestID=mg69fjzznKHO.16    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,895 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.895    requestID=mg69fjzznKHO.16    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,896 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.896    requestID=mg69fjzznKHO.17    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,896 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.896    requestID=mg69fjzznKHO.17    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,896 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     START USER COMMAND:    startTime=2017-01-13 10:42:25.896    requestID=mg69fjzznKHO.18    txID=null    sessionID=mg69fjzznKHO    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,897 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.897    requestID=mg69fjzznKHO.18    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,897 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue89) mg69fjzznKHO     END USER COMMAND:    endTime=2017-01-13 10:42:25.897    requestID=mg69fjzznKHO.4    txID=null    sessionID=mg69fjzznKHO    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=0

10:42:25,907 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #7) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.907    requestID=6o6brpnHy8dE.0    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select teiid_session_set('resolve_groupby_positional', true)

10:42:25,909 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #7) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.909    requestID=6o6brpnHy8dE.0    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,911 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #7) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.911    requestID=6o6brpnHy8dE.1    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select oid, typbasetype from pg_type where typname = 'lo'

10:42:25,915 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue90) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.915    requestID=6o6brpnHy8dE.2    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,916 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue90) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.916    requestID=6o6brpnHy8dE.2    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,917 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue90) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.917    requestID=6o6brpnHy8dE.3    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,917 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue90) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.917    requestID=6o6brpnHy8dE.3    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,919 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue90) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.919    requestID=6o6brpnHy8dE.1    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,922 DEBUG [org.teiid.COMMAND_LOG] (New I/O worker #7) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.922    requestID=6o6brpnHy8dE.4    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=SELECT k.Name AS attname, convert(Position, short) AS attnum, TableName AS relname, SchemaName AS nspname, TableName AS relname FROM SYS.KeyColumns k WHERE  UCASE(SchemaName) LIKE UCASE('MS_SQL_AdventureWorks2016_view') AND UCASE(TableName) LIKE UCASE('Shift') AND KeyType LIKE 'Primary' ORDER BY attnum

10:42:25,925 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.925    requestID=6o6brpnHy8dE.5    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,925 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.925    requestID=6o6brpnHy8dE.5    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,925 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.925    requestID=6o6brpnHy8dE.6    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,926 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.926    requestID=6o6brpnHy8dE.6    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,926 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.926    requestID=6o6brpnHy8dE.7    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,927 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.927    requestID=6o6brpnHy8dE.7    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,927 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     START USER COMMAND:    startTime=2017-01-13 10:42:25.927    requestID=6o6brpnHy8dE.8    txID=null    sessionID=6o6brpnHy8dE    applicationName=ODBC    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    sql=select attrelid, attnum, typoid from matpg_relatt where attname = ? and relname = ? and nspname = ?

10:42:25,927 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.927    requestID=6o6brpnHy8dE.8    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

10:42:25,928 DEBUG [org.teiid.COMMAND_LOG] (Worker10_QueryProcessorQueue91) 6o6brpnHy8dE     END USER COMMAND:    endTime=2017-01-13 10:42:25.928    requestID=6o6brpnHy8dE.4    txID=null    sessionID=6o6brpnHy8dE    principal=teiid@teiid-security    vdbName=AdventureWorks2016    vdbVersion=1    finalRowCount=1

[rareddy]

Could you provide a minimal VDB that exhibits this behavior? I have to recreate the issue locally to suggest any further steps.

[romagnolli]

My VDB (called AdventureWorks2016.vdb) is very simple - it contains only 2 views: "MS_SQL_AdventureWorks2016_source.xmi" (which is a source model) and "MS_SQL_AdventureWorks2016_view.xmi" (which is a view model).

The source model is importing data from AdventureWorks2016 database, which is MS SQL Server 2016.

I made the connection to the MS SQL Server using Generic JDBC Driver - I've downloaded the latest version of the SQL Server JDBC Driver - sqljdbc42.jar.

13 tables are imported from the SQL Server database:

[HumanResources].[Department]

[HumanResources].[Employee]

[HumanResources].[EmployeeDepartmentHistory]

[HumanResources].[Shift]

[Person].[BusinessEntity]

[Person].[Address]

[Person].[AddressType]

[Person].[BusinessEntityAddress]

[Person].[CountryRegion]

[Person].[EmailAddress]

[Person].[Person]

[Person].[PersonPhone]

[Person].[PhoneNumberType]

[Person].[StateProvince]

Then the view model is created - without any modifications on the source data. VDB is created and deployed successfully on the server. Then I tested the connection to the VDB using SQuirreL SQL client (JDBC connection) - everything is OK. Then tested the connection using MS Access (ODBC connection) - everything is OK. But using the same ODBC connection gave the error message with the "missing columns in the tables".....

I'm not an expert , but suspect that the Teiid user cannot access the SYS schema of my VDB - look at this row in my log file: "sql=SELECT k.Name AS attname, convert(Position, short) AS attnum, TableName AS relname, SchemaName AS nspname, TableName AS relname FROM SYS.KeyColumns k WHERE  UCASE(SchemaName) LIKE UCASE('MS_SQL_AdventureWorks2016_view') AND UCASE(TableName) LIKE UCASE('Shift') AND KeyType LIKE 'Primary' ORDER BY attnum".

I was using the same user for the connection with JDBC (SQuirreL) and ODBC (MS Access).

This user is created with the help of the add-user.bat. The user has "Application User" type....

[rareddy]

It would be easy for me if you can provide me a working VDB.

[romagnolli]

You need only the ".vdb" file ? And how can I provide it to you - I don't see an option to attach a file to my message....?

[rareddy]

Yes, use advance editor it will give you an option to attach the file. Preferably export the .vdb as the -vdb.xml file, then attach.

[romagnolli]

This is my .vdb. I can't find the way to export it as XML....

[romagnolli]

Hi Ramesh,

Do you have any progress on that issue...?

Thanks in advance

Ivan

[romagnolli]

Hi Ramesh,

I've attached my whole Teiid Model Project...

In PowerPivot I also have issues using the DSN.....

[rareddy]

I am having difficulty in securing Excel for testing. Can you post the error you see in above dialog.

[romagnolli]

ERROR [50000] ERROR: TEIID31100 Parsing error: Encountered "SELECT [*][[*]BT_EQUIPMENTORDER]" at line 1, column 10.

Was expecting: "char" | "cast" | "convert" | "all" | "any" | "array_agg" | "case" | "distinct" | "exists" | "false" ...;

No query has been executed with that handle

[rareddy]

Can you see the server.log and post the full error. Also if there is no stack, turn on the debugging on Teiid server then execute the query. From what I can tell Excel is generating a query that is not ANSI compatible or not standard, but I can confirm it when I see the full error. You can also turn on COMMAND_LOG on Teiid side to get the exact query that has been submitted too.

[romagnolli]

In the attached file is the Teiid_Command_Log - you can search for the word "Department" (in order to find the full query text) - this is the name of the table that I try to import in PowerPivot....

In server.log there are no records related to this error....

[rareddy]

I do not see the offending query in command_log, it never made through the parser layer, if the server logs in DEBUG mode, you should see in server.log.

[romagnolli]

I made another test - I tried to export data from a table called "PhoneNumberType" and here are all the logs that I managed to collect:

It's interesting that if I choose the option to write my own SELECT statement instead of selecting tables with checkboxes - everything goes well...

Looks like the wizard is throwing SELECT [*][[*]MSSQLServerAdventureWorksview]

instead of

SELECT * FROM MSSQLServerAdventureWorksview.PhoneNumberType

server.log

11:06:59,190 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue0) TEIID30013 Loading materialized view table #MAT_PG_CATALOG.PG_TYPE

11:06:59,233 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30014 Loaded materialized view table #MAT_PG_CATALOG.PG_TYPE with row count 37.

11:06:59,253 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30013 Loading materialized view table #MAT_PG_CATALOG.MATPG_RELATT

11:06:59,284 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30013 Loading materialized view table #MAT_PG_CATALOG.PG_ATTRIBUTE

11:06:59,303 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30013 Loading materialized view table #MAT_PG_CATALOG.MATPG_DATATYPE

11:06:59,321 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30014 Loaded materialized view table #MAT_PG_CATALOG.MATPG_DATATYPE with row count 18.

11:06:59,387 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30014 Loaded materialized view table #MAT_PG_CATALOG.PG_ATTRIBUTE with row count 498.

11:06:59,401 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30013 Loading materialized view table #MAT_PG_CATALOG.PG_CLASS

11:06:59,417 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30014 Loaded materialized view table #MAT_PG_CATALOG.PG_CLASS with row count 113.

11:06:59,507 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue2) TEIID30014 Loaded materialized view table #MAT_PG_CATALOG.MATPG_RELATT with row count 398.

11:07:31,733 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue10) TEIID30013 Loading materialized view table #MAT_PG_CATALOG.PG_INDEX

11:07:31,802 INFO  [org.teiid.PROCESSOR.MATVIEWS] (Worker0_QueryProcessorQueue10) TEIID30014 Loaded materialized view table #MAT_PG_CATALOG.PG_INDEX with row count 89.

11:07:35,671 WARN  [org.teiid.PROCESSOR] (Worker0_QueryProcessorQueue12) TEIID30020 Processing exception for request KXaPs8w95zD3.4 'TEIID31100 Parsing error: Encountered "SELECT [*][[*]MSSQLServerAdventureWorksview]" at line 1, column 8.

Was expecting: "char" | "cast" | "convert" | "all" | "any" | "array_agg" | "case" | "distinct" | "exists" | "false" ...'. Originally QueryParserException QueryParser.java:214. Enable more detailed logging to see the entire stacktrace.

teiid_command_log and ODBC.log are in the attached files.