system.trace_log

샘플링 쿼리 프로파일러가 수집한 스택 트레이스를 담고 있는 시스템 테이블입니다.

ClickHouse Cloud에서 쿼리하기

이 시스템 테이블의 데이터는 ClickHouse Cloud의 각 노드에 로컬로 저장됩니다. 따라서 전체 데이터를 모두 확인하려면 clusterAllReplicas 함수를 사용해야 합니다. 자세한 내용은 여기를 참조하십시오.

​

설명

샘플링 쿼리 프로파일러에서 수집된 스택 트레이스를 포함합니다.

서버 구성의 trace_log 섹션이 설정되어 있으면 ClickHouse가 이 테이블을 생성합니다. 다음 설정도 참조하십시오: query_profiler_real_time_period_ns, query_profiler_cpu_time_period_ns, memory_profiler_step, memory_profiler_sample_probability, trace_profile_events.

로그를 분석하려면 addressToLine, addressToLineWithInlines, addressToSymbol, demangle 인트로스펙션 함수를 사용하십시오.

​

컬럼

• hostname (LowCardinality(String)) — 쿼리를 실행하는 서버의 호스트명입니다.
• event_date (Date) — 샘플링 시점의 날짜입니다.
• event_time (DateTime) — 샘플링 시점의 타임스탬프.
• event_time_microseconds (DateTime64(6)) — 마이크로초 단위 정밀도를 가진 샘플링 시점의 타임스탬프입니다.
• timestamp_ns (UInt64) — 나노초 단위로 기록된 샘플링 시점의 타임스탬프입니다.
• revision (UInt32) — ClickHouse 서버의 빌드 리비전입니다. clickhouse-client로 서버에 연결하면 Connected to ClickHouse server version 19.18.1.와 유사한 문자열이 표시됩니다. 이 필드에는 서버의 version이 아니라 revision이 들어 있습니다.
• trace_type (Enum8(‘Real’ = 0, ‘CPU’ = 1, ‘Memory’ = 2, ‘MemorySample’ = 3, ‘MemoryPeak’ = 4, ‘ProfileEvent’ = 5, ‘JemallocSample’ = 6, ‘MemoryAllocatedWithoutCheck’ = 7, ‘Instrumentation’ = 8)) — 트레이스 유형: Real은 실제 경과 시간을 기준으로 스택 트레이스를 수집함을 나타냅니다. CPU는 CPU 시간을 기준으로 스택 트레이스를 수집함을 나타냅니다. Memory는 메모리 할당이 다음 워터마크를 초과할 때 메모리 할당 및 해제를 수집함을 나타냅니다. MemorySample은 임의의 메모리 할당 및 해제를 수집함을 나타냅니다. MemoryPeak은 최대 메모리 사용량 업데이트를 수집함을 나타냅니다. ProfileEvent는 profile events 증가분을 수집함을 나타냅니다. JemallocSample은 jemalloc 샘플을 수집함을 나타냅니다. MemoryAllocatedWithoutCheck는 메모리 제한을 무시하고 수행되는 대규모 할당(>16MiB)을 수집함을 나타냅니다(ClickHouse 개발자 전용).Instrumentation은 XRay를 통해 수행된 계측으로 수집된 traces를 나타냅니다.
• cpu_id (UInt64) — CPU 식별자.
• thread_id (UInt64) — 스레드 ID.
• thread_name (LowCardinality(String)) — 스레드 이름입니다.
• query_id (String) — query_log 시스템 테이블(system table)에서 실행 중이던 쿼리의 세부 정보를 조회하는 데 사용할 수 있는 쿼리 식별자입니다.
• trace (Array(UInt64)) — 샘플링 시점의 스택 트레이스입니다. 각 요소는 ClickHouse 서버 프로세스 내의 가상 메모리 주소입니다.
• size (Int64) — trace 타입이 Memory, MemorySample, MemoryAllocatedWithoutCheck 또는 MemoryPeak이면 할당된 메모리의 크기이고, 다른 trace 타입에서는 0입니다.
• ptr (UInt64) — 할당된 청크의 주소입니다.
• memory_context (Enum8(‘Unknown’ = -1, ‘Global’ = 0, ‘User’ = 1, ‘Process’ = 2, ‘Thread’ = 3, ‘Max’ = 4)) — 메모리 트래커 컨텍스트( Memory/MemoryPeak에만 해당): Unknown 컨텍스트는 이 trace_type에 대해 정의되지 않습니다. Global은 서버 컨텍스트를 나타냅니다. User는 사용자/머지 컨텍스트를 나타냅니다. Process는 프로세스(즉, 쿼리) 컨텍스트를 나타냅니다. Thread는 스레드 컨텍스트(특정 프로세스의 스레드)를 나타냅니다. Max는 메모리 트래커가 차단되지 않았음을 의미하는 특수 값입니다(blocked_context 컬럼에 사용).
• memory_blocked_context (Enum8(‘Unknown’ = -1, ‘Global’ = 0, ‘User’ = 1, ‘Process’ = 2, ‘Thread’ = 3, ‘Max’ = 4)) — 메모리 추적기(memory tracker)가 차단되는 컨텍스트입니다(ClickHouse 개발자 전용): Unknown 컨텍스트는 이 trace_type에 대해 정의되어 있지 않습니다. Global은 서버 컨텍스트를 나타냅니다. User는 사용자/머지 컨텍스트를 나타냅니다. Process는 프로세스(즉, 쿼리) 컨텍스트를 나타냅니다. Thread는 스레드(특정 프로세스의 스레드) 컨텍스트를 나타냅니다. Max는 메모리 추적기가 차단되지 않음을 의미하는 특수 값입니다(blocked_context 컬럼용).
• event (LowCardinality(String)) — trace 타입이 ProfileEvent인 경우 업데이트된 프로파일 이벤트 이름이며, 다른 trace 타입인 경우 빈 문자열입니다.
• increment (Int64) — trace 유형이 ProfileEvent일 때는 프로파일 이벤트의 증가량이고, 다른 trace 유형에서는 0입니다.
• symbols (Array(LowCardinality(String))) — 심볼화가 활성화된 경우 trace에 대응하는 디맹글링된 심볼 이름을 포함합니다. 심볼화는 서버 설정 파일의 trace_log 아래 symbolize 설정에서 활성화하거나 비활성화할 수 있습니다.
• lines (배열(LowCardinality(String))) — 심볼화가 활성화되어 있으면 trace에 대응하는 파일 이름과 줄 번호가 포함된 문자열을 담습니다.
• function_id (널 허용(Int32)) — trace 타입 Instrumentation에서 elf-binary의 xray_instr_map 섹션에 있는 함수에 할당된 ID입니다.
• function_name (Nullable(String)) — trace 유형 Instrumentation에서 계측된 함수의 이름입니다.
• handler (널 허용(String)) — trace 유형 Instrumentation에서 계측된 함수의 handler입니다.
• entry_type (Nullable(Enum8(‘Entry’ = 0, ‘Exit’ = 1))) — trace 타입이 Instrumentation인 경우, 계측된 함수의 진입 유형입니다.
• duration_nanoseconds (Nullable(UInt64)) — trace 타입의 Instrumentation에서 함수가 실행된 시간을 나노초 단위로 나타냅니다.

