[clickhouse-handler] print error stacktrace in clickhouse-handler by xuyifangreeneyes · Pull Request #1525 · databendlabs/databend

xuyifangreeneyes · 2021-08-19T16:00:21Z

I hereby agree to the terms of the CLA available at: https://datafuse.rs/policies/cla/

Summary

Summary about this PR

Log error in datafuse_query::servers::clickhouse::writers::query_writer::QueryWriter::write_error so we can see error stacktrace in datafuse-query's log.
Add query string into error message when datafuse_query::servers::clickhouse::writers::query_writer::QueryWriter::write returns an error. Similar to Edit log query string when mysql_session error #1353.

Changelog

Improvement

Related Issues

Fixes #1329

Test Plan

Manual test

databend-bot · 2021-08-19T16:00:24Z

Thanks for the contribution!
I have applied any labels matching special text in your PR Changelog.

Please review the labels and make any necessary changes.

databend-bot · 2021-08-19T16:00:25Z

Hello @xuyifangreeneyes, 🎉 Thank you for opening the pull request! 🎉
Your pull request state is not in Draft, please add Reviewers or Re-request review again!
FuseQuery: @BohuTANG @sundy-li @zhang2014
FuseStore: @drmingdrmer @dantengsky
Or visit datafuse roadmap for some clues.

xuyifangreeneyes · 2021-08-19T16:10:16Z

After the PR:

clickhouse-client output:

./clickhouse-client  --host 0.0.0.0 --port 9001
ClickHouse client version 21.9.1.1.
Connecting to 0.0.0.0:9001 as user default.
Connected to datafuse server version 2021.5.0 revision 54405.

ClickHouse client version is older than ClickHouse server. It may lack support for new features.

datafuse :) select number1 + 3 from  numbers(100);

SELECT number1 + 3
FROM numbers(100)

Query id: cb5de8b3-65b5-4557-8cdf-f92e2f41fa3b

Connecting to 0.0.0.0:9001 as user default.
Connected to datafuse server version 2021.5.0 revision 54405.

ClickHouse client version is older than ClickHouse server. It may lack support for new features.


0 rows in set. Elapsed: 0.076 sec.

Received exception from server (version 2021.5.0):
Code: 6. DB::Exception: Received from 0.0.0.0:9001. DB:Exception. Unable to get field named "number1". Valid fields: ["number"]. (CANNOT_PARSE_TEXT)

datafuse-query output:

 QUERY_LOG_LEVEL="DEBUG" ./target/debug/datafuse-query -c scripts/deploy/config/datafuse-query-node-1.toml
[2021-08-19T16:02:40Z INFO  datafuse_query] Config { log_level: "DEBUG", log_dir: "./_logs", num_cpus: 8, mysql_handler_host: "0.0.0.0", mysql_handler_port: 3307, max_active_sessions: 256, clickhouse_handler_host: "0.0.0.0", clickhouse_handler_port: 9001, flight_api_address: "0.0.0.0:9091", http_api_address: "0.0.0.0:8081", metric_api_address: "0.0.0.0:7071", store_api_address: "127.0.0.1:9191", store_api_username: ******, store_api_password: ******, config_file: "scripts/deploy/config/datafuse-query-node-1.toml", api_tls_server_cert: "", api_tls_server_key: "", rpc_tls_server_cert: "", rpc_tls_server_key: "key for rpc server cert", rpc_tls_query_server_root_ca_cert: "", rpc_tls_query_service_domain_name: "localhost", rpc_tls_store_server_root_ca_cert: "", rpc_tls_store_service_domain_name: "localhost", disable_remote_catalog: false }
[2021-08-19T16:02:40Z INFO  datafuse_query] DatafuseQuery v-0.1.0-4534bdf-simd(1.56.0-nightly-2021-08-19T15:46:50.636231+00:00)
[2021-08-19T16:02:40Z INFO  datafuse_query] MySQL handler listening on 0.0.0.0:3307, Usage: mysql -h0.0.0.0 -P3307
[2021-08-19T16:02:40Z INFO  datafuse_query] ClickHouse handler listening on 0.0.0.0:9001, Usage: clickhouse-client --host 0.0.0.0 --port 9001
[2021-08-19T16:02:40Z INFO  datafuse_query] Metric API server listening on 0.0.0.0:7071
[2021-08-19T16:02:40Z WARN  datafuse_query::api::http_service] Http API TLS not set
[2021-08-19T16:02:40Z INFO  datafuse_query] HTTP API server listening on 0.0.0.0:8081
[2021-08-19T16:02:40Z INFO  datafuse_query] RPC API server listening on 0.0.0.0:9091
[2021-08-19T16:02:40Z INFO  datafuse_query] Ready for connections.
[2021-08-19T16:02:53Z INFO  datafuse_query::servers::clickhouse::clickhouse_handler] ClickHouse connection coming: Ok(127.0.0.1:64315)
[2021-08-19T16:02:53Z DEBUG clickhouse_srv] Handle New session
[2021-08-19T16:02:53Z INFO  datafuse_query::sessions::context] Create DatafuseQueryContext
[2021-08-19T16:02:53Z DEBUG datafuse_query::servers::clickhouse::interactive_worker_base] SELECT message FROM system.warnings
Aug 20 00:02:53.273 DEBUG datafuse_query::sql::plan_parser: query="SELECT message FROM system.warnings"
[2021-08-19T16:02:53Z DEBUG sqlparser::parser] parsing expr
[2021-08-19T16:02:53Z DEBUG sqlparser::parser] prefix: Identifier(Ident { value: "message", quote_style: None })
[2021-08-19T16:02:53Z DEBUG sqlparser::parser] get_next_precedence() Word(Word { value: "FROM", quote_style: None, keyword: FROM })
[2021-08-19T16:02:53Z DEBUG sqlparser::parser] next precedence: 0
[2021-08-19T16:02:55Z ERROR datafuse_query::servers::clickhouse::writers::query_writer] OnQuery Error: Code: 25, displayText = Unknown table: 'warnings'.

       0: backtrace::backtrace::libunwind::trace
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/backtrace/libunwind.rs:90:5
          backtrace::backtrace::trace_unsynchronized
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/backtrace/mod.rs:66:5
       1: backtrace::backtrace::trace
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/backtrace/mod.rs:53:14
       2: backtrace::capture::Backtrace::create
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/capture.rs:176:9
       3: backtrace::capture::Backtrace::new
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/capture.rs:140:22
       4: common_exception::exception::ErrorCode::UnknownTable
                 at common/exception/src/exception.rs:108:77
       5: <datafuse_query::datasources::system::system_database::SystemDatabase as datafuse_query::datasources::database::Database>::get_table::{{closure}}
                 at query/src/datasources/system/system_database.rs:123:17
       6: core::option::Option<T>::ok_or_else
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/option.rs:942:25
       7: <datafuse_query::datasources::system::system_database::SystemDatabase as datafuse_query::datasources::database::Database>::get_table
                 at query/src/datasources/system/system_database.rs:122:13
       8: <datafuse_query::catalogs::impls::database_catalog::DatabaseCatalog as datafuse_query::catalogs::catalog::Catalog>::get_table
                 at query/src/catalogs/impls/database_catalog.rs:164:21
       9: datafuse_query::sessions::context::DatafuseQueryContext::get_table
                 at query/src/sessions/context.rs:154:9
      10: datafuse_query::sql::plan_parser::PlanParser::create_relation
                 at query/src/sql/plan_parser.rs:740:38
      11: datafuse_query::sql::plan_parser::PlanParser::plan_table_with_joins
                 at query/src/sql/plan_parser.rs:698:9
      12: datafuse_query::sql::plan_parser::PlanParser::plan_tables_with_joins
                 at query/src/sql/plan_parser.rs:654:18
      13: datafuse_query::sql::plan_parser::PlanParser::select_to_plan
                 at query/src/sql/plan_parser.rs:472:20
      14: datafuse_query::sql::plan_parser::PlanParser::query_to_plan
                 at query/src/sql/plan_parser.rs:450:17
      15: datafuse_query::sql::plan_parser::PlanParser::sql_statement_to_plan
                 at query/src/sql/plan_parser.rs:156:40
      16: datafuse_query::sql::plan_parser::PlanParser::statement_to_plan
                 at query/src/sql/plan_parser.rs:121:42
      17: datafuse_query::sql::plan_parser::PlanParser::build_from_sql::{{closure}}::{{closure}}
                 at query/src/sql/plan_parser.rs:97:34
      18: core::option::Option<T>::map
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/option.rs:836:29
      19: datafuse_query::sql::plan_parser::PlanParser::build_from_sql::{{closure}}
                 at query/src/sql/plan_parser.rs:95:13
      20: core::result::Result<T,E>::and_then
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/result.rs:966:22
      21: datafuse_query::sql::plan_parser::PlanParser::build_from_sql
                 at query/src/sql/plan_parser.rs:94:9
      22: datafuse_query::servers::clickhouse::interactive_worker_base::InteractiveWorkerBase::do_query::{{closure}}
                 at query/src/servers/clickhouse/interactive_worker_base.rs:59:20
      23: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      24: <datafuse_query::servers::clickhouse::interactive_worker::InteractiveWorker as clickhouse_srv::ClickHouseSession>::execute_query::{{closure}}
                 at query/src/servers/clickhouse/interactive_worker.rs:52:48
      25: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      26: <core::pin::Pin<P> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/future.rs:119:9
      27: clickhouse_srv::cmd::Cmd::apply::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/clickhouse-srv-0.3.1/src/cmd.rs:53:17
      28: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      29: clickhouse_srv::ClickHouseServer::run::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/clickhouse-srv-0.3.1/src/lib.rs:155:13
      30: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      31: clickhouse_srv::ClickHouseServer::run_on::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/clickhouse-srv-0.3.1/src/lib.rs:125:9
      32: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      33: clickhouse_srv::ClickHouseServer::run_on_stream::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/clickhouse-srv-0.3.1/src/lib.rs:118:9
      34: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      35: datafuse_query::servers::clickhouse::clickhouse_session::ClickHouseConnection::run_on_stream::{{closure}}::{{closure}}
                 at query/src/servers/clickhouse/clickhouse_session.rs:39:17
      36: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      37: tokio::runtime::task::core::CoreStage<T>::poll::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/core.rs:161:17
      38: tokio::loom::std::unsafe_cell::UnsafeCell<T>::with_mut
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/loom/std/unsafe_cell.rs:14:9
      39: tokio::runtime::task::core::CoreStage<T>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/core.rs:151:13
      40: tokio::runtime::task::harness::poll_future::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:461:19
      41: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/panic/unwind_safe.rs:271:9
      42: std::panicking::try::do_call
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:401:40
      43: <unknown>
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:429:6
      44: std::panicking::try
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:365:19
      45: std::panic::catch_unwind
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panic.rs:129:14
      46: tokio::runtime::task::harness::poll_future
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:449:18
      47: tokio::runtime::task::harness::Harness<T,S>::poll_inner
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:98:27
      48: tokio::runtime::task::harness::Harness<T,S>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:53:15
      49: tokio::runtime::task::raw::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/raw.rs:113:5
      50: tokio::runtime::task::raw::RawTask::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/raw.rs:70:18
      51: tokio::runtime::task::LocalNotified<S>::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/mod.rs:343:9
      52: tokio::runtime::thread_pool::worker::Context::run_task::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:398:13
      53: tokio::coop::with_budget::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/coop.rs:106:9
      54: std::thread::local::LocalKey<T>::try_with
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/thread/local.rs:399:16
      55: std::thread::local::LocalKey<T>::with
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/thread/local.rs:375:9
      56: tokio::coop::with_budget
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/coop.rs:99:5
          tokio::coop::budget
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/coop.rs:76:5
          tokio::runtime::thread_pool::worker::Context::run_task
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:397:9
      57: tokio::runtime::thread_pool::worker::Context::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:365:24
      58: tokio::runtime::thread_pool::worker::run::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:350:17
      59: tokio::macros::scoped_tls::ScopedKey<T>::set
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/macros/scoped_tls.rs:61:9
      60: tokio::runtime::thread_pool::worker::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:347:5
      61: tokio::runtime::thread_pool::worker::Launch::launch::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:326:45
      62: <tokio::runtime::blocking::task::BlockingTask<T> as core::future::future::Future>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/blocking/task.rs:42:21
      63: tokio::runtime::task::core::CoreStage<T>::poll::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/core.rs:161:17
      64: tokio::loom::std::unsafe_cell::UnsafeCell<T>::with_mut
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/loom/std/unsafe_cell.rs:14:9
      65: tokio::runtime::task::core::CoreStage<T>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/core.rs:151:13
      66: tokio::runtime::task::harness::poll_future::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:461:19
      67: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/panic/unwind_safe.rs:271:9
      68: std::panicking::try::do_call
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:401:40
      69: <unknown>
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:436:2
      70: std::panicking::try
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:365:19
      71: std::panic::catch_unwind
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panic.rs:129:14
      72: tokio::runtime::task::harness::poll_future
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:449:18
      73: tokio::runtime::task::harness::Harness<T,S>::poll_inner
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:98:27
      74: tokio::runtime::task::harness::Harness<T,S>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:53:15
      75: tokio::runtime::task::raw::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/raw.rs:113:5
      76: tokio::runtime::task::raw::RawTask::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/raw.rs:70:18
      77: tokio::runtime::task::UnownedTask<S>::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/mod.rs:373:9
      78: tokio::runtime::blocking::pool::Inner::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/blocking/pool.rs:265:17
      79: tokio::runtime::blocking::pool::Spawner::spawn_thread::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/blocking/pool.rs:245:17
      80: std::sys_common::backtrace::__rust_begin_short_backtrace
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/sys_common/backtrace.rs:125:18
      81: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/thread/mod.rs:476:17
      82: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/panic/unwind_safe.rs:271:9
      83: std::panicking::try::do_call
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:401:40
      84: <unknown>
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:436:2
      85: std::panicking::try
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:365:19
      86: std::panic::catch_unwind
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panic.rs:129:14
      87: std::thread::Builder::spawn_unchecked::{{closure}}
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/thread/mod.rs:475:30
      88: core::ops::function::FnOnce::call_once{{vtable.shim}}
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/ops/function.rs:227:5
      89: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/alloc/src/boxed.rs:1572:9
          <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/alloc/src/boxed.rs:1572:9
          std::sys::unix::thread::Thread::new::thread_start
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/sys/unix/thread.rs:91:17
      90: __pthread_start

