eH2razkS

119.04.08 20:08:41.429952 [ 1 ] {} <Debug> Application: Loaded metadata.
22019.04.08 20:08:41.429985 [ 1 ] {} <Information> Application: It looks like the process has no CAP_NET_ADMIN capability, 'taskstats' performance statistics will be disabled. It could happen due to incorrect ClickHouse package installation. You could resolve the problem manually with 'sudo setcap cap_net_admin=+ep /usr/bin/clickhouse'. Note that it will not work on 'nosuid' mounted filesystems. It also doesn't work if you run clickhouse-server inside network namespace as it happens in some containers.
32019.04.08 20:08:41.430410 [ 1 ] {} <Information> Application: Listening http://0.0.0.0:8123
42019.04.08 20:08:41.430491 [ 1 ] {} <Information> Application: Listening tcp: 0.0.0.0:9000
52019.04.08 20:08:41.430550 [ 1 ] {} <Information> Application: Listening interserver http: 0.0.0.0:9009
62019.04.08 20:08:41.430901 [ 1 ] {} <Information> Application: Available RAM: 7.64 GiB; physical cores: 2; logical cores: 2.
72019.04.08 20:08:41.430917 [ 1 ] {} <Information> Application: Ready for connections.
82019.04.08 20:08:43.433287 [ 20 ] {} <Debug> ConfigReloader: Loading config `/etc/clickhouse-server/config.xml'
92019.04.08 20:09:27.908660 [ 24 ] {} <Information> Application: Received termination signal (Terminated)
102019.04.08 20:09:27.908828 [ 1 ] {} <Debug> Application: Received termination signal.
112019.04.08 20:09:27.908854 [ 1 ] {} <Debug> Application: Waiting for current connections to close.
122019.04.08 20:09:27.908978 [ 24 ] {} <Debug> BaseDaemon: Received signal to close logs.
132019.04.08 20:09:27.909056 [ 24 ] {} <Information> BaseDaemon: Opened new log file after received signal.
142019.04.08 20:09:28.032528 [ 24 ] {} <Information> Application: Received termination signal (Terminated)
152019.04.08 20:09:28.491713 [ 1 ] {} <Debug> Application: Closed all listening sockets.
162019.04.08 20:09:28.491750 [ 1 ] {} <Debug> Application: Closed connections.
172019.04.08 20:09:28.492090 [ 1 ] {} <Information> Application: Shutting down storages.
182019.04.08 20:09:28.492149 [ 19 ] {} <Trace> SystemLog (system.query_thread_log): Flushing system log
192019.04.08 20:09:28.492314 [ 19 ] {} <Debug> SystemLog (system.query_thread_log): Will use existing table system.query_thread_log for QueryThreadLog
202019.04.08 20:09:28.492747 [ 18 ] {} <Trace> SystemLog (system.query_log): Flushing system log
212019.04.08 20:09:28.492855 [ 18 ] {} <Debug> SystemLog (system.query_log): Will use existing table system.query_log for QueryLog
222019.04.08 20:09:28.493456 [ 1 ] {} <Debug> Application: Shutted down storages.
232019.04.08 20:09:28.494190 [ 1 ] {} <Debug> Application: Destroyed global context.
242019.04.08 20:09:28.494480 [ 1 ] {} <Information> Application: shutting down
252019.04.08 20:09:28.494494 [ 1 ] {} <Debug> Application: Uninitializing subsystem: Logging Subsystem
262019.04.08 20:09:28.494523 [ 24 ] {} <Information> BaseDaemon: Stop SignalListener thread
272019.04.08 20:09:57.733017 [ 1 ] {} <Information> : Starting ClickHouse 19.4.3.11 with revision 54416
282019.04.08 20:09:57.734567 [ 1 ] {} <Information> Application: starting up
292019.04.08 20:09:58.289942 [ 1 ] {} <Debug> Application: rlimit on number of file descriptors is 262144
302019.04.08 20:09:58.290682 [ 1 ] {} <Debug> Application: Initializing DateLUT.
312019.04.08 20:09:58.290691 [ 1 ] {} <Trace> Application: Initialized DateLUT with time zone `UTC'.
322019.04.08 20:09:58.294771 [ 1 ] {} <Debug> Application: Configuration parameter 'interserver_http_host' doesn't exist or exists and empty. Will use 'host01.example.com' as replica host.
332019.04.08 20:09:58.295483 [ 1 ] {} <Debug> ConfigReloader: Loading config `/etc/clickhouse-server/users.xml'
342019.04.08 20:09:58.297783 [ 1 ] {} <Information> Application: Uncompressed cache size was lowered to 3.82 GiB because the system has low amount of memory
352019.04.08 20:09:58.299316 [ 1 ] {} <Information> Application: Mark cache size was lowered to 3.82 GiB because the system has low amount of memory
362019.04.08 20:09:58.299353 [ 1 ] {} <Information> Application: Loading metadata from /var/lib/clickhouse/
372019.04.08 20:09:58.318438 [ 1 ] {} <Information> DatabaseOrdinary (system): Total 2 tables.
382019.04.08 20:09:58.329063 [ 1 ] {} <Information> BackgroundProcessingPool: Create BackgroundProcessingPool with 16 threads
392019.04.08 20:09:58.344963 [ 1 ] {} <Debug> system.query_log (Data): Loading data parts
402019.04.08 20:09:58.345913 [ 1 ] {} <Debug> system.query_log (Data): Loaded data parts (0 items)
412019.04.08 20:09:58.354141 [ 1 ] {} <Debug> system.query_thread_log (Data): Loading data parts
422019.04.08 20:09:58.354216 [ 1 ] {} <Debug> system.query_thread_log (Data): Loaded data parts (0 items)
432019.04.08 20:09:58.354319 [ 1 ] {} <Information> DatabaseOrdinary (system): Starting up tables.
442019.04.08 20:09:58.364998 [ 1 ] {} <Debug> Application: Loaded metadata.
452019.04.08 20:09:58.366581 [ 1 ] {} <Information> Application: It looks like the process has no CAP_NET_ADMIN capability, 'taskstats' performance statistics will be disabled. It could happen due to incorrect ClickHouse package installation. You could resolve the problem manually with 'sudo setcap cap_net_admin=+ep /usr/bin/clickhouse'. Note that it will not work on 'nosuid' mounted filesystems. It also doesn't work if you run clickhouse-server inside network namespace as it happens in some containers.
462019.04.08 20:09:58.374227 [ 1 ] {} <Information> Application: Listening http://0.0.0.0:8123
472019.04.08 20:09:58.374291 [ 1 ] {} <Information> Application: Listening tcp: 0.0.0.0:9000
482019.04.08 20:09:58.374351 [ 1 ] {} <Information> Application: Listening interserver http: 0.0.0.0:9009
492019.04.08 20:09:58.374543 [ 1 ] {} <Information> Application: Available RAM: 7.64 GiB; physical cores: 2; logical cores: 2.
502019.04.08 20:09:58.374554 [ 1 ] {} <Information> Application: Ready for connections.
512019.04.08 20:10:00.379650 [ 20 ] {} <Debug> ConfigReloader: Loading config `/etc/clickhouse-server/config.xml'
522019.04.08 20:10:41.417934 [ 24 ] {} <Trace> TCPHandlerFactory: TCP Request. Address: 127.0.0.1:46192
532019.04.08 20:10:41.425294 [ 24 ] {} <Debug> TCPHandler: Connected ClickHouse client version 19.4.0, revision: 54416, user: default.
542019.04.08 20:10:41.434118 [ 25 ] {} <Trace> TCPHandlerFactory: TCP Request. Address: 127.0.0.1:46194
552019.04.08 20:10:41.434238 [ 25 ] {} <Debug> TCPHandler: Connected ClickHouse client version 19.4.0, revision: 54416, user: default.
562019.04.08 20:10:41.445391 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Debug> executeQuery: (from 127.0.0.1:46194) SELECT DISTINCT arrayJoin(extractAll(name, '[\\w_]{2,}')) AS res FROM (SELECT name FROM system.functions UNION ALL SELECT name FROM system.table_engines UNION ALL SELECT name FROM system.formats UNION ALL SELECT name FROM system.table_functions UNION ALL SELECT name FROM system.data_type_families UNION ALL SELECT name FROM system.settings UNION ALL SELECT concat(func.name, comb.name) FROM system.functions AS func CROSS JOIN system.aggregate_function_combinators AS comb WHERE is_aggregate UNION ALL SELECT name FROM system.databases LIMIT 10000 UNION ALL SELECT DISTINCT name FROM system.tables LIMIT 10000 UNION ALL SELECT DISTINCT name FROM system.columns LIMIT 10000) WHERE notEmpty(res)
572019.04.08 20:10:41.472301 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
582019.04.08 20:10:41.472426 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
592019.04.08 20:10:41.472674 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
602019.04.08 20:10:41.472762 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
612019.04.08 20:10:41.472899 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
622019.04.08 20:10:41.473347 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
632019.04.08 20:10:41.474125 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
642019.04.08 20:10:41.474331 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
652019.04.08 20:10:41.475108 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
662019.04.08 20:10:41.475562 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
672019.04.08 20:10:41.475750 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
682019.04.08 20:10:41.477860 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Debug> executeQuery: Query pipeline:
69Expression
70 Distinct
71  Union
72   Distinct × 6
73    Expression
74     Filter
75      Converting
76       Expression
77        Expression
78         One
79   Distinct
80    Expression
81     Filter
82      Converting
83       Expression
84        CreatingSets
85         Lazy
86         Expression
87          Filter
88           Expression
89            One
90   Distinct
91    Expression
92     Filter
93      Converting
94       Limit
95        Expression
96         Expression
97          One
98   Distinct
99    Expression
100     Filter
101      Converting
102       Limit
103        Expression
104         Distinct
105          Expression
106           Tables
107   Distinct
108    Expression
109     Filter
110      Converting
111       Limit
112        Expression
113         Distinct
114          Expression
115           Columns
116
1172019.04.08 20:10:41.478483 [ 27 ] {} <Trace> CreatingSetsBlockInputStream: Creating join. 
1182019.04.08 20:10:41.478571 [ 27 ] {} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
1192019.04.08 20:10:41.478633 [ 27 ] {} <Debug> CreatingSetsBlockInputStream: Created. Join with 6 entries from 6 rows. In 0.000 sec.
1202019.04.08 20:10:41.480807 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> UnionBlockInputStream: Waiting for threads to finish
1212019.04.08 20:10:41.480826 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> UnionBlockInputStream: Waited for threads to finish
1222019.04.08 20:10:41.480878 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Information> executeQuery: Read 1953 rows, 78.23 KiB in 0.035 sec., 55179 rows/sec., 2.16 MiB/sec.
1232019.04.08 20:10:41.480896 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Debug> MemoryTracker: Peak memory usage (for query): 1.86 MiB.
1242019.04.08 20:10:41.480920 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> UnionBlockInputStream: Waiting for threads to finish
1252019.04.08 20:10:41.480929 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Trace> UnionBlockInputStream: Waited for threads to finish
1262019.04.08 20:10:41.481027 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Debug> MemoryTracker: Peak memory usage (total): 1.86 MiB.
1272019.04.08 20:10:41.481049 [ 25 ] {0a87f431-7eee-41e6-bd19-8727e9107514} <Information> TCPHandler: Processed in 0.036 sec.
1282019.04.08 20:10:41.482232 [ 25 ] {} <Information> TCPHandler: Done processing connection.
1292019.04.08 20:10:46.187981 [ 24 ] {2053871d-fd04-4318-bf30-fedb849e054d} <Debug> executeQuery: (from 127.0.0.1:46192) use system
1302019.04.08 20:10:46.188083 [ 24 ] {2053871d-fd04-4318-bf30-fedb849e054d} <Debug> MemoryTracker: Peak memory usage (for query): 121.00 B.
1312019.04.08 20:10:46.188118 [ 24 ] {2053871d-fd04-4318-bf30-fedb849e054d} <Debug> MemoryTracker: Peak memory usage (total): 121.00 B.
1322019.04.08 20:10:46.188143 [ 24 ] {2053871d-fd04-4318-bf30-fedb849e054d} <Information> TCPHandler: Processed in 0.000 sec.
1332019.04.08 20:10:52.046945 [ 24 ] {b1ec809a-c2e6-4f7c-abda-558145bb5bd8} <Debug> executeQuery: (from 127.0.0.1:46192) show tables
1342019.04.08 20:10:52.047146 [ 24 ] {b1ec809a-c2e6-4f7c-abda-558145bb5bd8} <Debug> executeQuery: (internal) SELECT name FROM system.tables WHERE database = 'system'
1352019.04.08 20:10:52.053486 [ 24 ] {b1ec809a-c2e6-4f7c-abda-558145bb5bd8} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
1362019.04.08 20:10:52.053561 [ 24 ] {b1ec809a-c2e6-4f7c-abda-558145bb5bd8} <Debug> executeQuery: Query pipeline:
137Expression
138 Expression
139  Filter
140   Tables
141
1422019.04.08 20:10:52.053849 [ 24 ] {b1ec809a-c2e6-4f7c-abda-558145bb5bd8} <Information> executeQuery: Read 34 rows, 1.16 KiB in 0.007 sec., 4970 rows/sec., 169.61 KiB/sec.
1432019.04.08 20:10:52.053872 [ 24 ] {b1ec809a-c2e6-4f7c-abda-558145bb5bd8} <Debug> MemoryTracker: Peak memory usage (for query): 1.01 MiB.
1442019.04.08 20:10:52.053925 [ 24 ] {b1ec809a-c2e6-4f7c-abda-558145bb5bd8} <Debug> MemoryTracker: Peak memory usage (total): 1.01 MiB.
1452019.04.08 20:10:52.053966 [ 24 ] {b1ec809a-c2e6-4f7c-abda-558145bb5bd8} <Information> TCPHandler: Processed in 0.007 sec.
1462019.04.08 20:11:02.302663 [ 24 ] {0b43449f-b975-4a1b-a34e-a668d7f452bd} <Debug> executeQuery: (from 127.0.0.1:46192) select * from cluster
1472019.04.08 20:11:02.307593 [ 24 ] {0b43449f-b975-4a1b-a34e-a668d7f452bd} <Debug> MemoryTracker: Peak memory usage (total): 121.00 B.
1482019.04.08 20:11:02.355909 [ 24 ] {0b43449f-b975-4a1b-a34e-a668d7f452bd} <Error> executeQuery: Code: 60, e.displayText() = DB::Exception: Table system.cluster doesn't exist. (from 127.0.0.1:46192) (in query: select * from cluster), Stack trace:
149
1500. clickhouse-server(StackTrace::StackTrace()+0x16) [0x5f2ebc6]
1511. clickhouse-server(DB::Exception::Exception(std::string const&, int)+0x1f) [0x2e09c2f]
1522. clickhouse-server(DB::Context::getTableImpl(std::string const&, std::string const&, DB::Exception*) const+0x2ef) [0x557cfaf]
1533. clickhouse-server(DB::Context::getTable(std::string const&, std::string const&) const+0x5f) [0x557d32f]
1544. clickhouse-server(DB::InterpreterSelectQuery::InterpreterSelectQuery(std::shared_ptr<DB::IAST> const&, DB::Context const&, std::shared_ptr<DB::IBlockInputStream> const&, std::shared_ptr<DB::IStorage> const&, std::vector<std::string, std::allocator<std::string> > const&, DB::QueryProcessingStage::Enum, unsigned long, bool, bool)+0x1155) [0x55d27c5]
1555. clickhouse-server(DB::InterpreterSelectQuery::InterpreterSelectQuery(std::shared_ptr<DB::IAST> const&, DB::Context const&, std::vector<std::string, std::allocator<std::string> > const&, DB::QueryProcessingStage::Enum, unsigned long, bool, bool)+0x47) [0x55d2cb7]
1566. clickhouse-server(DB::InterpreterSelectWithUnionQuery::InterpreterSelectWithUnionQuery(std::shared_ptr<DB::IAST> const&, DB::Context const&, std::vector<std::string, std::allocator<std::string> > const&, DB::QueryProcessingStage::Enum, unsigned long, bool, bool)+0x71e) [0x55de74e]
1577. clickhouse-server(DB::InterpreterFactory::get(std::shared_ptr<DB::IAST>&, DB::Context&, DB::QueryProcessingStage::Enum)+0x2da) [0x55baa6a]
1588. clickhouse-server() [0x56f5fa3]
1599. clickhouse-server(DB::executeQuery(std::string const&, DB::Context&, bool, DB::QueryProcessingStage::Enum, bool)+0x61) [0x56f7eb1]
16010. clickhouse-server(DB::TCPHandler::runImpl()+0x47c) [0x2e1676c]
16111. clickhouse-server(DB::TCPHandler::run()+0x1c) [0x2e17b0c]
16212. clickhouse-server(Poco::Net::TCPServerConnection::start()+0xf) [0x613634f]
16313. clickhouse-server(Poco::Net::TCPServerDispatcher::run()+0x110) [0x61369b0]
16414. clickhouse-server(Poco::PooledThread::run()+0x77) [0x621e117]
16515. clickhouse-server(Poco::ThreadImpl::runnableEntry(void*)+0x38) [0x621b678]
16616. clickhouse-server() [0x692ecef]
16717. /lib64/libpthread.so.0(+0x7dd5) [0x7f7be94b2dd5]
16818. /lib64/libc.so.6(clone+0x6d) [0x7f7be8cd1ead]
169
1702019.04.08 20:11:02.358185 [ 24 ] {0b43449f-b975-4a1b-a34e-a668d7f452bd} <Debug> MemoryTracker: Peak memory usage (for query): 121.00 B.
1712019.04.08 20:11:02.358218 [ 24 ] {0b43449f-b975-4a1b-a34e-a668d7f452bd} <Information> TCPHandler: Processed in 0.056 sec.
1722019.04.08 20:11:19.857601 [ 24 ] {721380f5-3601-4b7d-a4f1-d7152a23acf5} <Debug> executeQuery: (from 127.0.0.1:46192) select * from clusters
1732019.04.08 20:11:19.858408 [ 24 ] {721380f5-3601-4b7d-a4f1-d7152a23acf5} <Trace> InterpreterSelectQuery: FetchColumns -> Complete
1742019.04.08 20:11:19.858492 [ 24 ] {721380f5-3601-4b7d-a4f1-d7152a23acf5} <Debug> executeQuery: Query pipeline:
175Expression
176 Expression
177  One
178
1792019.04.08 20:11:19.859017 [ 24 ] {721380f5-3601-4b7d-a4f1-d7152a23acf5} <Information> executeQuery: Read 4 rows, 464.00 B in 0.001 sec., 2994 rows/sec., 339.20 KiB/sec.
1802019.04.08 20:11:19.859039 [ 24 ] {721380f5-3601-4b7d-a4f1-d7152a23acf5} <Debug> MemoryTracker: Peak memory usage (for query): 1.04 MiB.
1812019.04.08 20:11:19.859074 [ 24 ] {721380f5-3601-4b7d-a4f1-d7152a23acf5} <Debug> MemoryTracker: Peak memory usage (total): 1.04 MiB.
1822019.04.08 20:11:19.859096 [ 24 ] {721380f5-3601-4b7d-a4f1-d7152a23acf5} <Information> TCPHandler: Processed in 0.002 sec.
1832019.04.08 20:11:28.280945 [ 24 ] {} <Information> TCPHandler: Done processing connection.