[
https://issues.apache.org/jira/browse/IGNITE-16249?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Amelchev Nikita updated IGNITE-16249:
-------------------------------------
Ignite Flags: Release Notes Required (was: Docs Required,Release Notes
Required)
> Some SQL query logs should be WARN instead of ERROR.
> ----------------------------------------------------
>
> Key: IGNITE-16249
> URL: https://issues.apache.org/jira/browse/IGNITE-16249
> Project: Ignite
> Issue Type: Improvement
> Components: sql
> Reporter: Andrey Mashenkov
> Assignee: Andrey Mashenkov
> Priority: Major
> Fix For: 2.13
>
> Time Spent: 20m
> Remaining Estimate: 0h
>
> User experience a lot of messages logged at error level, however, they must
> be logged at warn level. Because the failure is recoverable and relates to a
> particular user request.
>
> {noformat}
> [2021-12-01T14:29:49,635][ERROR][client-connector-#102%nebula-node%][ClientListenerNioListener]
> Failed to process client request
> [req=o.a.i.i.processors.platform.client.cache.ClientCacheSqlFieldsQueryRequest@6d1b5aac]
> org.apache.ignite.transactions.TransactionDuplicateKeyException: Duplicate
> key during INSERT [key=]
> at
> org.apache.ignite.internal.processors.query.h2.dml.DmlUtils.dmlDoInsert(DmlUtils.java:206)
>
> at
> org.apache.ignite.internal.processors.query.h2.dml.DmlUtils.processSelectResult(DmlUtils.java:169)
>
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.executeUpdateNonTransactional(IgniteH2Indexing.java:3190)
>
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.executeUpdate(IgniteH2Indexing.java:3034)
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.executeUpdateDistributed(IgniteH2Indexing.java:2961)
>
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.executeDml(IgniteH2Indexing.java:1310)
> ......
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2794)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2750)
>
> at
> org.apache.ignite.internal.processors.platform.client.cache.ClientCacheSqlFieldsQueryRequest.process(ClientCacheSqlFieldsQueryRequest.java:147)
> .....{noformat}
> {noformat}
> [15:38:19,355][SEVERE][client-connector-#124][ClientListenerNioListener]
> Failed to process client request
> [req=o.a.i.i.processors.platform.client.cache.ClientCacheSqlFieldsQueryRequest@18a76b4]
> class org.apache.ignite.internal.processors.query.IgniteSQLException: Failed
> to parse query. Column "TBL.COL1" not found; SQL statement:
> select * from TBL where COL1 =.... [42122-197]
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.prepareStatementAndCaches(IgniteH2Indexing.java:2375)
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.parseAndSplit(IgniteH2Indexing.java:2076)
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.querySqlFields(IgniteH2Indexing.java:1895)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor$3.applyx(GridQueryProcessor.java:2223)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor$3.applyx(GridQueryProcessor.java:2218)
> at
> org.apache.ignite.internal.util.lang.IgniteOutClosureX.apply(IgniteOutClosureX.java:35)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor.executeQuery(GridQueryProcessor.java:2725)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor.lambda$querySqlFields$0(GridQueryProcessor.java:2240)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor.executeQuerySafe(GridQueryProcessor.java:2260)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2211)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2137)
> at
> org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2093)
> at
> org.apache.ignite.internal.processors.platform.client.cache.ClientCacheSqlFieldsQueryRequest.process(ClientCacheSqlFieldsQueryRequest.java:106)
> at
> org.apache.ignite.internal.processors.platform.client.ClientRequestHandler.handle(ClientRequestHandler.java:51)
> at
> org.apache.ignite.internal.processors.odbc.ClientListenerNioListener.onMessage(ClientListenerNioListener.java:183)
> ......
> Caused by: org.h2.jdbc.JdbcSQLException: Column "TBL.COL1" not found; SQL
> statement:
> select * from TBL where COL1 =....
> at
> org.h2.message.DbException.getJdbcSQLException(DbException.java:357)
> at org.h2.message.DbException.get(DbException.java:179)
> at org.h2.message.DbException.get(DbException.java:155)
> at
> org.h2.expression.ExpressionColumn.optimize(ExpressionColumn.java:150)
> at org.h2.command.dml.Select.prepare(Select.java:858)
> at org.h2.command.Parser.prepareCommand(Parser.java:283)
> at org.h2.engine.Session.prepareLocal(Session.java:611)
> at org.h2.engine.Session.prepareCommand(Session.java:549)
> at org.h2.jdbc.JdbcConnection.prepareCommand(JdbcConnection.java:1247)
> at
> org.h2.jdbc.JdbcPreparedStatement.<init>(JdbcPreparedStatement.java:76)
> at
> org.h2.jdbc.JdbcConnection.prepareStatement(JdbcConnection.java:695)
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.prepare0(IgniteH2Indexing.java:410)
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.prepareStatement(IgniteH2Indexing.java:380)
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.prepareStatement(IgniteH2Indexing.java:347)
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.prepareStatementAndCaches(IgniteH2Indexing.java:2357)
> ... 23 more{noformat}
>
> {noformat}
> 2021-07-23 00:44:32,000[ERROR][query-#309%pool%][GridMapQueryExecutor] Failed
> to execute local query.
> org.apache.ignite.internal.processors.query.IgniteSQLException: Failed to set
> schema for DB connection for thread [schema=QUERY_CACHE]
> at
> org.apache.ignite.internal.processors.query.h2.H2Connection.schema(H2Connection.java:81)
> at
> org.apache.ignite.internal.processors.query.h2.H2PooledConnection.schema(H2PooledConnection.java:60)
>
> at
> org.apache.ignite.internal.processors.query.h2.ConnectionManager.connection(ConnectionManager.java:243)
>
> at
> org.apache.ignite.internal.processors.query.h2.twostep.GridMapQueryExecutor.onQueryRequest0(GridMapQueryExecutor.java:425)
>
> at
> org.apache.ignite.internal.processors.query.h2.twostep.GridMapQueryExecutor.onQueryRequest(GridMapQueryExecutor.java:269)
> at
> org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.onMessage(IgniteH2Indexing.java:2395)
>
> .....
> Caused by: org.gridgain.internal.h2.jdbc.JdbcSQLSyntaxErrorException: Schema
> "QUERY_CACHE" not found [90079-199]
> at
> org.gridgain.internal.h2.message.DbException.getJdbcSQLException(DbException.java:574)
> at
> org.gridgain.internal.h2.message.DbException.getJdbcSQLException(DbException.java:427)
>
> at org.gridgain.internal.h2.message.DbException.get(DbException.java:205)
> at org.gridgain.internal.h2.message.DbException.get(DbException.java:181)
> at org.gridgain.internal.h2.engine.Database.getSchema(Database.java:2015)
> at
> org.gridgain.internal.h2.engine.Session.setCurrentSchemaName(Session.java:1430)
>
> at
> org.gridgain.internal.h2.jdbc.JdbcConnection.setSchema(JdbcConnection.java:2013)
> at
> org.apache.ignite.internal.processors.query.h2.H2Connection.schema(H2Connection.java:78)
>
> ... 15 more{noformat}
>
>
--
This message was sent by Atlassian Jira
(v8.20.1#820001)