[2021-08-19T16:02:55Z INFO  datafuse_query::sessions::context] Destroy DatafuseQueryContext
[2021-08-19T16:03:05Z DEBUG datafuse_query::sessions::session_ref] Destroy session 8346b1ad-c217-4352-865f-e2291bd32803
[2021-08-19T16:03:05Z INFO  datafuse_query::servers::clickhouse::clickhouse_handler] ClickHouse connection coming: Ok(127.0.0.1:64372)
[2021-08-19T16:03:05Z DEBUG clickhouse_srv] Handle New session
[2021-08-19T16:03:05Z INFO  datafuse_query::sessions::context] Create DatafuseQueryContext
[2021-08-19T16:03:05Z DEBUG datafuse_query::servers::clickhouse::interactive_worker_base] select number1 + 3 from  numbers(100);
Aug 20 00:03:05.981 DEBUG datafuse_query::sql::plan_parser: query="select number1 + 3 from  numbers(100);"
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] parsing expr
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] prefix: Identifier(Ident { value: "number1", quote_style: None })
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] get_next_precedence() Plus
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] next precedence: 30
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] parsing expr
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] prefix: Value(Number("3", false))
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] get_next_precedence() Word(Word { value: "from", quote_style: None, keyword: FROM })
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] next precedence: 0
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] get_next_precedence() Word(Word { value: "from", quote_style: None, keyword: FROM })
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] next precedence: 0
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] parsing expr
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] prefix: Value(Number("100", false))
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] get_next_precedence() RParen
[2021-08-19T16:03:05Z DEBUG sqlparser::parser] next precedence: 0
[2021-08-19T16:03:06Z ERROR datafuse_query::servers::clickhouse::writers::query_writer] OnQuery Error: Code: 6, displayText = Unable to get field named "number1". Valid fields: ["number"].

       0: backtrace::backtrace::libunwind::trace
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/backtrace/libunwind.rs:90:5
          backtrace::backtrace::trace_unsynchronized
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/backtrace/mod.rs:66:5
       1: backtrace::backtrace::trace
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/backtrace/mod.rs:53:14
       2: backtrace::capture::Backtrace::create
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/capture.rs:176:9
       3: backtrace::capture::Backtrace::new
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.61/src/capture.rs:140:22
       4: common_exception::exception::ErrorCode::BadArguments
                 at common/exception/src/exception.rs:108:77
       5: common_datavalues::data_schema::DataSchema::index_of
                 at common/datavalues/src/data_schema.rs:65:13
       6: common_datavalues::data_schema::DataSchema::field_with_name
                 at common/datavalues/src/data_schema.rs:54:25
       7: common_planners::plan_expression::Expression::to_data_type
                 at common/planners/src/plan_expression.rs:215:41
       8: common_planners::plan_expression::Expression::to_data_type
                 at common/planners/src/plan_expression.rs:223:21
       9: common_planners::plan_expression::Expression::to_data_field
                 at common/planners/src/plan_expression.rs:170:9
      10: common_planners::plan_rewriter::RewriteHelper::exprs_to_fields::{{closure}}
                 at common/planners/src/plan_rewriter.rs:716:25
      11: core::iter::adapters::map::map_try_fold::{{closure}}
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/adapters/map.rs:91:28
      12: core::iter::traits::iterator::Iterator::try_fold
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/traits/iterator.rs:1998:21
      13: <core::iter::adapters::map::Map<I,F> as core::iter::traits::iterator::Iterator>::try_fold
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/adapters/map.rs:117:9
      14: <core::iter::adapters::ResultShunt<I,E> as core::iter::traits::iterator::Iterator>::try_fold
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/adapters/mod.rs:177:9
      15: core::iter::traits::iterator::Iterator::find
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/traits/iterator.rs:2385:9
      16: <core::iter::adapters::ResultShunt<I,E> as core::iter::traits::iterator::Iterator>::next
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/adapters/mod.rs:159:9
      17: <alloc::vec::Vec<T> as alloc::vec::spec_from_iter_nested::SpecFromIterNested<T,I>>::from_iter
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/alloc/src/vec/spec_from_iter_nested.rs:23:32
      18: <alloc::vec::Vec<T> as alloc::vec::spec_from_iter::SpecFromIter<T,I>>::from_iter
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/alloc/src/vec/spec_from_iter.rs:33:9
      19: <alloc::vec::Vec<T> as core::iter::traits::collect::FromIterator<T>>::from_iter
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/alloc/src/vec/mod.rs:2462:9
      20: core::iter::traits::iterator::Iterator::collect
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/traits/iterator.rs:1749:9
      21: <core::result::Result<V,E> as core::iter::traits::collect::FromIterator<core::result::Result<A,E>>>::from_iter::{{closure}}
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/result.rs:1887:53
      22: core::iter::adapters::process_results
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/adapters/mod.rs:148:17
      23: <core::result::Result<V,E> as core::iter::traits::collect::FromIterator<core::result::Result<A,E>>>::from_iter
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/result.rs:1887:9
      24: core::iter::traits::iterator::Iterator::collect
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/iter/traits/iterator.rs:1749:9
      25: common_planners::plan_rewriter::RewriteHelper::exprs_to_fields
                 at common/planners/src/plan_rewriter.rs:714:9
      26: common_planners::plan_builder::PlanBuilder::expression
                 at common/planners/src/plan_builder.rs:92:22
      27: datafuse_query::sql::plan_parser::PlanParser::expression
                 at query/src/sql/plan_parser.rs:1221:9
      28: datafuse_query::sql::plan_parser::PlanParser::select_to_plan
                 at query/src/sql/plan_parser.rs:614:20
      29: datafuse_query::sql::plan_parser::PlanParser::query_to_plan
                 at query/src/sql/plan_parser.rs:450:17
      30: datafuse_query::sql::plan_parser::PlanParser::sql_statement_to_plan
                 at query/src/sql/plan_parser.rs:156:40
      31: datafuse_query::sql::plan_parser::PlanParser::statement_to_plan
                 at query/src/sql/plan_parser.rs:121:42
      32: datafuse_query::sql::plan_parser::PlanParser::build_from_sql::{{closure}}::{{closure}}
                 at query/src/sql/plan_parser.rs:97:34
      33: core::option::Option<T>::map
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/option.rs:836:29
      34: datafuse_query::sql::plan_parser::PlanParser::build_from_sql::{{closure}}
                 at query/src/sql/plan_parser.rs:95:13
      35: core::result::Result<T,E>::and_then
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/result.rs:966:22
      36: datafuse_query::sql::plan_parser::PlanParser::build_from_sql
                 at query/src/sql/plan_parser.rs:94:9
      37: datafuse_query::servers::clickhouse::interactive_worker_base::InteractiveWorkerBase::do_query::{{closure}}
                 at query/src/servers/clickhouse/interactive_worker_base.rs:59:20
      38: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      39: <datafuse_query::servers::clickhouse::interactive_worker::InteractiveWorker as clickhouse_srv::ClickHouseSession>::execute_query::{{closure}}
                 at query/src/servers/clickhouse/interactive_worker.rs:52:48
      40: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      41: <core::pin::Pin<P> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/future.rs:119:9
      42: clickhouse_srv::cmd::Cmd::apply::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/clickhouse-srv-0.3.1/src/cmd.rs:53:17
      43: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      44: clickhouse_srv::ClickHouseServer::run::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/clickhouse-srv-0.3.1/src/lib.rs:155:13
      45: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      46: clickhouse_srv::ClickHouseServer::run_on::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/clickhouse-srv-0.3.1/src/lib.rs:125:9
      47: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      48: clickhouse_srv::ClickHouseServer::run_on_stream::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/clickhouse-srv-0.3.1/src/lib.rs:118:9
      49: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      50: datafuse_query::servers::clickhouse::clickhouse_session::ClickHouseConnection::run_on_stream::{{closure}}::{{closure}}
                 at query/src/servers/clickhouse/clickhouse_session.rs:39:17
      51: <core::future::from_generator::GenFuture<T> as core::future::future::Future>::poll
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/future/mod.rs:80:19
      52: tokio::runtime::task::core::CoreStage<T>::poll::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/core.rs:161:17
      53: tokio::loom::std::unsafe_cell::UnsafeCell<T>::with_mut
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/loom/std/unsafe_cell.rs:14:9
      54: tokio::runtime::task::core::CoreStage<T>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/core.rs:151:13
      55: tokio::runtime::task::harness::poll_future::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:461:19
      56: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/panic/unwind_safe.rs:271:9
      57: std::panicking::try::do_call
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:401:40
      58: <unknown>
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:429:6
      59: std::panicking::try
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:365:19
      60: std::panic::catch_unwind
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panic.rs:129:14
      61: tokio::runtime::task::harness::poll_future
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:449:18
      62: tokio::runtime::task::harness::Harness<T,S>::poll_inner
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:98:27
      63: tokio::runtime::task::harness::Harness<T,S>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:53:15
      64: tokio::runtime::task::raw::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/raw.rs:113:5
      65: tokio::runtime::task::raw::RawTask::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/raw.rs:70:18
      66: tokio::runtime::task::LocalNotified<S>::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/mod.rs:343:9
      67: tokio::runtime::thread_pool::worker::Context::run_task::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:398:13
      68: tokio::coop::with_budget::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/coop.rs:106:9
      69: std::thread::local::LocalKey<T>::try_with
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/thread/local.rs:399:16
      70: std::thread::local::LocalKey<T>::with
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/thread/local.rs:375:9
      71: tokio::coop::with_budget
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/coop.rs:99:5
          tokio::coop::budget
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/coop.rs:76:5
          tokio::runtime::thread_pool::worker::Context::run_task
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:397:9
      72: tokio::runtime::thread_pool::worker::Context::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:365:24
      73: tokio::runtime::thread_pool::worker::run::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:350:17
      74: tokio::macros::scoped_tls::ScopedKey<T>::set
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/macros/scoped_tls.rs:61:9
      75: tokio::runtime::thread_pool::worker::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:347:5
      76: tokio::runtime::thread_pool::worker::Launch::launch::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/thread_pool/worker.rs:326:45
      77: <tokio::runtime::blocking::task::BlockingTask<T> as core::future::future::Future>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/blocking/task.rs:42:21
      78: tokio::runtime::task::core::CoreStage<T>::poll::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/core.rs:161:17
      79: tokio::loom::std::unsafe_cell::UnsafeCell<T>::with_mut
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/loom/std/unsafe_cell.rs:14:9
      80: tokio::runtime::task::core::CoreStage<T>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/core.rs:151:13
      81: tokio::runtime::task::harness::poll_future::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:461:19
      82: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/panic/unwind_safe.rs:271:9
      83: std::panicking::try::do_call
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:401:40
      84: <unknown>
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:436:2
      85: std::panicking::try
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:365:19
      86: std::panic::catch_unwind
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panic.rs:129:14
      87: tokio::runtime::task::harness::poll_future
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:449:18
      88: tokio::runtime::task::harness::Harness<T,S>::poll_inner
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:98:27
      89: tokio::runtime::task::harness::Harness<T,S>::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/harness.rs:53:15
      90: tokio::runtime::task::raw::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/raw.rs:113:5
      91: tokio::runtime::task::raw::RawTask::poll
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/raw.rs:70:18
      92: tokio::runtime::task::UnownedTask<S>::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/task/mod.rs:373:9
      93: tokio::runtime::blocking::pool::Inner::run
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/blocking/pool.rs:265:17
      94: tokio::runtime::blocking::pool::Spawner::spawn_thread::{{closure}}
                 at /Users/xuyifan/.cargo/registry/src/github.com-1ecc6299db9ec823/tokio-1.10.0/src/runtime/blocking/pool.rs:245:17
      95: std::sys_common::backtrace::__rust_begin_short_backtrace
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/sys_common/backtrace.rs:125:18
      96: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/thread/mod.rs:476:17
      97: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/panic/unwind_safe.rs:271:9
      98: std::panicking::try::do_call
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:401:40
      99: <unknown>
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:436:2
     100: std::panicking::try
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panicking.rs:365:19
     101: std::panic::catch_unwind
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/panic.rs:129:14
     102: std::thread::Builder::spawn_unchecked::{{closure}}
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/thread/mod.rs:475:30
     103: core::ops::function::FnOnce::call_once{{vtable.shim}}
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/core/src/ops/function.rs:227:5
     104: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/alloc/src/boxed.rs:1572:9
          <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/alloc/src/boxed.rs:1572:9
          std::sys::unix::thread::Thread::new::thread_start
                 at /rustc/e3b1c12becebd21d88ca9f4364d7db8d1d380c18/library/std/src/sys/unix/thread.rs:91:17
     105: __pthread_start

