Skip to content

Instantly share code, notes, and snippets.

Embed
What would you like to do?
2021-03-04 14:57:31,929 DEBUG [org.teiid.AUDIT_LOG] (default task-1016) [Test.1, null anonymous USERPASSWORD null null null true] <session.logon-request>
2021-03-04 14:57:31,931 DEBUG [org.teiid.SECURITY] (default task-1016) authenticateUser anonymous OData
2021-03-04 14:57:31,931 DEBUG [org.teiid.SECURITY] (default task-1016) Logon successful, created session: sessionid=ogTd/AG+4EeF; userName=c04bbd6b-865e-4702-9e4a-13fe528072d9; vdbName=Test; vdbVersion=1; createdTime=Thu Mar 04 14:57:31 UTC 2021; applicationName=OData; clientHostName=null; clientHardwareAddress=null; IPAddress=null; securityDomain=keycloak; lastPingTime=Thu Mar 04 14:57:31 UTC 2021
2021-03-04 14:57:31,932 DEBUG [org.teiid.AUDIT_LOG] (default task-1016) [c04bbd6b-865e-4702-9e4a-13fe528072d9] <session.logon-success>
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) Successfully obtained a session.
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) Connection Url=
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) proxy-base-uri=https://xxx.yyy.zz
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) PassthroughAuthentication=true
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) ApplicationName=OData
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) transportName=odata
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) waitForLoad=0
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) VirtualDatabaseName=Test.1
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) local-transport-name=odata
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) skiptoken-cache-time=300000
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) batch-size=-1
2021-03-04 14:57:31,932 FINE [org.teiid.jdbc] (default task-1016) The JDBC Driver successfully obtained a connection.
2021-03-04 14:57:31,943 DEBUG [org.teiid.ODATA] (default task-1016) Teiid-Query: INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:31,943 FINER [org.teiid.jdbc] (default task-1016) Executing: requestID -1 commands: [INSERT INTO Test.TestTable (Id) VALUES (?)] expecting: UPDATECOUNT
2021-03-04 14:57:31,943 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before begin:org.teiid.dqp.internal.process.DQPCore@13f292()
2021-03-04 14:57:31,943 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before begin:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF)
2021-03-04 14:57:31,944 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after begin : ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider)
2021-03-04 14:57:31,944 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after begin : org.teiid.client.util.ResultsFuture@4f5cf296
2021-03-04 14:57:31,944 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before executeRequest:org.teiid.dqp.internal.process.DQPCore@13f292(0,org.teiid.client.RequestMessage@7c8bb26f)
2021-03-04 14:57:31,944 DEBUG [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF START USER COMMAND: startTime=2021-03-04 14:57:31.944 requestID=ogTd/AG+4EeF.0 txID=null sessionID=ogTd/AG+4EeF applicationName=OData principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 sql=INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:31,945 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 start processing MORE_WORK
2021-03-04 14:57:31,945 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Request Thread ogTd/AG+4EeF.0 with state NEW
2021-03-04 14:57:31,945 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 Command has no cache hint and result set cache mode is not on.
2021-03-04 14:57:31,945 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 executing prepared INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:31,945 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Cache miss for Cache Entry<ogTd/AG+4EeF=c04bbd6b-865e-4702-9e4a-13fe528072d9> params:null sql:INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:31,945 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Query does not exist in cache: INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:31,945 TRACE [org.teiid.PLANNER.RESOLVER] (default task-1016) ogTd/AG+4EeF Resolving command INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:31,947 TRACE [org.teiid.PLANNER] (default task-1016) ogTd/AG+4EeF
============================================================================
USER COMMAND:
INSERT INTO Test.TestTable (Id) VALUES (?)
----------------------------------------------------------------------------
OPTIMIZE:
INSERT INTO Test.TestTable (Id) VALUES (?)
----------------------------------------------------------------------------
GENERATE CANONICAL:
INSERT INTO Test.TestTable (Id) VALUES (?)
CANONICAL PLAN:
Project(groups=[], props={PROJECT_COLS=[Count]})
Source(groups=[Test.TestTable], props={ATOMIC_REQUEST=INSERT INTO Test.TestTable (Id) VALUES (?), VIRTUAL_COMMAND=INSERT INTO Test.TestTable (Id) VALUES (?)})
============================================================================
EXECUTING PlaceAccess
AFTER:
Project(groups=[], props={PROJECT_COLS=[Count]})
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000})
Source(groups=[Test.TestTable], props={ATOMIC_REQUEST=INSERT INTO Test.TestTable (Id) VALUES (?), VIRTUAL_COMMAND=INSERT INTO Test.TestTable (Id) VALUES (?)})
============================================================================
EXECUTING RaiseAccess
AFTER:
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000})
Project(groups=[], props={PROJECT_COLS=[Count]})
Source(groups=[Test.TestTable], props={ATOMIC_REQUEST=INSERT INTO Test.TestTable (Id) VALUES (?), VIRTUAL_COMMAND=INSERT INTO Test.TestTable (Id) VALUES (?)})
============================================================================
EXECUTING AssignOutputElements
AFTER:
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000, OUTPUT_COLS=[Count]})
Project(groups=[], props={PROJECT_COLS=[Count], OUTPUT_COLS=[Count]})
Source(groups=[Test.TestTable], props={ATOMIC_REQUEST=INSERT INTO Test.TestTable (Id) VALUES (?), VIRTUAL_COMMAND=INSERT INTO Test.TestTable (Id) VALUES (?), OUTPUT_COLS=[Count]})
============================================================================
EXECUTING CalculateCost
AFTER:
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000, OUTPUT_COLS=[Count], EST_CARDINALITY=28.0, EST_COL_STATS={Count=[-1.0, -1.0, -1.0]}})
Project(groups=[], props={PROJECT_COLS=[Count], OUTPUT_COLS=[Count], EST_CARDINALITY=28.0, EST_COL_STATS={Count=[-1.0, -1.0, -1.0]}})
Source(groups=[Test.TestTable], props={ATOMIC_REQUEST=INSERT INTO Test.TestTable (Id) VALUES (?), VIRTUAL_COMMAND=INSERT INTO Test.TestTable (Id) VALUES (?), OUTPUT_COLS=[Count], EST_CARDINALITY=28.0})
============================================================================
EXECUTING PlanSubqueries
AFTER:
Access(groups=[Test.TestTable])
Project(groups=[])
Source(groups=[Test.TestTable])
============================================================================
EXECUTING PlanSorts
AFTER:
Access(groups=[Test.TestTable])
Project(groups=[])
Source(groups=[Test.TestTable])
============================================================================
EXECUTING CollapseSource
AFTER:
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000, OUTPUT_COLS=[Count], EST_CARDINALITY=28.0, EST_COL_STATS={Count=[-1.0, -1.0, -1.0]}, ATOMIC_REQUEST=INSERT INTO Test.TestTable (Id) VALUES (?)})
============================================================================
CONVERTING PLAN TREE TO PROCESS TREE
PROCESS PLAN =
AccessNode(0) output=[Count] INSERT INTO Test.TestTable (Id) VALUES (?)
============================================================================
----------------------------------------------------------------------------
OPTIMIZATION COMPLETE:
PROCESSOR PLAN:
AccessNode(0) output=[Count] INSERT INTO Test.TestTable (Id) VALUES (?)
============================================================================
2021-03-04 14:57:31,947 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ProcessTree for ogTd/AG+4EeF.0 AccessNode(0) output=[Count] INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:31,947 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Adding to global/distributed cache Cache Entry<ogTd/AG+4EeF=c04bbd6b-865e-4702-9e4a-13fe528072d9> params:null sql:INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:31,948 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before getOrCreateTransactionContext:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF)
2021-03-04 14:57:31,948 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after getOrCreateTransactionContext : ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider)
2021-03-04 14:57:31,948 TRACE [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF PLAN USER COMMAND: endTime=2021-03-04 14:57:31.948 requestID=ogTd/AG+4EeF.0 txID=null sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 finalRowCount=null plan=AccessNode:
Relational Node ID: 0
Output Columns: Count (integer)
Cost Estimates: Estimated Node Cardinality: 28.0
Query: INSERT INTO Test.TestTable (Id) VALUES (?)
Model Name: Test
2021-03-04 14:57:31,948 DEBUG [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Creating TupleBuffer: 43734 [Count] [class java.lang.Integer] batch size 2048 of type PROCESSOR
2021-03-04 14:57:31,948 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before resume:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:31,948 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after resume : null
2021-03-04 14:57:31,948 TRACE [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Reserving buffer space 114688 FORCE
2021-03-04 14:57:31,949 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Create State
2021-03-04 14:57:31,949 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Processing NEW request: INSERT INTO Test.TestTable (Id) VALUES (41)
2021-03-04 14:57:31,990 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Obtained execution
2021-03-04 14:57:31,990 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF START DATA SRC COMMAND: startTime=2021-03-04 14:57:31.99 requestID=ogTd/AG+4EeF.0 sourceCommandID=0 executionID=16884 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 sql=INSERT INTO Test.TestTable (Id) VALUES (41)
2021-03-04 14:57:31,991 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF Source-specific command: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)
2021-03-04 14:57:31,991 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF SOURCE SRC COMMAND: endTime=2021-03-04 14:57:31.991 requestID=ogTd/AG+4EeF.0 sourceCommandID=0 executionID=16884 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 sourceCommand=[INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]
2021-03-04 14:57:32,071 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Executed command
2021-03-04 14:57:32,071 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Processing MORE request
2021-03-04 14:57:32,071 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Getting results from connector
2021-03-04 14:57:32,071 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Obtained last batch, total row count: 1
2021-03-04 14:57:32,071 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF closed atomic-request: ogTd/AG+4EeF.0.0.16884
2021-03-04 14:57:32,071 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Remove State
2021-03-04 14:57:32,071 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Processing Close : INSERT INTO Test.TestTable (Id) VALUES (41)
2021-03-04 14:57:32,072 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Closed execution
2021-03-04 14:57:32,072 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0.0.16884 Closed connection
2021-03-04 14:57:32,072 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF END SRC COMMAND: endTime=2021-03-04 14:57:32.072 requestID=ogTd/AG+4EeF.0 sourceCommandID=0 executionID=16884 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 finalRowCount=1 cpuTime(ns)=5660551
2021-03-04 14:57:32,072 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF AccessNode(0) sending TupleBatch; beginning row=1, number of rows=1, lastBatch=1
1: [1]
2021-03-04 14:57:32,072 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF QueryProcessor: closing processor
2021-03-04 14:57:32,072 TRACE [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Releasing buffer space 114688
2021-03-04 14:57:32,072 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 Finished Processing
2021-03-04 14:57:32,072 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,073 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,073 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF [RequestWorkItem.sendResultsIfNeeded] requestID: ogTd/AG+4EeF.0 resultsID: 43734 done: true
2021-03-04 14:57:32,073 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Sending results for ogTd/AG+4EeF.0 start row 1 end row 1 17 bytes
2021-03-04 14:57:32,073 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,073 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,073 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 end processing WORKING
2021-03-04 14:57:32,073 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after executeRequest : org.teiid.client.util.ResultsFuture@341ad77e
2021-03-04 14:57:32,073 FINER [org.teiid.jdbc] (default task-1016) Recieved update counts: [1]
2021-03-04 14:57:32,073 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before closeRequest:org.teiid.dqp.internal.process.DQPCore@13f292(0)
2021-03-04 14:57:32,074 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF closeQuery for requestID=ogTd/AG+4EeF.0
2021-03-04 14:57:32,074 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 more work IDLE
2021-03-04 14:57:32,074 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Restarting processing using the calling thread ogTd/AG+4EeF.0
2021-03-04 14:57:32,074 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 start processing MORE_WORK
2021-03-04 14:57:32,074 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Request Thread ogTd/AG+4EeF.0 with state CLOSE
2021-03-04 14:57:32,074 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Removing tuplesource for the request ogTd/AG+4EeF.0
2021-03-04 14:57:32,074 DEBUG [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Removing TupleBuffer: 43734
2021-03-04 14:57:32,074 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,074 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,074 DEBUG [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF END USER COMMAND: endTime=2021-03-04 14:57:32.074 requestID=ogTd/AG+4EeF.0 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 finalRowCount=1 plan=AccessNode:
Relational Node ID: 0
Output Columns: Count (integer)
Statistics:
- Node Output Rows: 1
- Node Next Batch Process Time: 123
- Node Cumulative Next Batch Process Time: 123
- Node Cumulative Process Time: 123
- Node Next Batch Calls: 1
- Node Blocks: 0
Cost Estimates: Estimated Node Cardinality: 28.0
Query: INSERT INTO Test.TestTable (Id) VALUES (?)
Model Name: Test
2021-03-04 14:57:32,074 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,074 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,075 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 end processing WORKING
2021-03-04 14:57:32,075 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 done processing WORKING
2021-03-04 14:57:32,075 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after closeRequest : org.teiid.client.util.ResultsFuture@4f5cf296
2021-03-04 14:57:32,075 FINE [org.teiid.jdbc] (default task-1016) Successfully executed a query INSERT INTO Test.TestTable (Id) VALUES (?) and obtained results
2021-03-04 14:57:32,075 FINER [org.teiid.jdbc] (default task-1016) Creating ResultSet requestID: 0 beginRow: 1 resultsColumns: 0 parameters: 0
2021-03-04 14:57:32,076 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before closeRequest:org.teiid.dqp.internal.process.DQPCore@13f292(0)
2021-03-04 14:57:32,076 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF closeQuery for requestID=ogTd/AG+4EeF.0
2021-03-04 14:57:32,076 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.0 close call ignored as the request has already been removed.
2021-03-04 14:57:32,076 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after closeRequest : org.teiid.client.util.ResultsFuture@4f5cf296
2021-03-04 14:57:32,076 FINE [org.teiid.jdbc] (default task-1016) Statement closed successfully.
2021-03-04 14:57:32,076 DEBUG [org.teiid.ODATA] (default task-1016) created entity = TestTable with key= Test.TestTable.Id = 41
2021-03-04 14:57:32,076 DEBUG [org.teiid.ODATA] (default task-1016) Teiid-Query: /*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */
2021-03-04 14:57:32,076 FINER [org.teiid.jdbc] (default task-1016) Executing: requestID -1 commands: [/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */] expecting: RESULTSET
2021-03-04 14:57:32,076 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before executeRequest:org.teiid.dqp.internal.process.DQPCore@13f292(1,org.teiid.client.RequestMessage@49b7d625)
2021-03-04 14:57:32,077 DEBUG [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF START USER COMMAND: startTime=2021-03-04 14:57:32.077 requestID=ogTd/AG+4EeF.1 txID=null sessionID=ogTd/AG+4EeF applicationName=OData principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 sql=/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */
2021-03-04 14:57:32,077 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 start processing MORE_WORK
2021-03-04 14:57:32,077 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Request Thread ogTd/AG+4EeF.1 with state NEW
2021-03-04 14:57:32,077 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before getOrCreateTransactionContext:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF)
2021-03-04 14:57:32,077 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after getOrCreateTransactionContext : ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider)
2021-03-04 14:57:32,077 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before resume:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,077 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after resume : null
2021-03-04 14:57:32,077 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Cache miss for Cache Entry<ogTd/AG+4EeF=c04bbd6b-865e-4702-9e4a-13fe528072d9> params:[0, 64] sql:/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */
2021-03-04 14:57:32,078 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 executing prepared /*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */
2021-03-04 14:57:32,078 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Cache miss for Cache Entry<ogTd/AG+4EeF=c04bbd6b-865e-4702-9e4a-13fe528072d9> params:null sql:/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */
2021-03-04 14:57:32,078 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Query does not exist in cache: /*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */
2021-03-04 14:57:32,078 TRACE [org.teiid.PLANNER.RESOLVER] (default task-1016) ogTd/AG+4EeF Resolving command /*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ?
2021-03-04 14:57:32,080 TRACE [org.teiid.PLANNER] (default task-1016) ogTd/AG+4EeF
============================================================================
USER COMMAND:
/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ?
----------------------------------------------------------------------------
OPTIMIZE:
/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ?
----------------------------------------------------------------------------
GENERATE CANONICAL:
/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ?
CANONICAL PLAN:
TupleLimit(groups=[], props={OFFSET_TUPLE_COUNT=?, MAX_TUPLE_LIMIT=?})
Project(groups=[Test.TestTable], props={PROJECT_COLS=[Test.TestTable.Id]})
Select(groups=[Test.TestTable], props={SELECT_CRITERIA=Test.TestTable.Id = 41})
Source(groups=[Test.TestTable])
============================================================================
EXECUTING PlaceAccess
AFTER:
TupleLimit(groups=[], props={OFFSET_TUPLE_COUNT=?, MAX_TUPLE_LIMIT=?})
Project(groups=[Test.TestTable], props={PROJECT_COLS=[Test.TestTable.Id]})
Select(groups=[Test.TestTable], props={SELECT_CRITERIA=Test.TestTable.Id = 41})
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000})
Source(groups=[Test.TestTable])
============================================================================
EXECUTING PushSelectCriteria
AFTER:
TupleLimit(groups=[])
Project(groups=[Test.TestTable], props={PROJECT_COLS=[Test.TestTable.Id]})
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000})
Select(groups=[Test.TestTable], props={SELECT_CRITERIA=Test.TestTable.Id = 41})
Source(groups=[Test.TestTable])
============================================================================
EXECUTING CleanCriteria
AFTER:
TupleLimit(groups=[], props={OFFSET_TUPLE_COUNT=?, MAX_TUPLE_LIMIT=?, OUTPUT_COLS=null})
Project(groups=[Test.TestTable], props={PROJECT_COLS=[Test.TestTable.Id], OUTPUT_COLS=null})
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000, OUTPUT_COLS=null})
Select(groups=[Test.TestTable], props={SELECT_CRITERIA=Test.TestTable.Id = 41, OUTPUT_COLS=null})
Source(groups=[Test.TestTable], props={OUTPUT_COLS=null})
============================================================================
EXECUTING RaiseAccess
AFTER:
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000, OUTPUT_COLS=null})
TupleLimit(groups=[], props={OFFSET_TUPLE_COUNT=?, MAX_TUPLE_LIMIT=?, OUTPUT_COLS=null})
Project(groups=[Test.TestTable], props={PROJECT_COLS=[Test.TestTable.Id], OUTPUT_COLS=null})
Select(groups=[Test.TestTable], props={SELECT_CRITERIA=Test.TestTable.Id = 41, OUTPUT_COLS=null})
Source(groups=[Test.TestTable])
============================================================================
EXECUTING PushLargeIn
AFTER:
Access(groups=[Test.TestTable])
TupleLimit(groups=[])
Project(groups=[Test.TestTable])
Select(groups=[Test.TestTable])
Source(groups=[Test.TestTable])
============================================================================
EXECUTING AssignOutputElements
AFTER:
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000, OUTPUT_COLS=[Test.TestTable.Id]})
TupleLimit(groups=[], props={OFFSET_TUPLE_COUNT=?, MAX_TUPLE_LIMIT=?, OUTPUT_COLS=[Test.TestTable.Id]})
Project(groups=[Test.TestTable], props={PROJECT_COLS=[Test.TestTable.Id], OUTPUT_COLS=[Test.TestTable.Id]})
Select(groups=[Test.TestTable], props={SELECT_CRITERIA=Test.TestTable.Id = 41, OUTPUT_COLS=[Test.TestTable.Id]})
Source(groups=[Test.TestTable], props={OUTPUT_COLS=[Test.TestTable.Id]})
============================================================================
EXECUTING PushLimit
AFTER:
Access(groups=[Test.TestTable])
TupleLimit(groups=[])
Project(groups=[Test.TestTable])
Select(groups=[Test.TestTable])
Source(groups=[Test.TestTable])
============================================================================
EXECUTING CalculateCost
AFTER:
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000, OUTPUT_COLS=[Test.TestTable.Id], EST_CARDINALITY=1.0, EST_COL_STATS={Test.TestTable.Id=[1.0, 1.0, 0.0]}})
TupleLimit(groups=[], props={OFFSET_TUPLE_COUNT=?, MAX_TUPLE_LIMIT=?, OUTPUT_COLS=[Test.TestTable.Id], EST_CARDINALITY=1.0, EST_COL_STATS={Test.TestTable.Id=[1.0, 1.0, 0.0]}})
Project(groups=[Test.TestTable], props={PROJECT_COLS=[Test.TestTable.Id], OUTPUT_COLS=[Test.TestTable.Id], EST_CARDINALITY=1.0, EST_COL_STATS={Test.TestTable.Id=[1.0, 1.0, 0.0]}})
Select(groups=[Test.TestTable], props={SELECT_CRITERIA=Test.TestTable.Id = 41, OUTPUT_COLS=[Test.TestTable.Id], EST_CARDINALITY=1.0, EST_COL_STATS={Test.TestTable.Id=[1.0, 1.0, 0.0]}})
Source(groups=[Test.TestTable], props={OUTPUT_COLS=[Test.TestTable.Id], EST_COL_STATS={Test.TestTable.Id=[28.0, 28.0, 0.0]}, EST_CARDINALITY=28.0})
============================================================================
EXECUTING PlanSubqueries
AFTER:
Access(groups=[Test.TestTable])
TupleLimit(groups=[])
Project(groups=[Test.TestTable])
Select(groups=[Test.TestTable])
Source(groups=[Test.TestTable])
============================================================================
EXECUTING MergeCriteria
AFTER:
Access(groups=[Test.TestTable])
TupleLimit(groups=[])
Project(groups=[Test.TestTable])
Select(groups=[Test.TestTable])
Source(groups=[Test.TestTable])
============================================================================
EXECUTING PlanSorts
AFTER:
Access(groups=[Test.TestTable])
TupleLimit(groups=[])
Project(groups=[Test.TestTable])
Select(groups=[Test.TestTable])
Source(groups=[Test.TestTable])
============================================================================
EXECUTING CollapseSource
AFTER:
Access(groups=[Test.TestTable], props={SOURCE_HINT=null, MODEL_ID=Schema name=Test, nameInSource=null, uuid=tid:43c82bdfb6c1-0027b8b2-00000000, OUTPUT_COLS=[Test.TestTable.Id], EST_CARDINALITY=1.0, EST_COL_STATS={Test.TestTable.Id=[1.0, 1.0, 0.0]}, ATOMIC_REQUEST=SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ?})
============================================================================
CONVERTING PLAN TREE TO PROCESS TREE
PROCESS PLAN =
AccessNode(0) output=[Test.TestTable.Id] SELECT g_0.Id AS c_0 FROM Test.TestTable AS g_0 WHERE g_0.Id = 41 LIMIT ?, ?
============================================================================
----------------------------------------------------------------------------
OPTIMIZATION COMPLETE:
PROCESSOR PLAN:
AccessNode(0) output=[Test.TestTable.Id] SELECT g_0.Id AS c_0 FROM Test.TestTable AS g_0 WHERE g_0.Id = 41 LIMIT ?, ?
============================================================================
2021-03-04 14:57:32,080 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ProcessTree for ogTd/AG+4EeF.1 AccessNode(0) output=[Test.TestTable.Id] SELECT g_0.Id AS c_0 FROM Test.TestTable AS g_0 WHERE g_0.Id = 41 LIMIT ?, ?
2021-03-04 14:57:32,080 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Cache hint modified the query determinism from DETERMINISTIC to DETERMINISTIC
2021-03-04 14:57:32,080 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Adding to global/distributed cache Cache Entry<ogTd/AG+4EeF=c04bbd6b-865e-4702-9e4a-13fe528072d9> params:null sql:/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */
2021-03-04 14:57:32,080 TRACE [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF PLAN USER COMMAND: endTime=2021-03-04 14:57:32.08 requestID=ogTd/AG+4EeF.1 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 finalRowCount=null plan=AccessNode:
Relational Node ID: 0
Output Columns: Id (integer)
Cost Estimates: Estimated Node Cardinality: 1.0
Query: SELECT g_0.Id AS c_0 FROM Test.TestTable AS g_0 WHERE g_0.Id = 41 LIMIT ?, ?
Model Name: Test
2021-03-04 14:57:32,080 DEBUG [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Creating TupleBuffer: 43735 [Test.TestTable.Id] [class java.lang.Integer] batch size 2048 of type PROCESSOR
2021-03-04 14:57:32,080 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before resume:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,080 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after resume : null
2021-03-04 14:57:32,080 TRACE [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Reserving buffer space 114688 FORCE
2021-03-04 14:57:32,080 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Create State
2021-03-04 14:57:32,080 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 no cache directive
2021-03-04 14:57:32,081 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Processing NEW request: SELECT g_0.Id AS c_0 FROM Test.TestTable AS g_0 WHERE g_0.Id = 41 LIMIT 64
2021-03-04 14:57:32,081 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Obtained execution
2021-03-04 14:57:32,081 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF START DATA SRC COMMAND: startTime=2021-03-04 14:57:32.081 requestID=ogTd/AG+4EeF.1 sourceCommandID=0 executionID=16885 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 sql=SELECT g_0.Id AS c_0 FROM Test.TestTable AS g_0 WHERE g_0.Id = 41 LIMIT 64
2021-03-04 14:57:32,081 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF Source-specific command: SELECT TOP 64 g_0."Id" AS c_0 FROM "TestDB"."dbo"."TestTable" g_0 WHERE g_0."Id" = 41
2021-03-04 14:57:32,081 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF SOURCE SRC COMMAND: endTime=2021-03-04 14:57:32.081 requestID=ogTd/AG+4EeF.1 sourceCommandID=0 executionID=16885 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 sourceCommand=[SELECT TOP 64 g_0."Id" AS c_0 FROM "TestDB"."dbo"."TestTable" g_0 WHERE g_0."Id" = 41]
2021-03-04 14:57:32,082 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Executed command
2021-03-04 14:57:32,082 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Processing MORE request
2021-03-04 14:57:32,082 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Getting results from connector
2021-03-04 14:57:32,082 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Obtained last batch, total row count: 1
2021-03-04 14:57:32,082 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF closed atomic-request: ogTd/AG+4EeF.1.0.16885
2021-03-04 14:57:32,082 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Remove State
2021-03-04 14:57:32,083 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Processing Close : SELECT g_0.Id AS c_0 FROM Test.TestTable AS g_0 WHERE g_0.Id = 41 LIMIT 64
2021-03-04 14:57:32,083 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Closed execution
2021-03-04 14:57:32,083 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1.0.16885 Closed connection
2021-03-04 14:57:32,083 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF END SRC COMMAND: endTime=2021-03-04 14:57:32.083 requestID=ogTd/AG+4EeF.1 sourceCommandID=0 executionID=16885 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 finalRowCount=1 cpuTime(ns)=970930
2021-03-04 14:57:32,083 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF AccessNode(0) sending TupleBatch; beginning row=1, number of rows=1, lastBatch=1
1: [41]
2021-03-04 14:57:32,083 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF QueryProcessor: closing processor
2021-03-04 14:57:32,083 TRACE [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Releasing buffer space 114688
2021-03-04 14:57:32,083 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 Finished Processing
2021-03-04 14:57:32,083 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 Using cache hint /*+ cache(ttl:300000 scope:USER) */
2021-03-04 14:57:32,083 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Cache hint modified the query determinism from DETERMINISTIC to USER_DETERMINISTIC
2021-03-04 14:57:32,083 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Adding to global/distributed cache Cache Entry<ogTd/AG+4EeF=c04bbd6b-865e-4702-9e4a-13fe528072d9> params:[0, 64] sql:/*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */
2021-03-04 14:57:32,083 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,083 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,083 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF [RequestWorkItem.sendResultsIfNeeded] requestID: ogTd/AG+4EeF.1 resultsID: 43735 done: true
2021-03-04 14:57:32,084 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Sending results for ogTd/AG+4EeF.1 start row 1 end row 1 17 bytes
2021-03-04 14:57:32,084 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,084 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,084 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 end processing WORKING
2021-03-04 14:57:32,084 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after executeRequest : org.teiid.client.util.ResultsFuture@2778201b
2021-03-04 14:57:32,084 FINER [org.teiid.jdbc] (default task-1016) Creating ResultSet requestID: 1 beginRow: 1 resultsColumns: 1 parameters: 0
2021-03-04 14:57:32,084 FINE [org.teiid.jdbc] (default task-1016) Successfully executed a query /*+ cache(ttl:300000 scope:USER) */ SELECT Test.TestTable.Id FROM Test.TestTable WHERE Test.TestTable.Id = 41 LIMIT ?, ? /* ogTd/AG+4EeF */ and obtained results
2021-03-04 14:57:32,084 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before commit:org.teiid.dqp.internal.process.DQPCore@13f292()
2021-03-04 14:57:32,084 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Running task for parent thread null
2021-03-04 14:57:32,084 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before commit:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF)
2021-03-04 14:57:32,085 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after commit : null
2021-03-04 14:57:32,085 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after commit : org.teiid.client.util.ResultsFuture@4371df82
2021-03-04 14:57:32,085 FINE [org.teiid.jdbc] (default task-1016) Successfully committed transactions on all open statements on this connection.
2021-03-04 14:57:32,086 DEBUG [org.teiid.ODATA] (default task-1016) Teiid-Query: INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:32,086 FINER [org.teiid.jdbc] (default task-1016) Executing: requestID -1 commands: [INSERT INTO Test.TestTable (Id) VALUES (?)] expecting: UPDATECOUNT
2021-03-04 14:57:32,086 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before begin:org.teiid.dqp.internal.process.DQPCore@13f292()
2021-03-04 14:57:32,086 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before begin:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF)
2021-03-04 14:57:32,086 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after begin : ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider)
2021-03-04 14:57:32,086 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after begin : org.teiid.client.util.ResultsFuture@4f5cf296
2021-03-04 14:57:32,086 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before executeRequest:org.teiid.dqp.internal.process.DQPCore@13f292(2,org.teiid.client.RequestMessage@2c0e2248)
2021-03-04 14:57:32,087 DEBUG [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF START USER COMMAND: startTime=2021-03-04 14:57:32.086 requestID=ogTd/AG+4EeF.2 txID=null sessionID=ogTd/AG+4EeF applicationName=OData principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 sql=INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:32,087 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2 start processing MORE_WORK
2021-03-04 14:57:32,087 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Request Thread ogTd/AG+4EeF.2 with state NEW
2021-03-04 14:57:32,087 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2 Command has no cache hint and result set cache mode is not on.
2021-03-04 14:57:32,087 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2 executing prepared INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:32,087 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Cache hit for Cache Entry<ogTd/AG+4EeF=c04bbd6b-865e-4702-9e4a-13fe528072d9> params:null sql:INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:32,087 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Query exist in cache: INSERT INTO Test.TestTable (Id) VALUES (?)
2021-03-04 14:57:32,087 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before getOrCreateTransactionContext:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF)
2021-03-04 14:57:32,087 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after getOrCreateTransactionContext : ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider)
2021-03-04 14:57:32,087 TRACE [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF PLAN USER COMMAND: endTime=2021-03-04 14:57:32.087 requestID=ogTd/AG+4EeF.2 txID=null sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 finalRowCount=null plan=AccessNode:
Relational Node ID: 0
Output Columns: Count (integer)
Cost Estimates: Estimated Node Cardinality: 28.0
Query: INSERT INTO Test.TestTable (Id) VALUES (?)
Model Name: Test
2021-03-04 14:57:32,087 DEBUG [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Creating TupleBuffer: 43736 [Count] [class java.lang.Integer] batch size 2048 of type PROCESSOR
2021-03-04 14:57:32,087 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before resume:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,087 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after resume : null
2021-03-04 14:57:32,087 TRACE [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Reserving buffer space 114688 FORCE
2021-03-04 14:57:32,087 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2.0.16886 Create State
2021-03-04 14:57:32,087 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2.0.16886 Processing NEW request: INSERT INTO Test.TestTable (Id) VALUES (1)
2021-03-04 14:57:32,088 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2.0.16886 Obtained execution
2021-03-04 14:57:32,088 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF START DATA SRC COMMAND: startTime=2021-03-04 14:57:32.088 requestID=ogTd/AG+4EeF.2 sourceCommandID=0 executionID=16886 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 sql=INSERT INTO Test.TestTable (Id) VALUES (1)
2021-03-04 14:57:32,088 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF Source-specific command: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)
2021-03-04 14:57:32,088 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF SOURCE SRC COMMAND: endTime=2021-03-04 14:57:32.088 requestID=ogTd/AG+4EeF.2 sourceCommandID=0 executionID=16886 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 sourceCommand=[INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]
2021-03-04 14:57:32,090 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF ERROR SRC COMMAND: endTime=2021-03-04 14:57:32.09 requestID=ogTd/AG+4EeF.2 sourceCommandID=0 executionID=16886 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9
2021-03-04 14:57:32,090 WARN [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF Connector worker process failed for atomic-request=ogTd/AG+4EeF.2.0.16886: org.teiid.translator.jdbc.JDBCExecutionException: 2627 TEIID11013:TEIID11004 Error executing statement(s): [Prepared Values: [1] SQL: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]
at org.teiid.translator.jdbc.JDBCUpdateExecution.executeTranslatedCommand(JDBCUpdateExecution.java:325)
at org.teiid.translator.jdbc.JDBCUpdateExecution.execute(JDBCUpdateExecution.java:73)
at org.teiid.dqp.internal.datamgr.ConnectorWorkItem$1.execute(ConnectorWorkItem.java:443)
at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:402)
at sun.reflect.GeneratedMethodAccessor102.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.dqp.internal.datamgr.ConnectorManager$1.invoke(ConnectorManager.java:228)
at com.sun.proxy.$Proxy57.execute(Unknown Source)
at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:302)
at org.teiid.dqp.internal.process.DataTierTupleSource.nextTuple(DataTierTupleSource.java:138)
at org.teiid.query.processor.relational.AccessNode.nextBatchDirect(AccessNode.java:401)
at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:277)
at org.teiid.query.processor.relational.RelationalPlan.nextBatch(RelationalPlan.java:139)
at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:147)
at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:110)
at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:160)
at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:142)
at org.teiid.dqp.internal.process.RequestWorkItem.processMore(RequestWorkItem.java:492)
at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:362)
at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:43)
at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:285)
at org.teiid.dqp.internal.process.DQPCore.executeRequest(DQPCore.java:361)
at org.teiid.dqp.internal.process.DQPCore.executeRequest(DQPCore.java:276)
at sun.reflect.GeneratedMethodAccessor93.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.logging.LogManager$LoggingProxy.invoke(LogManager.java:119)
at org.teiid.transport.SessionCheckingProxy.invoke(SessionCheckingProxy.java:60)
at com.sun.proxy.$Proxy41.executeRequest(Unknown Source)
at sun.reflect.GeneratedMethodAccessor93.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.transport.LocalServerConnection$1$1.call(LocalServerConnection.java:212)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:281)
at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:265)
at org.teiid.transport.LocalServerConnection$1.invoke(LocalServerConnection.java:210)
at com.sun.proxy.$Proxy41.executeRequest(Unknown Source)
at org.teiid.jdbc.StatementImpl.execute(StatementImpl.java:748)
at org.teiid.jdbc.StatementImpl.executeSql(StatementImpl.java:578)
at org.teiid.jdbc.PreparedStatementImpl.executeUpdate(PreparedStatementImpl.java:319)
at org.teiid.olingo.service.LocalClient.executeUpdate(LocalClient.java:500)
at org.teiid.olingo.service.TeiidServiceHandler.performInsert(TeiidServiceHandler.java:390)
at org.teiid.olingo.service.TeiidServiceHandler.performDeepInsert(TeiidServiceHandler.java:412)
at org.teiid.olingo.service.TeiidServiceHandler.createEntity(TeiidServiceHandler.java:462)
at org.apache.olingo.server.core.requests.DataRequest$EntityRequest.execute(DataRequest.java:370)
at org.apache.olingo.server.core.requests.DataRequest.execute(DataRequest.java:247)
at org.apache.olingo.server.core.ServiceDispatcher.internalExecute(ServiceDispatcher.java:166)
at org.apache.olingo.server.core.ServiceDispatcher.execute(ServiceDispatcher.java:97)
at org.apache.olingo.server.core.requests.BatchRequest.executeSingleRequest(BatchRequest.java:157)
at org.apache.olingo.server.core.requests.BatchRequest.processChangeSet(BatchRequest.java:138)
at org.apache.olingo.server.core.requests.BatchRequest.execute(BatchRequest.java:82)
at org.apache.olingo.server.core.ServiceDispatcher.internalExecute(ServiceDispatcher.java:166)
at org.apache.olingo.server.core.ServiceDispatcher.execute(ServiceDispatcher.java:97)
at org.apache.olingo.server.core.OData4HttpHandler.process(OData4HttpHandler.java:67)
at org.teiid.olingo.web.ODataServlet.service(ODataServlet.java:39)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:791)
at io.undertow.servlet.handlers.ServletHandler.handleRequest(ServletHandler.java:74)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:129)
at org.teiid.olingo.web.ODataFilter.internalDoFilter(ODataFilter.java:287)
at org.teiid.olingo.web.ODataFilter.doFilter(ODataFilter.java:120)
at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
at org.teiid.oauth.keycloak.AuthFilter.doFilter(AuthFilter.java:46)
at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
at io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:84)
at io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62)
at io.undertow.servlet.handlers.ServletChain$1.handleRequest(ServletChain.java:68)
at io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36)
at org.wildfly.extension.undertow.security.SecurityContextAssociationHandler.handleRequest(SecurityContextAssociationHandler.java:78)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.keycloak.adapters.undertow.UndertowAuthenticatedActionsHandler.handleRequest(UndertowAuthenticatedActionsHandler.java:66)
at io.undertow.servlet.handlers.security.SSLInformationAssociationHandler.handleRequest(SSLInformationAssociationHandler.java:132)
at io.undertow.servlet.handlers.security.ServletAuthenticationCallHandler.handleRequest(ServletAuthenticationCallHandler.java:57)
at io.undertow.server.handlers.DisableCacheHandler.handleRequest(DisableCacheHandler.java:33)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at io.undertow.security.handlers.AuthenticationConstraintHandler.handleRequest(AuthenticationConstraintHandler.java:53)
at io.undertow.security.handlers.AbstractConfidentialityHandler.handleRequest(AbstractConfidentialityHandler.java:46)
at io.undertow.servlet.handlers.security.ServletConfidentialityConstraintHandler.handleRequest(ServletConfidentialityConstraintHandler.java:64)
at io.undertow.servlet.handlers.security.ServletSecurityConstraintHandler.handleRequest(ServletSecurityConstraintHandler.java:59)
at io.undertow.security.handlers.AuthenticationMechanismsHandler.handleRequest(AuthenticationMechanismsHandler.java:60)
at io.undertow.servlet.handlers.security.CachedAuthenticatedSessionHandler.handleRequest(CachedAuthenticatedSessionHandler.java:77)
at io.undertow.security.handlers.NotificationReceiverHandler.handleRequest(NotificationReceiverHandler.java:50)
at io.undertow.security.handlers.AbstractSecurityContextAssociationHandler.handleRequest(AbstractSecurityContextAssociationHandler.java:43)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.wildfly.extension.undertow.security.jacc.JACCContextIdHandler.handleRequest(JACCContextIdHandler.java:61)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.wildfly.extension.undertow.deployment.GlobalRequestControllerHandler.handleRequest(GlobalRequestControllerHandler.java:68)
at org.keycloak.adapters.undertow.ServletPreAuthActionsHandler.handleRequest(ServletPreAuthActionsHandler.java:69)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
at io.undertow.server.Connectors.executeRootHandler(Connectors.java:364)
at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:830)
at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1982)
at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1486)
at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1377)
at java.lang.Thread.run(Thread.java:748)
Caused by: com.microsoft.sqlserver.jdbc.SQLServerException: Verletzung der PRIMARY KEY-Einschr?nkung 'PK_TestTable'. Ein doppelter Schl?ssel kann in das dbo.TestTable-Objekt nicht eingef?gt werden.
at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDatabaseError(SQLServerException.java:262)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.getNextResult(SQLServerStatement.java:1624)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement.doExecutePreparedStatement(SQLServerPreparedStatement.java:594)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement$PrepStmtExecCmd.doExecute(SQLServerPreparedStatement.java:524)
at com.microsoft.sqlserver.jdbc.TDSCommand.execute(IOBuffer.java:7194)
at com.microsoft.sqlserver.jdbc.SQLServerConnection.executeCommand(SQLServerConnection.java:2979)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeCommand(SQLServerStatement.java:248)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeStatement(SQLServerStatement.java:223)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement.executeUpdate(SQLServerPreparedStatement.java:473)
at org.jboss.jca.adapters.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:537)
at org.teiid.translator.jdbc.JDBCUpdateExecution.executeTranslatedCommand(JDBCUpdateExecution.java:294)
... 112 more
2021-03-04 14:57:32,092 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF closed atomic-request: ogTd/AG+4EeF.2.0.16886
2021-03-04 14:57:32,092 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2.0.16886 Remove State
2021-03-04 14:57:32,092 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2.0.16886 Processing Close : INSERT INTO Test.TestTable (Id) VALUES (1)
2021-03-04 14:57:32,092 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2.0.16886 Closed execution
2021-03-04 14:57:32,092 DEBUG [org.teiid.CONNECTOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2.0.16886 Closed connection
2021-03-04 14:57:32,092 DEBUG [org.teiid.COMMAND_LOG.SOURCE] (default task-1016) ogTd/AG+4EeF END SRC COMMAND: endTime=2021-03-04 14:57:32.092 requestID=ogTd/AG+4EeF.2 sourceCommandID=0 executionID=16886 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) modelName=Test translatorName=sqlserver sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 finalRowCount=0 cpuTime(ns)=2846054
2021-03-04 14:57:32,092 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF QueryProcessor: closing processor
2021-03-04 14:57:32,092 TRACE [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Releasing buffer space 114688
2021-03-04 14:57:32,092 DEBUG [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF ERROR USER COMMAND: endTime=2021-03-04 14:57:32.092 requestID=ogTd/AG+4EeF.2 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 finalRowCount=null plan=AccessNode:
Relational Node ID: 0
Output Columns: Count (integer)
Statistics:
- Node Output Rows: 0
- Node Next Batch Process Time: 0
- Node Cumulative Next Batch Process Time: 0
- Node Cumulative Process Time: 0
- Node Next Batch Calls: 0
- Node Blocks: 0
Cost Estimates: Estimated Node Cardinality: 28.0
Query: INSERT INTO Test.TestTable (Id) VALUES (?)
Model Name: Test
2021-03-04 14:57:32,093 WARN [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF TEIID30020 Processing exception for request ogTd/AG+4EeF.2 'TEIID30504 TestDBDS: 2627 TEIID11013:TEIID11004 Error executing statement(s): [Prepared Values: [1] SQL: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]'. Originally TeiidProcessingException 'Verletzung der PRIMARY KEY-Einschr?nkung 'PK_TestTable'. Ein doppelter Schl?ssel kann in das dbo.TestTable-Objekt nicht eingef?gt werden.' SQLServerException.java:262.: org.teiid.core.TeiidProcessingException: TEIID30504 TestDBDS: 2627 TEIID11013:TEIID11004 Error executing statement(s): [Prepared Values: [1] SQL: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]
at org.teiid.dqp.internal.process.DataTierTupleSource.exceptionOccurred(DataTierTupleSource.java:397)
at org.teiid.dqp.internal.process.DataTierTupleSource.nextTuple(DataTierTupleSource.java:157)
at org.teiid.query.processor.relational.AccessNode.nextBatchDirect(AccessNode.java:401)
at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:277)
at org.teiid.query.processor.relational.RelationalPlan.nextBatch(RelationalPlan.java:139)
at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:147)
at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:110)
at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:160)
at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:142)
at org.teiid.dqp.internal.process.RequestWorkItem.processMore(RequestWorkItem.java:492)
at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:362)
at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:43)
at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:285)
at org.teiid.dqp.internal.process.DQPCore.executeRequest(DQPCore.java:361)
at org.teiid.dqp.internal.process.DQPCore.executeRequest(DQPCore.java:276)
at sun.reflect.GeneratedMethodAccessor93.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.logging.LogManager$LoggingProxy.invoke(LogManager.java:119)
at org.teiid.transport.SessionCheckingProxy.invoke(SessionCheckingProxy.java:60)
at com.sun.proxy.$Proxy41.executeRequest(Unknown Source)
at sun.reflect.GeneratedMethodAccessor93.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.transport.LocalServerConnection$1$1.call(LocalServerConnection.java:212)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:281)
at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:265)
at org.teiid.transport.LocalServerConnection$1.invoke(LocalServerConnection.java:210)
at com.sun.proxy.$Proxy41.executeRequest(Unknown Source)
at org.teiid.jdbc.StatementImpl.execute(StatementImpl.java:748)
at org.teiid.jdbc.StatementImpl.executeSql(StatementImpl.java:578)
at org.teiid.jdbc.PreparedStatementImpl.executeUpdate(PreparedStatementImpl.java:319)
at org.teiid.olingo.service.LocalClient.executeUpdate(LocalClient.java:500)
at org.teiid.olingo.service.TeiidServiceHandler.performInsert(TeiidServiceHandler.java:390)
at org.teiid.olingo.service.TeiidServiceHandler.performDeepInsert(TeiidServiceHandler.java:412)
at org.teiid.olingo.service.TeiidServiceHandler.createEntity(TeiidServiceHandler.java:462)
at org.apache.olingo.server.core.requests.DataRequest$EntityRequest.execute(DataRequest.java:370)
at org.apache.olingo.server.core.requests.DataRequest.execute(DataRequest.java:247)
at org.apache.olingo.server.core.ServiceDispatcher.internalExecute(ServiceDispatcher.java:166)
at org.apache.olingo.server.core.ServiceDispatcher.execute(ServiceDispatcher.java:97)
at org.apache.olingo.server.core.requests.BatchRequest.executeSingleRequest(BatchRequest.java:157)
at org.apache.olingo.server.core.requests.BatchRequest.processChangeSet(BatchRequest.java:138)
at org.apache.olingo.server.core.requests.BatchRequest.execute(BatchRequest.java:82)
at org.apache.olingo.server.core.ServiceDispatcher.internalExecute(ServiceDispatcher.java:166)
at org.apache.olingo.server.core.ServiceDispatcher.execute(ServiceDispatcher.java:97)
at org.apache.olingo.server.core.OData4HttpHandler.process(OData4HttpHandler.java:67)
at org.teiid.olingo.web.ODataServlet.service(ODataServlet.java:39)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:791)
at io.undertow.servlet.handlers.ServletHandler.handleRequest(ServletHandler.java:74)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:129)
at org.teiid.olingo.web.ODataFilter.internalDoFilter(ODataFilter.java:287)
at org.teiid.olingo.web.ODataFilter.doFilter(ODataFilter.java:120)
at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
at org.teiid.oauth.keycloak.AuthFilter.doFilter(AuthFilter.java:46)
at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
at io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:84)
at io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62)
at io.undertow.servlet.handlers.ServletChain$1.handleRequest(ServletChain.java:68)
at io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36)
at org.wildfly.extension.undertow.security.SecurityContextAssociationHandler.handleRequest(SecurityContextAssociationHandler.java:78)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.keycloak.adapters.undertow.UndertowAuthenticatedActionsHandler.handleRequest(UndertowAuthenticatedActionsHandler.java:66)
at io.undertow.servlet.handlers.security.SSLInformationAssociationHandler.handleRequest(SSLInformationAssociationHandler.java:132)
at io.undertow.servlet.handlers.security.ServletAuthenticationCallHandler.handleRequest(ServletAuthenticationCallHandler.java:57)
at io.undertow.server.handlers.DisableCacheHandler.handleRequest(DisableCacheHandler.java:33)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at io.undertow.security.handlers.AuthenticationConstraintHandler.handleRequest(AuthenticationConstraintHandler.java:53)
at io.undertow.security.handlers.AbstractConfidentialityHandler.handleRequest(AbstractConfidentialityHandler.java:46)
at io.undertow.servlet.handlers.security.ServletConfidentialityConstraintHandler.handleRequest(ServletConfidentialityConstraintHandler.java:64)
at io.undertow.servlet.handlers.security.ServletSecurityConstraintHandler.handleRequest(ServletSecurityConstraintHandler.java:59)
at io.undertow.security.handlers.AuthenticationMechanismsHandler.handleRequest(AuthenticationMechanismsHandler.java:60)
at io.undertow.servlet.handlers.security.CachedAuthenticatedSessionHandler.handleRequest(CachedAuthenticatedSessionHandler.java:77)
at io.undertow.security.handlers.NotificationReceiverHandler.handleRequest(NotificationReceiverHandler.java:50)
at io.undertow.security.handlers.AbstractSecurityContextAssociationHandler.handleRequest(AbstractSecurityContextAssociationHandler.java:43)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.wildfly.extension.undertow.security.jacc.JACCContextIdHandler.handleRequest(JACCContextIdHandler.java:61)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.wildfly.extension.undertow.deployment.GlobalRequestControllerHandler.handleRequest(GlobalRequestControllerHandler.java:68)
at org.keycloak.adapters.undertow.ServletPreAuthActionsHandler.handleRequest(ServletPreAuthActionsHandler.java:69)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
at io.undertow.server.Connectors.executeRootHandler(Connectors.java:364)
at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:830)
at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1982)
at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1486)
at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1377)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.teiid.translator.jdbc.JDBCExecutionException: 2627 TEIID11013:TEIID11004 Error executing statement(s): [Prepared Values: [1] SQL: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]
at org.teiid.translator.jdbc.JDBCUpdateExecution.executeTranslatedCommand(JDBCUpdateExecution.java:325)
at org.teiid.translator.jdbc.JDBCUpdateExecution.execute(JDBCUpdateExecution.java:73)
at org.teiid.dqp.internal.datamgr.ConnectorWorkItem$1.execute(ConnectorWorkItem.java:443)
at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:402)
at sun.reflect.GeneratedMethodAccessor102.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.dqp.internal.datamgr.ConnectorManager$1.invoke(ConnectorManager.java:228)
at com.sun.proxy.$Proxy57.execute(Unknown Source)
at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:302)
at org.teiid.dqp.internal.process.DataTierTupleSource.nextTuple(DataTierTupleSource.java:138)
... 102 more
Caused by: com.microsoft.sqlserver.jdbc.SQLServerException: Verletzung der PRIMARY KEY-Einschr?nkung 'PK_TestTable'. Ein doppelter Schl?ssel kann in das dbo.TestTable-Objekt nicht eingef?gt werden.
at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDatabaseError(SQLServerException.java:262)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.getNextResult(SQLServerStatement.java:1624)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement.doExecutePreparedStatement(SQLServerPreparedStatement.java:594)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement$PrepStmtExecCmd.doExecute(SQLServerPreparedStatement.java:524)
at com.microsoft.sqlserver.jdbc.TDSCommand.execute(IOBuffer.java:7194)
at com.microsoft.sqlserver.jdbc.SQLServerConnection.executeCommand(SQLServerConnection.java:2979)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeCommand(SQLServerStatement.java:248)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeStatement(SQLServerStatement.java:223)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement.executeUpdate(SQLServerPreparedStatement.java:473)
at org.jboss.jca.adapters.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:537)
at org.teiid.translator.jdbc.JDBCUpdateExecution.executeTranslatedCommand(JDBCUpdateExecution.java:294)
... 112 more
2021-03-04 14:57:32,094 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Removing tuplesource for the request ogTd/AG+4EeF.2
2021-03-04 14:57:32,094 DEBUG [org.teiid.BUFFER_MGR] (default task-1016) ogTd/AG+4EeF Removing TupleBuffer: 43736
2021-03-04 14:57:32,094 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,094 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,094 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2 Finished Processing
2021-03-04 14:57:32,094 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF org.teiid.core.TeiidProcessingException: TEIID30504 TestDBDS: 2627 TEIID11013:TEIID11004 Error executing statement(s): [Prepared Values: [1] SQL: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)] Sending error to client ogTd/AG+4EeF.2
2021-03-04 14:57:32,094 DEBUG [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF END USER COMMAND: endTime=2021-03-04 14:57:32.094 requestID=ogTd/AG+4EeF.2 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 finalRowCount=0 plan=AccessNode:
Relational Node ID: 0
Output Columns: Count (integer)
Statistics:
- Node Output Rows: 0
- Node Next Batch Process Time: 0
- Node Cumulative Next Batch Process Time: 0
- Node Cumulative Process Time: 0
- Node Next Batch Calls: 0
- Node Blocks: 0
Cost Estimates: Estimated Node Cardinality: 28.0
Query: INSERT INTO Test.TestTable (Id) VALUES (?)
Model Name: Test
2021-03-04 14:57:32,094 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:344c status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,094 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,094 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2 end processing WORKING
2021-03-04 14:57:32,094 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.2 done processing WORKING
2021-03-04 14:57:32,094 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after executeRequest : org.teiid.client.util.ResultsFuture@1530ccc5
2021-03-04 14:57:32,094 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before rollback:org.teiid.dqp.internal.process.DQPCore@13f292()
2021-03-04 14:57:32,095 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Running task for parent thread null
2021-03-04 14:57:32,095 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before rollback:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF)
2021-03-04 14:57:32,095 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after rollback : null
2021-03-04 14:57:32,095 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after rollback : org.teiid.client.util.ResultsFuture@74cb5292
2021-03-04 14:57:32,095 FINE [org.teiid.jdbc] (default task-1016) Successfully rolled back transactions on all open statements on this connection.
2021-03-04 14:57:32,095 WARN [org.teiid.ODATA] (default task-1016) TEIID16052 Unable to process odata request due to: TEIID30504 TestDBDS: 2627 TEIID11013:TEIID11004 Error executing statement(s): [Prepared Values: [1] SQL: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]: org.teiid.core.TeiidProcessingException: TEIID30504 TestDBDS: 2627 TEIID11013:TEIID11004 Error executing statement(s): [Prepared Values: [1] SQL: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]
at org.teiid.dqp.internal.process.DataTierTupleSource.exceptionOccurred(DataTierTupleSource.java:397)
at org.teiid.dqp.internal.process.DataTierTupleSource.nextTuple(DataTierTupleSource.java:157)
at org.teiid.query.processor.relational.AccessNode.nextBatchDirect(AccessNode.java:401)
at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:277)
at org.teiid.query.processor.relational.RelationalPlan.nextBatch(RelationalPlan.java:139)
at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:147)
at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:110)
at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:160)
at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:142)
at org.teiid.dqp.internal.process.RequestWorkItem.processMore(RequestWorkItem.java:492)
at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:362)
at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:43)
at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:285)
at org.teiid.dqp.internal.process.DQPCore.executeRequest(DQPCore.java:361)
at org.teiid.dqp.internal.process.DQPCore.executeRequest(DQPCore.java:276)
at sun.reflect.GeneratedMethodAccessor93.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.logging.LogManager$LoggingProxy.invoke(LogManager.java:119)
at org.teiid.transport.SessionCheckingProxy.invoke(SessionCheckingProxy.java:60)
at com.sun.proxy.$Proxy41.executeRequest(Unknown Source)
at sun.reflect.GeneratedMethodAccessor93.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.transport.LocalServerConnection$1$1.call(LocalServerConnection.java:212)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:281)
at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:265)
at org.teiid.transport.LocalServerConnection$1.invoke(LocalServerConnection.java:210)
at com.sun.proxy.$Proxy41.executeRequest(Unknown Source)
at org.teiid.jdbc.StatementImpl.execute(StatementImpl.java:748)
at org.teiid.jdbc.StatementImpl.executeSql(StatementImpl.java:578)
at org.teiid.jdbc.PreparedStatementImpl.executeUpdate(PreparedStatementImpl.java:319)
at org.teiid.olingo.service.LocalClient.executeUpdate(LocalClient.java:500)
at org.teiid.olingo.service.TeiidServiceHandler.performInsert(TeiidServiceHandler.java:390)
at org.teiid.olingo.service.TeiidServiceHandler.performDeepInsert(TeiidServiceHandler.java:412)
at org.teiid.olingo.service.TeiidServiceHandler.createEntity(TeiidServiceHandler.java:462)
at org.apache.olingo.server.core.requests.DataRequest$EntityRequest.execute(DataRequest.java:370)
at org.apache.olingo.server.core.requests.DataRequest.execute(DataRequest.java:247)
at org.apache.olingo.server.core.ServiceDispatcher.internalExecute(ServiceDispatcher.java:166)
at org.apache.olingo.server.core.ServiceDispatcher.execute(ServiceDispatcher.java:97)
at org.apache.olingo.server.core.requests.BatchRequest.executeSingleRequest(BatchRequest.java:157)
at org.apache.olingo.server.core.requests.BatchRequest.processChangeSet(BatchRequest.java:138)
at org.apache.olingo.server.core.requests.BatchRequest.execute(BatchRequest.java:82)
at org.apache.olingo.server.core.ServiceDispatcher.internalExecute(ServiceDispatcher.java:166)
at org.apache.olingo.server.core.ServiceDispatcher.execute(ServiceDispatcher.java:97)
at org.apache.olingo.server.core.OData4HttpHandler.process(OData4HttpHandler.java:67)
at org.teiid.olingo.web.ODataServlet.service(ODataServlet.java:39)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:791)
at io.undertow.servlet.handlers.ServletHandler.handleRequest(ServletHandler.java:74)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:129)
at org.teiid.olingo.web.ODataFilter.internalDoFilter(ODataFilter.java:287)
at org.teiid.olingo.web.ODataFilter.doFilter(ODataFilter.java:120)
at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
at org.teiid.oauth.keycloak.AuthFilter.doFilter(AuthFilter.java:46)
at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
at io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:84)
at io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62)
at io.undertow.servlet.handlers.ServletChain$1.handleRequest(ServletChain.java:68)
at io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36)
at org.wildfly.extension.undertow.security.SecurityContextAssociationHandler.handleRequest(SecurityContextAssociationHandler.java:78)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.keycloak.adapters.undertow.UndertowAuthenticatedActionsHandler.handleRequest(UndertowAuthenticatedActionsHandler.java:66)
at io.undertow.servlet.handlers.security.SSLInformationAssociationHandler.handleRequest(SSLInformationAssociationHandler.java:132)
at io.undertow.servlet.handlers.security.ServletAuthenticationCallHandler.handleRequest(ServletAuthenticationCallHandler.java:57)
at io.undertow.server.handlers.DisableCacheHandler.handleRequest(DisableCacheHandler.java:33)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at io.undertow.security.handlers.AuthenticationConstraintHandler.handleRequest(AuthenticationConstraintHandler.java:53)
at io.undertow.security.handlers.AbstractConfidentialityHandler.handleRequest(AbstractConfidentialityHandler.java:46)
at io.undertow.servlet.handlers.security.ServletConfidentialityConstraintHandler.handleRequest(ServletConfidentialityConstraintHandler.java:64)
at io.undertow.servlet.handlers.security.ServletSecurityConstraintHandler.handleRequest(ServletSecurityConstraintHandler.java:59)
at io.undertow.security.handlers.AuthenticationMechanismsHandler.handleRequest(AuthenticationMechanismsHandler.java:60)
at io.undertow.servlet.handlers.security.CachedAuthenticatedSessionHandler.handleRequest(CachedAuthenticatedSessionHandler.java:77)
at io.undertow.security.handlers.NotificationReceiverHandler.handleRequest(NotificationReceiverHandler.java:50)
at io.undertow.security.handlers.AbstractSecurityContextAssociationHandler.handleRequest(AbstractSecurityContextAssociationHandler.java:43)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.wildfly.extension.undertow.security.jacc.JACCContextIdHandler.handleRequest(JACCContextIdHandler.java:61)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at org.wildfly.extension.undertow.deployment.GlobalRequestControllerHandler.handleRequest(GlobalRequestControllerHandler.java:68)
at org.keycloak.adapters.undertow.ServletPreAuthActionsHandler.handleRequest(ServletPreAuthActionsHandler.java:69)
at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
at io.undertow.server.Connectors.executeRootHandler(Connectors.java:364)
at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:830)
at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1982)
at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1486)
at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1377)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.teiid.translator.jdbc.JDBCExecutionException: 2627 TEIID11013:TEIID11004 Error executing statement(s): [Prepared Values: [1] SQL: INSERT INTO "TestDB"."dbo"."TestTable" ("Id") VALUES (?)]
at org.teiid.translator.jdbc.JDBCUpdateExecution.executeTranslatedCommand(JDBCUpdateExecution.java:325)
at org.teiid.translator.jdbc.JDBCUpdateExecution.execute(JDBCUpdateExecution.java:73)
at org.teiid.dqp.internal.datamgr.ConnectorWorkItem$1.execute(ConnectorWorkItem.java:443)
at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:402)
at sun.reflect.GeneratedMethodAccessor102.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.teiid.dqp.internal.datamgr.ConnectorManager$1.invoke(ConnectorManager.java:228)
at com.sun.proxy.$Proxy57.execute(Unknown Source)
at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:302)
at org.teiid.dqp.internal.process.DataTierTupleSource.nextTuple(DataTierTupleSource.java:138)
... 102 more
Caused by: com.microsoft.sqlserver.jdbc.SQLServerException: Verletzung der PRIMARY KEY-Einschr?nkung 'PK_TestTable'. Ein doppelter Schl?ssel kann in das dbo.TestTable-Objekt nicht eingef?gt werden.
at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDatabaseError(SQLServerException.java:262)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.getNextResult(SQLServerStatement.java:1624)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement.doExecutePreparedStatement(SQLServerPreparedStatement.java:594)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement$PrepStmtExecCmd.doExecute(SQLServerPreparedStatement.java:524)
at com.microsoft.sqlserver.jdbc.TDSCommand.execute(IOBuffer.java:7194)
at com.microsoft.sqlserver.jdbc.SQLServerConnection.executeCommand(SQLServerConnection.java:2979)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeCommand(SQLServerStatement.java:248)
at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeStatement(SQLServerStatement.java:223)
at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement.executeUpdate(SQLServerPreparedStatement.java:473)
at org.jboss.jca.adapters.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:537)
at org.teiid.translator.jdbc.JDBCUpdateExecution.executeTranslatedCommand(JDBCUpdateExecution.java:294)
... 112 more
2021-03-04 14:57:32,097 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF before closeRequest:org.teiid.dqp.internal.process.DQPCore@13f292(1)
2021-03-04 14:57:32,097 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF closeQuery for requestID=ogTd/AG+4EeF.1
2021-03-04 14:57:32,097 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 more work IDLE
2021-03-04 14:57:32,097 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Restarting processing using the calling thread ogTd/AG+4EeF.1
2021-03-04 14:57:32,097 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 start processing MORE_WORK
2021-03-04 14:57:32,097 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Request Thread ogTd/AG+4EeF.1 with state CLOSE
2021-03-04 14:57:32,097 DEBUG [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF Removing tuplesource for the request ogTd/AG+4EeF.1
2021-03-04 14:57:32,097 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.COMMITTED >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,097 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,097 DEBUG [org.teiid.COMMAND_LOG] (default task-1016) ogTd/AG+4EeF END USER COMMAND: endTime=2021-03-04 14:57:32.097 requestID=ogTd/AG+4EeF.1 txID=Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.COMMITTED >, owner=Local transaction context for provider JBoss JTA transaction provider)sessionID=ogTd/AG+4EeF principal=c04bbd6b-865e-4702-9e4a-13fe528072d9 vdbName=Test vdbVersion=1 finalRowCount=1 plan=AccessNode:
Relational Node ID: 0
Output Columns: Id (integer)
Statistics:
- Node Output Rows: 1
- Node Next Batch Process Time: 3
- Node Cumulative Next Batch Process Time: 3
- Node Cumulative Process Time: 3
- Node Next Batch Calls: 1
- Node Blocks: 0
Cost Estimates: Estimated Node Cardinality: 1.0
Query: SELECT g_0.Id AS c_0 FROM Test.TestTable AS g_0 WHERE g_0.Id = 41 LIMIT ?, ?
Model Name: Test
2021-03-04 14:57:32,097 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before suspend:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF LOCAL ID:Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff7f000101:-e872b14:603e4fcb:3447 status: ActionStatus.COMMITTED >, owner=Local transaction context for provider JBoss JTA transaction provider))
2021-03-04 14:57:32,097 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after suspend : null
2021-03-04 14:57:32,097 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 end processing WORKING
2021-03-04 14:57:32,097 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF ogTd/AG+4EeF.1 done processing WORKING
2021-03-04 14:57:32,097 TRACE [org.teiid.PROCESSOR] (default task-1016) ogTd/AG+4EeF after closeRequest : org.teiid.client.util.ResultsFuture@4f5cf296
2021-03-04 14:57:32,097 FINE [org.teiid.jdbc] (default task-1016) Statement closed successfully.
2021-03-04 14:57:32,097 FINE [org.teiid.jdbc] (default task-1016) Statement closed successfully.
2021-03-04 14:57:32,097 DEBUG [org.teiid.SECURITY] (default task-1016) ogTd/AG+4EeF closeSession ogTd/AG+4EeF
2021-03-04 14:57:32,097 DEBUG [org.teiid.AUDIT_LOG] (default task-1016) ogTd/AG+4EeF [c04bbd6b-865e-4702-9e4a-13fe528072d9] <session.logoff>
2021-03-04 14:57:32,097 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF before cancelTransactions:org.teiid.dqp.internal.process.TransactionServerImpl@4e607583(ogTd/AG+4EeF,false)
2021-03-04 14:57:32,097 DEBUG [org.teiid.TXN_LOG] (default task-1016) ogTd/AG+4EeF after cancelTransactions : null
2021-03-04 14:57:32,097 FINE [org.teiid.jdbc] (default task-1016) Connection successfully closed.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment