Created
March 5, 2021 15:49
-
-
Save mbankdmt/ec1465e22f71c00dab6db13483da66c1 to your computer and use it in GitHub Desktop.
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| 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