[2021-08-19T16:03:06Z INFO  datafuse_query::sessions::context] Destroy DatafuseQueryContext

We can see error stacktrace in log when the user enters an invalid query.

It seems that SELECT message FROM system.warnings runs when clickhouse-client connects when datafuse-query and an error Unknown table: 'warnings' occurs. Is it a minor bug?

xuyifangreeneyes · 2021-08-19T16:16:13Z

Besides, I cannot find a way to let datafuse_query::servers::clickhouse::writers::query_writer::QueryWriter::write return an error. #985 mentions that we can run SELECT number FROM numbers_mt(10000000000) ORDER BY number DESC LIMIT 100 to make it OOM to make the error occur. However, running SELECT number FROM numbers_mt(10000000000) ORDER BY number DESC LIMIT 100 keeps datafuse-query running and has no response to the client in my computer.

xuyifangreeneyes · 2021-08-19T16:18:06Z

@bohutang @sundy-li @zhang2014

codecov-commenter · 2021-08-19T16:27:44Z

Codecov Report

Merging #1525 (4534bdf) into master (1321317) will decrease coverage by 0%.
The diff coverage is n/a.

@@          Coverage Diff           @@
##           master   #1525   +/-   ##
======================================
- Coverage      72%     72%   -1%     
======================================
  Files         527     527           
  Lines       31755   31768   +13     