별칭:

• build_id — 'E2EEB60ED9503BFFA825B628D480CCDC40B2D416'의 별칭입니다.

심볼화는 서버 설정 파일의 trace_log 아래 symbolize에서 활성화하거나 비활성화할 수 있습니다.

​

예시

SELECT * FROM system.trace_log LIMIT 1 \G

Row 1:
──────
hostname:                clickhouse.eu-central1.internal
event_date:              2025-11-11
event_time:              2025-11-11 11:53:59
event_time_microseconds: 2025-11-11 11:53:59.128333
timestamp_ns:            1762862039128333000
revision:                54504
trace_type:              Instrumentation
cpu_id:                  19
thread_id:               3166432 -- 317만
query_id:                ef462508-e189-4ea2-b231-4489506728e8
trace:                   [350594916,447733712,447742095,447727324,447726659,221642873,450882315,451852359,451905441,451885554,512404306,512509092,612861767,612863269,612466367,612455825,137631896259267,137631896856768]
size:                    0
ptr:                     0
memory_context:          Unknown
memory_blocked_context:  Unknown
event:
increment:               0
symbols:                 ['StackTrace::StackTrace()','DB::InstrumentationManager::createTraceLogElement(DB::InstrumentationManager::InstrumentedPointInfo const&, XRayEntryType, std::__1::chrono::time_point<std::__1::chrono::system_clock, std::__1::chrono::duration<long long, std::__1::ratio<1l, 1000000l>>>) const','DB::InstrumentationManager::profile(XRayEntryType, DB::InstrumentationManager::InstrumentedPointInfo const&)','DB::InstrumentationManager::dispatchHandlerImpl(int, XRayEntryType)','DB::InstrumentationManager::dispatchHandler(int, XRayEntryType)','__xray_FunctionEntry','DB::QueryMetricLog::startQuery(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char>> const&, std::__1::chrono::time_point<std::__1::chrono::system_clock, std::__1::chrono::duration<long long, std::__1::ratio<1l, 1000000l>>>, unsigned long)','DB::logQueryStart(std::__1::chrono::time_point<std::__1::chrono::system_clock, std::__1::chrono::duration<long long, std::__1::ratio<1l, 1000000l>>> const&, std::__1::shared_ptr<DB::Context> const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char>> const&, unsigned long, std::__1::shared_ptr<DB::IAST> const&, DB::QueryPipeline const&, DB::IInterpreter const*, bool, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char>> const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char>> const&, bool)','DB::executeQueryImpl(char const*, char const*, std::__1::shared_ptr<DB::Context>, DB::QueryFlags, DB::QueryProcessingStage::Enum, std::__1::unique_ptr<DB::ReadBuffer, std::__1::default_delete<DB::ReadBuffer>>&, std::__1::shared_ptr<DB::IAST>&, std::__1::shared_ptr<DB::ImplicitTransactionControlExecutor>, std::__1::function<void ()>)','DB::executeQuery(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char>> const&, std::__1::shared_ptr<DB::Context>, DB::QueryFlags, DB::QueryProcessingStage::Enum)','DB::TCPHandler::runImpl()','DB::TCPHandler::run()','Poco::Net::TCPServerConnection::start()','Poco::Net::TCPServerDispatcher::run()','Poco::PooledThread::run()','Poco::ThreadImpl::runnableEntry(void*)','start_thread','__clone3']
lines:                   ['./build/../src/Common/StackTrace.cpp:395','./src/Common/StackTrace.h:62','./contrib/llvm-project/libcxx/include/__memory/shared_ptr.h:738','./build/./src/Interpreters/InstrumentationManager.cpp:257','./build/./src/Interpreters/InstrumentationManager.cpp:225','/home/ubuntu/ClickHouse/ClickHouse/build/programs/clickhouse','./build/./src/Interpreters/QueryMetricLog.cpp:0','./contrib/llvm-project/libcxx/include/__memory/shared_ptr.h:667','./build/./src/Interpreters/executeQuery.cpp:0','./build/./src/Interpreters/executeQuery.cpp:0','./contrib/llvm-project/libcxx/include/__memory/shared_ptr.h:744','./contrib/llvm-project/libcxx/include/__memory/shared_ptr.h:583','./build/../base/poco/Net/src/TCPServerConnection.cpp:54','../contrib/llvm-project/libcxx/include/__memory/unique_ptr.h:80','./build/../base/poco/Foundation/src/ThreadPool.cpp:219','../base/poco/Foundation/include/Poco/AutoPtr.h:77','/home/ubuntu/ClickHouse/ClickHouse/build/programs/clickhouse','/home/ubuntu/ClickHouse/ClickHouse/build/programs/clickhouse']
function_id:             231255
function_name:           DB::QueryMetricLog::startQuery(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char>> const&, std::__1::chrono::time_point<std::__1::chrono::system_clock, std::__1::chrono::duration<long long, std::__1::ratio<1l, 1000000l>>>, unsigned long)
handler:                 profile
entry_type:              Exit
duration_nanoseconds:   58435

