ClickHouse/src/Common/TraceCollector.cpp

152 lines
4.3 KiB
C++
Raw Normal View History

2019-02-03 09:57:12 +00:00
#include "TraceCollector.h"
2019-07-05 13:48:47 +00:00
#include <Core/Field.h>
2020-01-16 12:37:29 +00:00
#include <IO/ReadBufferFromFileDescriptor.h>
#include <IO/ReadHelpers.h>
#include <IO/WriteBufferFromFileDescriptor.h>
#include <IO/WriteBufferFromFileDescriptorDiscardOnFailure.h>
#include <IO/WriteHelpers.h>
#include <Interpreters/TraceLog.h>
2019-07-05 13:48:47 +00:00
#include <Poco/Logger.h>
2020-01-16 12:37:29 +00:00
#include <Common/Exception.h>
#include <Common/PipeFDs.h>
2019-07-29 22:26:44 +00:00
#include <Common/StackTrace.h>
2019-02-03 09:57:12 +00:00
#include <common/logger_useful.h>
2019-02-03 21:30:45 +00:00
2019-07-24 02:02:10 +00:00
2019-07-10 20:47:39 +00:00
namespace DB
{
2020-01-16 12:37:29 +00:00
namespace
{
/// Normally query_id is a UUID (string with a fixed length) but user can provide custom query_id.
/// Thus upper bound on query_id length should be introduced to avoid buffer overflow in signal handler.
constexpr size_t QUERY_ID_MAX_LEN = 1024;
}
2019-07-10 20:47:39 +00:00
LazyPipeFDs pipe;
2019-07-10 20:47:39 +00:00
TraceCollector::TraceCollector(std::shared_ptr<TraceLog> trace_log_)
: trace_log(std::move(trace_log_))
2019-07-10 20:47:39 +00:00
{
2020-01-16 12:37:29 +00:00
pipe.open();
2019-07-24 02:02:10 +00:00
/** Turn write end of pipe to non-blocking mode to avoid deadlocks
* when QueryProfiler is invoked under locks and TraceCollector cannot pull data from pipe.
*/
2020-01-16 12:37:29 +00:00
pipe.setNonBlocking();
pipe.tryIncreaseSize(1 << 20);
2019-07-24 02:02:10 +00:00
2019-07-10 20:47:39 +00:00
thread = ThreadFromGlobalPool(&TraceCollector::run, this);
}
2019-07-10 20:47:39 +00:00
TraceCollector::~TraceCollector()
{
if (!thread.joinable())
LOG_ERROR(&Poco::Logger::get("TraceCollector"), "TraceCollector thread is malformed and cannot be joined");
2019-07-10 20:47:39 +00:00
else
2020-01-16 12:37:29 +00:00
stop();
2019-07-10 20:47:39 +00:00
2020-01-16 12:37:29 +00:00
pipe.close();
}
2020-04-30 13:25:17 +00:00
void TraceCollector::collect(TraceType trace_type, const StackTrace & stack_trace, Int64 size)
{
2020-01-16 12:37:29 +00:00
constexpr size_t buf_size = sizeof(char) + // TraceCollector stop flag
8 * sizeof(char) + // maximum VarUInt length for string size
QUERY_ID_MAX_LEN * sizeof(char) + // maximum query_id length
sizeof(UInt8) + // number of stack frames
sizeof(StackTrace::Frames) + // collected stack trace, maximum capacity
sizeof(TraceType) + // trace type
sizeof(UInt64) + // thread_id
2020-04-30 13:25:17 +00:00
sizeof(Int64); // size
2020-01-16 12:37:29 +00:00
char buffer[buf_size];
WriteBufferFromFileDescriptorDiscardOnFailure out(pipe.fds_rw[1], buf_size, buffer);
StringRef query_id = CurrentThread::getQueryId();
query_id.size = std::min(query_id.size, QUERY_ID_MAX_LEN);
auto thread_id = CurrentThread::get().thread_id;
2020-01-16 12:37:29 +00:00
writeChar(false, out); /// true if requested to stop the collecting thread.
2020-01-16 12:37:29 +00:00
writeStringBinary(query_id, out);
size_t stack_trace_size = stack_trace.getSize();
size_t stack_trace_offset = stack_trace.getOffset();
writeIntBinary(UInt8(stack_trace_size - stack_trace_offset), out);
for (size_t i = stack_trace_offset; i < stack_trace_size; ++i)
writePODBinary(stack_trace.getFramePointers()[i], out);
2020-01-16 12:37:29 +00:00
writePODBinary(trace_type, out);
writePODBinary(thread_id, out);
2020-01-16 12:37:29 +00:00
writePODBinary(size, out);
out.next();
2019-07-10 20:47:39 +00:00
}
2019-02-03 09:57:12 +00:00
/** Sends TraceCollector stop message
2019-07-05 13:48:47 +00:00
*
* Each sequence of data for TraceCollector thread starts with a boolean flag.
* If this flag is true, TraceCollector must stop reading trace_pipe and exit.
* This function sends flag with a true value to stop TraceCollector gracefully.
*/
2020-01-16 12:37:29 +00:00
void TraceCollector::stop()
2019-07-05 13:48:47 +00:00
{
2020-01-16 12:37:29 +00:00
WriteBufferFromFileDescriptor out(pipe.fds_rw[1]);
2019-07-10 20:47:39 +00:00
writeChar(true, out);
2019-07-05 13:48:47 +00:00
out.next();
thread.join();
2019-07-05 13:48:47 +00:00
}
2019-02-03 09:57:12 +00:00
2019-07-05 13:48:47 +00:00
void TraceCollector::run()
{
2020-01-16 12:37:29 +00:00
ReadBufferFromFileDescriptor in(pipe.fds_rw[0]);
2019-07-05 13:48:47 +00:00
while (true)
{
char is_last;
readChar(is_last, in);
if (is_last)
break;
2019-05-19 20:22:44 +00:00
2019-07-05 13:48:47 +00:00
std::string query_id;
readStringBinary(query_id, in);
2019-02-03 21:30:45 +00:00
2020-01-16 12:37:29 +00:00
UInt8 trace_size = 0;
readIntBinary(trace_size, in);
2019-05-14 22:15:23 +00:00
2019-07-05 13:48:47 +00:00
Array trace;
2020-01-16 12:37:29 +00:00
trace.reserve(trace_size);
2020-01-16 12:37:29 +00:00
for (size_t i = 0; i < trace_size; i++)
{
uintptr_t addr = 0;
readPODBinary(addr, in);
trace.emplace_back(UInt64(addr));
}
2019-02-03 21:30:45 +00:00
TraceType trace_type;
readPODBinary(trace_type, in);
UInt64 thread_id;
2020-02-02 02:27:15 +00:00
readPODBinary(thread_id, in);
2019-02-03 21:30:45 +00:00
2020-04-30 13:25:17 +00:00
Int64 size;
2020-01-16 12:37:29 +00:00
readPODBinary(size, in);
2020-01-23 09:42:58 +00:00
if (trace_log)
{
2020-08-01 15:54:44 +00:00
UInt64 time = clock_gettime_ns(CLOCK_REALTIME);
2020-07-31 20:16:31 +00:00
TraceLogElement element{time_t(time / 1000000000), time, trace_type, thread_id, query_id, trace, size};
2020-01-23 09:42:58 +00:00
trace_log->add(element);
}
2019-02-03 09:57:12 +00:00
}
}
2019-07-10 20:47:39 +00:00
}