======================================
- Hits        23176   23142   -34     
- Misses       8579    8626   +47

Impacted Files	Coverage Δ
store/src/meta_service/network.rs	`46% <0%> (-23%)`	⬇️
store/src/meta_service/meta_service_impl.rs	`56% <0%> (-20%)`	⬇️
store/src/meta_service/raftmeta.rs	`79% <0%> (-2%)`	⬇️
query/src/sql/parser/ast/query.rs	`46% <0%> (-1%)`	⬇️
store/src/meta_service/raftmeta_test.rs	`94% <0%> (-1%)`	⬇️
query/src/optimizers/optimizer.rs	`100% <0%> (ø)`
...sql/parser/transformer/transform_sqlparser_test.rs	`100% <0%> (ø)`
query/src/sql/parser/ast/ast_display_test.rs	`87% <0%> (+<1%)`	⬆️
.../src/sql/parser/transformer/transform_sqlparser.rs	`43% <0%> (+1%)`	⬆️

Continue to review full report at Codecov.

Legend - Click here to learn more
Δ = absolute <relative> (impact), ø = not affected, ? = missing data
Powered by Codecov. Last update 1321317...4534bdf. Read the comment docs.

databend-bot · 2021-08-20T00:02:15Z

CI Passed
Reviewer Approved
Let's Merge