​

Chrome Event Trace Format으로 변환

프로파일링 데이터는 다음 쿼리로 Chrome의 Event Trace Format으로 변환할 수 있습니다. 이 쿼리를 chrome_trace.sql 파일에 저장하십시오:

WITH traces AS (
    SELECT * FROM system.trace_log
    WHERE event_date >= today() AND trace_type = 'Instrumentation' AND handler = 'profile'
    ORDER BY event_time, entry_type
)
SELECT
    format(
        '{{"traceEvents": [{}\n]}}',
        arrayStringConcat(
            groupArray(
                format(
                    '\n{{"name": "{}", "cat": "clickhouse", "ph": "{}", "ts": {}, "pid": 1, "tid": {}, "args": {{"query_id": "{}", "cpu_id": {}, "stack": [{}]}}}},',
                    function_name,
                    if(entry_type = 0, 'B', 'E'),
                    timestamp_ns/1000,
                    toString(thread_id),
                    query_id,
                    cpu_id,
                    arrayStringConcat(arrayMap((x, y) -> concat('"', x, ': ', y, '", '), lines, symbols))
                )
            )
        )
    )
FROM traces;

그리고 이를 ClickHouse Client에서 실행해 trace.json 파일로 내보내면, Perfetto 또는 speedscope로 가져올 수 있습니다.

echo $(clickhouse client --query "$(cat chrome_trace.sql)") > trace.json

더 간결하지만 정보량은 적은 트레이스를 원한다면 스택 부분은 생략할 수 있습니다.

​

관련 항목

• SYSTEM INSTRUMENT — 계측 지점을 추가하거나 제거합니다.
• system.instrumentation — 계측이 적용된 지점을 확인합니다.
• system.symbols — 계측 지점을 추가할 수 있는 심볼을 확인합니다.