[ https://issues.apache.org/jira/browse/IGNITE-16249?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Andrey Mashenkov reassigned IGNITE-16249: ----------------------------------------- Assignee: Andrey Mashenkov > 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 > > 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)