print error stacktrace for in clickhouse-handler

4534bdf

databend-bot added the pr-improvement label Aug 19, 2021

sundy-li approved these changes Aug 20, 2021

View reviewed changes

databend-bot merged commit de01a3d into databendlabs:master Aug 20, 2021

xuyifangreeneyes deleted the print-error-stacktrace branch October 30, 2021 05:05

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Uh oh!

[clickhouse-handler] print error stacktrace in clickhouse-handler#1525

[clickhouse-handler] print error stacktrace in clickhouse-handler#1525
databend-bot merged 1 commit into
databendlabs:masterfrom
xuyifangreeneyes:print-error-stacktrace

xuyifangreeneyes commented Aug 19, 2021

Uh oh!

databend-bot commented Aug 19, 2021

Uh oh!

databend-bot commented Aug 19, 2021

Uh oh!

xuyifangreeneyes commented Aug 19, 2021

Uh oh!

xuyifangreeneyes commented Aug 19, 2021

Uh oh!

xuyifangreeneyes commented Aug 19, 2021

Uh oh!

codecov-commenter commented Aug 19, 2021

Uh oh!

databend-bot commented Aug 20, 2021

Uh oh!

Reviewers

Assignees

Labels

Projects

Milestone

Development

Uh oh!

4 participants

Uh oh!

Conversation

xuyifangreeneyes commented Aug 19, 2021

Summary

Changelog

Related Issues

Test Plan

Uh oh!

databend-bot commented Aug 19, 2021

Uh oh!

databend-bot commented Aug 19, 2021

Uh oh!

xuyifangreeneyes commented Aug 19, 2021

Uh oh!

xuyifangreeneyes commented Aug 19, 2021

Uh oh!

xuyifangreeneyes commented Aug 19, 2021

Uh oh!

codecov-commenter commented Aug 19, 2021

Codecov Report

Uh oh!

databend-bot commented Aug 20, 2021

Uh oh!

Reviewers

Assignees

Labels

Projects

Milestone

Development

Uh oh!

4 participants