| 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