Andrey Mashenkov created IGNITE-16249:
-----------------------------------------
Summary: 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
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)
