sqlalchemy/superset does not work --------------------------------- Key: TEIID-5655 URL: https://issues.jboss.org/browse/TEIID-5655 Project: Teiid Issue Type: Bug Components: ODBC Affects Versions: 11.0 Environment: Docker jboss/teiid:latest Reporter: luca gioppo Assignee: Steven Hawkins Priority: Major Fix For: 12.1, 12.0.1, 11.2.2 I have a TEIID docker container working with a VDB installed and trying to access it through superset. It seems that it manages to connect but cannot retrieve metadata information: error on TEIID is the following: {code:java} 14:37:26,443 DEBUG [org.teiid.COMMAND_LOG] (NIO2) InB1qhclEodo START USER COMMAND: startTime=2019-02-11 14:37:26.443 requestID=InB1qhclEodo.0 txID=null sessionID=InB1qhclEodo applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select teiid_session_set('resolve_groupby_positional', true) 14:37:26,444 DEBUG [org.teiid.COMMAND_LOG] (NIO2) InB1qhclEodo END USER COMMAND: endTime=2019-02-11 14:37:26.444 requestID=InB1qhclEodo.0 txID=null sessionID=InB1qhclEodo principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,445 DEBUG [org.teiid.COMMAND_LOG] (NIO2) InB1qhclEodo START USER COMMAND: startTime=2019-02-11 14:37:26.445 requestID=InB1qhclEodo.1 txID=null sessionID=InB1qhclEodo applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT t.oid, NULL FROM pg_type t JOIN pg_namespace ns ON typnamespace = ns.oid WHERE typname = 'hstore' 14:37:26,448 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue551) InB1qhclEodo START USER COMMAND: startTime=2019-02-11 14:37:26.448 requestID=InB1qhclEodo.2 txID=null sessionID=InB1qhclEodo applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,449 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue551) InB1qhclEodo END USER COMMAND: endTime=2019-02-11 14:37:26.449 requestID=InB1qhclEodo.2 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:34a status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=InB1qhclEodo principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,450 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue552) InB1qhclEodo END USER COMMAND: endTime=2019-02-11 14:37:26.45 requestID=InB1qhclEodo.1 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:34a status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=InB1qhclEodo principal=user vdbName=micado vdbVersion=1 finalRowCount=0 14:37:26,450 DEBUG [org.teiid.COMMAND_LOG] (NIO2) InB1qhclEodo START USER COMMAND: startTime=2019-02-11 14:37:26.45 requestID=InB1qhclEodo.3 txID=null sessionID=InB1qhclEodo applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select version() 14:37:26,452 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue554) InB1qhclEodo END USER COMMAND: endTime=2019-02-11 14:37:26.452 requestID=InB1qhclEodo.3 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:34d status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=InB1qhclEodo principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,452 DEBUG [org.teiid.COMMAND_LOG] (NIO2) InB1qhclEodo START USER COMMAND: startTime=2019-02-11 14:37:26.452 requestID=InB1qhclEodo.4 txID=null sessionID=InB1qhclEodo applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT '' 14:37:26,453 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue556) InB1qhclEodo END USER COMMAND: endTime=2019-02-11 14:37:26.453 requestID=InB1qhclEodo.4 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:34d status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=InB1qhclEodo principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,454 DEBUG [org.teiid.COMMAND_LOG] (NIO2) InB1qhclEodo START USER COMMAND: startTime=2019-02-11 14:37:26.454 requestID=InB1qhclEodo.5 txID=null sessionID=InB1qhclEodo applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT CAST('test plain returns' AS VARCHAR(60)) AS anon_1 14:37:26,455 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue558) InB1qhclEodo END USER COMMAND: endTime=2019-02-11 14:37:26.455 requestID=InB1qhclEodo.5 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:34d status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=InB1qhclEodo principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,455 DEBUG [org.teiid.COMMAND_LOG] (NIO2) InB1qhclEodo START USER COMMAND: startTime=2019-02-11 14:37:26.455 requestID=InB1qhclEodo.6 txID=null sessionID=InB1qhclEodo applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT CAST('test unicode returns' AS VARCHAR(60)) AS anon_1 14:37:26,458 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue560) InB1qhclEodo END USER COMMAND: endTime=2019-02-11 14:37:26.458 requestID=InB1qhclEodo.6 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:34d status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=InB1qhclEodo principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,460 DEBUG [org.teiid.COMMAND_LOG] (NIO3) fAwN5yLRkx4H START USER COMMAND: startTime=2019-02-11 14:37:26.46 requestID=fAwN5yLRkx4H.0 txID=null sessionID=fAwN5yLRkx4H applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select teiid_session_set('resolve_groupby_positional', true) 14:37:26,461 DEBUG [org.teiid.COMMAND_LOG] (NIO3) fAwN5yLRkx4H END USER COMMAND: endTime=2019-02-11 14:37:26.461 requestID=fAwN5yLRkx4H.0 txID=null sessionID=fAwN5yLRkx4H principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,462 DEBUG [org.teiid.COMMAND_LOG] (NIO3) fAwN5yLRkx4H START USER COMMAND: startTime=2019-02-11 14:37:26.462 requestID=fAwN5yLRkx4H.1 txID=null sessionID=fAwN5yLRkx4H applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT c.oid FROM pg_catalog.pg_class c LEFT JOIN pg_catalog.pg_namespace n ON n.oid = c.relnamespace WHERE (n.nspname = 'ViewModel') AND c.relname = 'countries' AND c.relkind in ('r', 'v', 'm', 'f') 14:37:26,466 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue561) fAwN5yLRkx4H START USER COMMAND: startTime=2019-02-11 14:37:26.466 requestID=fAwN5yLRkx4H.2 txID=null sessionID=fAwN5yLRkx4H applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,467 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue561) fAwN5yLRkx4H END USER COMMAND: endTime=2019-02-11 14:37:26.467 requestID=fAwN5yLRkx4H.2 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:350 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=fAwN5yLRkx4H principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,468 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue562) fAwN5yLRkx4H END USER COMMAND: endTime=2019-02-11 14:37:26.468 requestID=fAwN5yLRkx4H.1 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:350 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=fAwN5yLRkx4H principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,471 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.471 requestID=r0qH9ETfzvCM.0 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select teiid_session_set('resolve_groupby_positional', true) 14:37:26,472 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM END USER COMMAND: endTime=2019-02-11 14:37:26.472 requestID=r0qH9ETfzvCM.0 txID=null sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,473 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.473 requestID=r0qH9ETfzvCM.1 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT a.attname, pg_catalog.format_type(a.atttypid, a.atttypmod), (SELECT pg_catalog.pg_get_expr(d.adbin, d.adrelid) FROM pg_catalog.pg_attrdef d WHERE d.adrelid = a.attrelid AND d.adnum = a.attnum AND a.atthasdef) AS DEFAULT, a.attnotnull, a.attnum, a.attrelid as table_oid, pgd.description as comment FROM pg_catalog.pg_attribute a LEFT JOIN pg_catalog.pg_description pgd ON ( pgd.objoid = a.attrelid AND pgd.objsubid = a.attnum) WHERE a.attrelid = 33 AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum 14:37:26,495 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.495 requestID=r0qH9ETfzvCM.-3371315444994726912 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,497 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.497 requestID=r0qH9ETfzvCM.-2569683003037246464 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,499 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.499 requestID=r0qH9ETfzvCM.-760253570742753280 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,501 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.501 requestID=r0qH9ETfzvCM.-1144088098515236864 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,504 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.504 requestID=r0qH9ETfzvCM.-4500981364408287232 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,507 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.507 requestID=r0qH9ETfzvCM.-4578430080951506944 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,508 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.508 requestID=r0qH9ETfzvCM.-7781261662498164736 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,509 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.509 requestID=r0qH9ETfzvCM.-8862428592286505984 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,511 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.511 requestID=r0qH9ETfzvCM.-1326429883415938048 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,512 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.512 requestID=r0qH9ETfzvCM.-2514831200636129280 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,514 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.514 requestID=r0qH9ETfzvCM.-1599350125892356096 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select typname from pg_catalog.pg_type where oid = ? 14:37:26,520 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.518 requestID=r0qH9ETfzvCM.2 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,520 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM END USER COMMAND: endTime=2019-02-11 14:37:26.52 requestID=r0qH9ETfzvCM.2 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,521 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.521 requestID=r0qH9ETfzvCM.3 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,521 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM END USER COMMAND: endTime=2019-02-11 14:37:26.521 requestID=r0qH9ETfzvCM.3 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,521 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.521 requestID=r0qH9ETfzvCM.4 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,522 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM END USER COMMAND: endTime=2019-02-11 14:37:26.522 requestID=r0qH9ETfzvCM.4 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,522 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.522 requestID=r0qH9ETfzvCM.5 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,523 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM END USER COMMAND: endTime=2019-02-11 14:37:26.523 requestID=r0qH9ETfzvCM.5 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,523 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM START USER COMMAND: startTime=2019-02-11 14:37:26.523 requestID=r0qH9ETfzvCM.6 txID=null sessionID=r0qH9ETfzvCM applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,523 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue563) r0qH9ETfzvCM END USER COMMAND: endTime=2019-02-11 14:37:26.523 requestID=r0qH9ETfzvCM.6 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,525 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue564) r0qH9ETfzvCM END USER COMMAND: endTime=2019-02-11 14:37:26.525 requestID=r0qH9ETfzvCM.1 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=11 14:37:26,525 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.525 requestID=r0qH9ETfzvCM.-3371315444994726912 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-2569683003037246464 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-760253570742753280 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-1144088098515236864 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-4500981364408287232 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-4578430080951506944 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-7781261662498164736 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-8862428592286505984 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-1326429883415938048 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-2514831200636129280 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,526 DEBUG [org.teiid.COMMAND_LOG] (NIO4) r0qH9ETfzvCM CANCEL USER COMMAND: endTime=2019-02-11 14:37:26.526 requestID=r0qH9ETfzvCM.-1599350125892356096 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:353 status: ActionStatus.ABORTED >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=r0qH9ETfzvCM principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,527 DEBUG [org.teiid.COMMAND_LOG] (NIO5) iFzmjQNMXOvc START USER COMMAND: startTime=2019-02-11 14:37:26.527 requestID=iFzmjQNMXOvc.0 txID=null sessionID=iFzmjQNMXOvc applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select teiid_session_set('resolve_groupby_positional', true) 14:37:26,528 DEBUG [org.teiid.COMMAND_LOG] (NIO5) iFzmjQNMXOvc END USER COMMAND: endTime=2019-02-11 14:37:26.528 requestID=iFzmjQNMXOvc.0 txID=null sessionID=iFzmjQNMXOvc principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,530 DEBUG [org.teiid.COMMAND_LOG] (NIO5) iFzmjQNMXOvc START USER COMMAND: startTime=2019-02-11 14:37:26.53 requestID=iFzmjQNMXOvc.1 txID=null sessionID=iFzmjQNMXOvc applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT t.typname as "name", pg_catalog.format_type(t.typbasetype, t.typtypmod) as "attype", not t.typnotnull as "nullable", t.typdefault as "default", pg_catalog.pg_type_is_visible(t.oid) as "visible", n.nspname as "schema" FROM pg_catalog.pg_type t LEFT JOIN pg_catalog.pg_namespace n ON n.oid = t.typnamespace WHERE t.typtype = 'd' 14:37:26,533 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue565) iFzmjQNMXOvc START USER COMMAND: startTime=2019-02-11 14:37:26.533 requestID=iFzmjQNMXOvc.2 txID=null sessionID=iFzmjQNMXOvc applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,534 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue565) iFzmjQNMXOvc END USER COMMAND: endTime=2019-02-11 14:37:26.534 requestID=iFzmjQNMXOvc.2 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:356 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=iFzmjQNMXOvc principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,534 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue565) iFzmjQNMXOvc START USER COMMAND: startTime=2019-02-11 14:37:26.534 requestID=iFzmjQNMXOvc.3 txID=null sessionID=iFzmjQNMXOvc applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,535 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue565) iFzmjQNMXOvc END USER COMMAND: endTime=2019-02-11 14:37:26.535 requestID=iFzmjQNMXOvc.3 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:356 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=iFzmjQNMXOvc principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,535 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue565) iFzmjQNMXOvc START USER COMMAND: startTime=2019-02-11 14:37:26.535 requestID=iFzmjQNMXOvc.4 txID=null sessionID=iFzmjQNMXOvc applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,535 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue565) iFzmjQNMXOvc END USER COMMAND: endTime=2019-02-11 14:37:26.535 requestID=iFzmjQNMXOvc.4 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:356 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=iFzmjQNMXOvc principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,536 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue566) iFzmjQNMXOvc END USER COMMAND: endTime=2019-02-11 14:37:26.536 requestID=iFzmjQNMXOvc.1 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:356 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=iFzmjQNMXOvc principal=user vdbName=micado vdbVersion=1 finalRowCount=0 14:37:26,539 DEBUG [org.teiid.COMMAND_LOG] (NIO6) o/ZspOAKOAQq START USER COMMAND: startTime=2019-02-11 14:37:26.539 requestID=o/ZspOAKOAQq.0 txID=null sessionID=o/ZspOAKOAQq applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select teiid_session_set('resolve_groupby_positional', true) 14:37:26,540 DEBUG [org.teiid.COMMAND_LOG] (NIO6) o/ZspOAKOAQq END USER COMMAND: endTime=2019-02-11 14:37:26.54 requestID=o/ZspOAKOAQq.0 txID=null sessionID=o/ZspOAKOAQq principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,544 DEBUG [org.teiid.COMMAND_LOG] (NIO7) 2bY7LmYVew3V START USER COMMAND: startTime=2019-02-11 14:37:26.543 requestID=2bY7LmYVew3V.0 txID=null sessionID=2bY7LmYVew3V applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select teiid_session_set('resolve_groupby_positional', true) 14:37:26,544 DEBUG [org.teiid.COMMAND_LOG] (NIO7) 2bY7LmYVew3V END USER COMMAND: endTime=2019-02-11 14:37:26.544 requestID=2bY7LmYVew3V.0 txID=null sessionID=2bY7LmYVew3V principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,546 DEBUG [org.teiid.COMMAND_LOG] (NIO7) 2bY7LmYVew3V START USER COMMAND: startTime=2019-02-11 14:37:26.546 requestID=2bY7LmYVew3V.1 txID=null sessionID=2bY7LmYVew3V applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT c.oid FROM pg_catalog.pg_class c LEFT JOIN pg_catalog.pg_namespace n ON n.oid = c.relnamespace WHERE (n.nspname = 'ViewModel') AND c.relname = 'countries' AND c.relkind in ('r', 'v', 'm', 'f') 14:37:26,550 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue567) 2bY7LmYVew3V START USER COMMAND: startTime=2019-02-11 14:37:26.55 requestID=2bY7LmYVew3V.2 txID=null sessionID=2bY7LmYVew3V applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select pg_catalog.getOid(SYS.Columns.TableUID), cast(SYS.Columns.Position as short), cast((select p.value from SYS.Properties p where p.name = 'pg_type:oid' and p.uid = SYS.Columns.uid) as integer) from SYS.Columns where Name = ? and TableName = ? and SchemaName = ? 14:37:26,551 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue567) 2bY7LmYVew3V END USER COMMAND: endTime=2019-02-11 14:37:26.551 requestID=2bY7LmYVew3V.2 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:359 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=2bY7LmYVew3V principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,552 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue568) 2bY7LmYVew3V END USER COMMAND: endTime=2019-02-11 14:37:26.552 requestID=2bY7LmYVew3V.1 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffffac1d0003:21e3de1a:5c6149ba:359 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=2bY7LmYVew3V principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,555 DEBUG [org.teiid.COMMAND_LOG] (NIO8) P/Da0+l55xke START USER COMMAND: startTime=2019-02-11 14:37:26.555 requestID=P/Da0+l55xke.0 txID=null sessionID=P/Da0+l55xke applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=select teiid_session_set('resolve_groupby_positional', true) 14:37:26,555 DEBUG [org.teiid.COMMAND_LOG] (NIO8) P/Da0+l55xke END USER COMMAND: endTime=2019-02-11 14:37:26.555 requestID=P/Da0+l55xke.0 txID=null sessionID=P/Da0+l55xke principal=user vdbName=micado vdbVersion=1 finalRowCount=1 14:37:26,557 DEBUG [org.teiid.COMMAND_LOG] (NIO8) P/Da0+l55xke START USER COMMAND: startTime=2019-02-11 14:37:26.557 requestID=P/Da0+l55xke.1 txID=null sessionID=P/Da0+l55xke applicationName=ODBC principal=user vdbName=micado vdbVersion=1 sql=SELECT i.relname as relname, ix.indisunique, ix.indexprs, ix.indpred, a.attname, a.attnum, NULL, ix.indkey, i.reloptions, am.amname FROM pg_class t join pg_index ix on t.oid = ix.indrelid join pg_class i on i.oid = ix.indexrelid left outer join pg_attribute a on t.oid = a.attrelid and a.attnum = ANY(ix.indkey) left outer join pg_am am on i.relam = am.oid WHERE t.relkind IN ('r', 'v', 'f', 'm') and t.oid = 33 and ix.indisprimary = 'f' ORDER BY t.relname, i.relname 14:37:26,560 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue569) P/Da0+l55xke ERROR USER COMMAND: endTime=2019-02-11 14:37:26.56 requestID=P/Da0+l55xke.1 txID=null sessionID=P/Da0+l55xke principal=user vdbName=micado vdbVersion=1 finalRowCount=null 14:37:26,560 ERROR [org.teiid.PROCESSOR] (Worker17_QueryProcessorQueue569) P/Da0+l55xke TEIID30019 Unexpected exception for request P/Da0+l55xke.1: java.lang.NullPointerException at org.teiid.query.eval.Evaluator.evaluate(Evaluator.java:571) at org.teiid.query.eval.Evaluator.internalEvaluateTVL(Evaluator.java:193) at org.teiid.query.eval.Evaluator.evaluateTVL(Evaluator.java:174) at org.teiid.query.rewriter.QueryRewriter.evaluateCriteria(QueryRewriter.java:1347) at org.teiid.query.rewriter.QueryRewriter.rewriteCriteria(QueryRewriter.java:1041) at org.teiid.query.rewriter.QueryRewriter.rewriteCriteria(QueryRewriter.java:979) at org.teiid.query.optimizer.relational.rules.JoinUtil.isNullDependent(JoinUtil.java:144) at org.teiid.query.optimizer.relational.rules.RulePlanOuterJoins.isCriteriaValid(RulePlanOuterJoins.java:218) at org.teiid.query.optimizer.relational.rules.RulePlanOuterJoins.checkLeftOrdering(RulePlanOuterJoins.java:344) at org.teiid.query.optimizer.relational.rules.RulePlanOuterJoins.planLeftOuterJoinAssociativityBeforePlanning(RulePlanOuterJoins.java:251) at org.teiid.query.optimizer.relational.rules.RulePlanOuterJoins.execute(RulePlanOuterJoins.java:62) at org.teiid.query.optimizer.relational.RelationalPlanner.executeRules(RelationalPlanner.java:1025) at org.teiid.query.optimizer.relational.RelationalPlanner.optimize(RelationalPlanner.java:228) at org.teiid.query.optimizer.QueryOptimizer.optimizePlan(QueryOptimizer.java:179) at org.teiid.dqp.internal.process.Request.generatePlan(Request.java:458) at org.teiid.dqp.internal.process.Request.processRequest(Request.java:486) at org.teiid.dqp.internal.process.RequestWorkItem.processNew(RequestWorkItem.java:672) at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:351) at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:47) at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:285) at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:277) at org.teiid.dqp.internal.process.ThreadReuseExecutor$RunnableWrapper.run(ThreadReuseExecutor.java:115) at org.teiid.dqp.internal.process.ThreadReuseExecutor$3.run(ThreadReuseExecutor.java:206) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:37:26,561 DEBUG [org.teiid.COMMAND_LOG] (Worker17_QueryProcessorQueue569) P/Da0+l55xke END USER COMMAND: endTime=2019-02-11 14:37:26.561 requestID=P/Da0+l55xke.1 txID=null sessionID=P/Da0+l55xke principal=user vdbName=micado vdbVersion=1 finalRowCount=-1 {code}