Microsoft MVP성태의 닷넷 이야기
글쓴 사람
정성태 (techsharer at outlook.com)
홈페이지
첨부 파일

(시리즈 글이 11개 있습니다.)
.NET Framework: 475. ETW(Event Tracing for Windows)를 C#에서 사용하는 방법
; https://www.sysnet.pe.kr/2/0/1804

.NET Framework: 483. 코드로 살펴 보는 ETW의 활성화 시점
; https://www.sysnet.pe.kr/2/0/1815

.NET Framework: 915. ETW(Event Tracing for Windows)를 이용한 닷넷 프로그램의 내부 이벤트 활용
; https://www.sysnet.pe.kr/2/0/12244

.NET Framework: 923. C# - ETW(Event Tracing for Windows)를 이용한 Finalizer 실행 감시
; https://www.sysnet.pe.kr/2/0/12255

.NET Framework: 932. C# - ETW 관련 Win32 API 사용 예제 코드 (1)
; https://www.sysnet.pe.kr/2/0/12292

.NET Framework: 933. C# - ETW 관련 Win32 API 사용 예제 코드 (2) NT Kernel Logger
; https://www.sysnet.pe.kr/2/0/12296

.NET Framework: 934. C# - ETW 관련 Win32 API 사용 예제 코드 (3) ETW Consumer 구현
; https://www.sysnet.pe.kr/2/0/12299

.NET Framework: 935. C# - ETW 관련 Win32 API 사용 예제 코드 (4) CLR ETW Consumer
; https://www.sysnet.pe.kr/2/0/12300

.NET Framework: 936. C# - ETW 관련 Win32 API 사용 예제 코드 (5) - Private Logger
; https://www.sysnet.pe.kr/2/0/12302

개발 환경 구성: 504. ETW - 닷넷 프레임워크 기반의 응용 프로그램을 위한 명령행 도구 etrace 소개
; https://www.sysnet.pe.kr/2/0/12303

.NET Framework: 994. C# - (.NET Core 2.2부터 가능한) 프로세스 내부에서 CLR ETW 이벤트 수신
; https://www.sysnet.pe.kr/2/0/12474




C# - ETW 관련 Win32 API 사용 예제 코드 (3) ETW Consumer 구현

C# - ETW 관련 Win32 API 사용 예제 코드 (1)
; https://www.sysnet.pe.kr/2/0/12292

C# - ETW 관련 Win32 API 사용 예제 코드 (2) NT Kernel Logger
; https://www.sysnet.pe.kr/2/0/12296




System Logger 유형의 ETW 이벤트를 구독하는 방법에 이어, 이번 글에는 일반적인 ETW 이벤트를 처리하는 방법에 대해 살펴보겠습니다. 이에 대해서는 C++ 코드로 문서에 이미 잘 공개되어 있는데,

Example that Creates a Session and Enables a Manifest-based or Classic Provider
; https://learn.microsoft.com/en-us/windows/win32/etw/example-that-creates-a-session-and-enables-a-manifest-based-provider

C#으로는 대충 다음과 같은 식으로 구현할 수 있습니다.

string sessionName = "mySession";
Guid auditApiCalls = EtwInterop.RegisteredProviders["Microsoft-Windows-Kernel-Audit-API-Calls"];

// 세션을 열고,
EventTraceProperties prop = new EventTraceProperties(true, sessionName);
result = NativeMethods.StartTrace(out sessionHandle, sessionName, ref prop);
Console.WriteLine(result);

if (result == 0)
{
    // 열린 세션에서 활성화할 ETW Provider를 지정 및 Enable 시키고,
    ENABLE_TRACE_PARAMETERS enableParameters = new ENABLE_TRACE_PARAMETERS();
    enableParameters.Version = 1;
    enableParameters.EnableProperty = (uint)EventEnableProperty.Sid;

    result = NativeMethods.EnableTraceEx2(sessionHandle, ref auditApiCalls,
        NativeMethods.EVENT_CONTROL_CODE_ENABLE_PROVIDER, (byte)TraceEventLevel.Informational,
        0, 0, 0, ref enableParameters);

    Console.WriteLine(result);
}

if (result == 0)
{
    // 이벤트 처리 방법과 함께 OpenTrace 호출
    EventTraceLogfile logFile = new EventTraceLogfile();
    logFile.LoggerName = sessionName;
    logFile.EventRecordCallback = EventRecordCallback;

    logFile.ProcessTraceMode = NativeMethods.PROCESS_TRACE_MODE_EVENT_RECORD | NativeMethods.PROCESS_TRACE_MODE_REAL_TIME
        | NativeMethods.PROCESS_TRACE_MODE_RAW_TIMESTAMP;
    traceHandle = NativeMethods.OpenTrace(ref logFile);
}

if (traceHandle != 0)
{
    new Thread((ThreadStart)(
    () =>
    {
        EtwInterop.ProcessTrace(traceHandle);
    })).Start();

    Console.WriteLine("Press ENTER key to exit...");
    Console.ReadLine();
}

지난 글에 설명한 System Logger 유형에서는, StartTrace 시점에 전달한 EventTraceProperties 구조체에 ETW Provider까지 지정했었던 반면 이번에는 그런 동작이 StartTrace로부터 분리돼 EnableTraceEx2에서 수행되는 차이가 있습니다.




지난 글에서 EventTraceWatcher 타입을 설명하며 이미 언급했지만, 위와 같이 세션을 시작하고 그냥 종료하면 "logman query -ets" 명령어를 통해 ETW 세션이 살아 있는 것을 확인할 수 있습니다. 그래서, 위의 프로그램을 다시 실행해 보면 StartTrace의 result 반환 값이 0xb7(ERROR_ALREADY_EXISTS: 0n183)로 나오면서 실패하게 됩니다.

사실 이건 의도된 동작입니다. "ETW(Event Tracing for Windows)를 C#에서 사용하는 방법"에서 설명한 xperf나 logman 도구들이 명령행을 통해 세션을 시작/종료하는 동작으로 보면 StartTrace로 열어둔 세션이 프로세스가 종료했다고 해서 없어지면 그런 식으로 사용할 수 없기 때문입니다. 그래서 보통 지난 번에서와 같이 CloseTrace/ControlTrace의 조합으로 세션을 종료하게 되는데요.

try
{
    // ...[생략]...

    if (traceHandle != 0)
    {
        Thread t = new Thread((ThreadStart)(
        () =>
        {
            Console.WriteLine("PrcoessTrace: " + EtwInterop.ProcessTrace(traceHandle));
        }));

        t.Start();

        Console.WriteLine("Press ENTER key to exit...");
        Console.ReadLine();

        Console.WriteLine("Closed: " + (NativeMethods.CloseTrace(traceHandle) == 0));
        t.Join();
    }
}
finally
{
    // https://www.sysnet.pe.kr/2/0/12292#control_trace
    EtwInterop.CloseActiveSession(sessionName);
}

위와 같이 CloseTrace를 호출하면 별도의 스레드에서 실행하던 ProcessTrace 함수는 CloseTrace 함수가 호출하는 순간까지의 큐에 쌓여 있던 이벤트까지 모두 처리를 완료하고 실행을 반환합니다. 그리고 이후 (EtwInterop.CloseActiveSession 호출 내부에 있는) StopTrace 함수를 호출하는 것으로 세션이 완료되고.




그런데 "Example that Creates a Session and Enables a Manifest-based or Classic Provider" 글의 예제에서는 CloseTrace 호출은 없고 EnableTraceEx2로 EVENT_CONTROL_CODE_DISABLE_PROVIDER 옵션 처리를 하고 있습니다. 실제로 해당 예제로 ProcessTrace까지 돌려 보면 EnableTraceEx2의 호출로 ProcessTrace가 제어를 반환하지는 않습니다.

(어차피 세션을 종료하므로) 굳이 반환하지 않아도 괜찮은 것인지... 아니면 저 예제에서는 ProcessTrace를 호출하지 않아 CloseTrace에 대한 호출을 빼먹인 것인지 알 수 없는데... 휴... "THE WORST API EVER MADE" 글이 괜히 쓰여진 것이 아닐 것입니다. ^^;




세션을 닫지 않고 종료한 경우, 살아 있는 그 세션과 동일한 이름으로 StartTrace를 하면 ERROR_ALREADY_EXISTS가 반환된다고 했습니다. 따라서 그런 경우에는 세션을 굳이 생성하지 않고 곧바로 OpenTrace를 호출할 수 있습니다.

이것을 종합해 최종적으로 다음과 같이 코드를 구성할 수 있습니다.

string sessionName = "mySession";
Guid auditApiCalls = EtwInterop.RegisteredProviders["Microsoft-Windows-Kernel-Audit-API-Calls"];
int result = 0;

ulong traceHandle = 0;
ulong sessionHandle = 0;

try
{
    if (EtwInterop.IsSessionActive(sessionName, out var _) == true)
    {
        // 해당 이름의 세션이 살아 있으면 그냥 OpenTrace 단계로.
    }
    else
    {
        // 해당 이름의 세션이 없으면 새롭게 생성
        EventTraceProperties prop = new EventTraceProperties(true, sessionName);
        result = NativeMethods.StartTrace(out sessionHandle, sessionName, ref prop);
        Console.WriteLine(result);

        if (result == 0)
        {
            ENABLE_TRACE_PARAMETERS enableParameters = new ENABLE_TRACE_PARAMETERS();
            enableParameters.Version = 1;
            enableParameters.EnableProperty = (uint)EventEnableProperty.Sid;

            result = NativeMethods.EnableTraceEx2(sessionHandle, ref auditApiCalls,
                NativeMethods.EVENT_CONTROL_CODE_ENABLE_PROVIDER, (byte)TraceEventLevel.Informational,
                0, 0, 0, ref enableParameters);

            Console.WriteLine(result);
        }
    }

    if (result == 0)
    {
        EventTraceLogfile logFile = new EventTraceLogfile();
        logFile.LoggerName = sessionName;
        logFile.EventRecordCallback = EventRecordCallback;

        logFile.ProcessTraceMode = NativeMethods.PROCESS_TRACE_MODE_EVENT_RECORD | NativeMethods.PROCESS_TRACE_MODE_REAL_TIME
            | NativeMethods.PROCESS_TRACE_MODE_RAW_TIMESTAMP;
        traceHandle = NativeMethods.OpenTrace(ref logFile);
    }

    if (traceHandle != 0)
    {
        Thread t = new Thread((ThreadStart)(
        () =>
        {
            Console.WriteLine("PrcoessTrace: " + EtwInterop.ProcessTrace(traceHandle));
        }));

        t.Start();

        Console.WriteLine("Press ENTER key to exit...");
        Console.ReadLine();

        // Console.WriteLine("Disabled: " + EtwInterop.DisableProvider(sessionHandle, ref auditApiCalls));
        Console.WriteLine("Closed: " + (NativeMethods.CloseTrace(traceHandle) == 0));

        t.Join();
    }
}
finally
{
    EtwInterop.CloseActiveSession(sessionName);
}

(첨부 파일은 이 글의 예제 코드를 포함합니다.)

여기서 잠시, 마이크로소프트가 공개한 ETW 관련 C# 래퍼들은 어떻게 동작하고 있을까요? 우선, 1) 오래 전에 공개되었던 EventTraceWatcher의 경우 기존에 동일한 이름의 세션이 있으면 OpenTrace 처리를 하는 반면, 2) 새롭게 나온 Microsoft.Diagnostics.Tracing.TraceEvent에서는 동일한 이름의 세션이 있으면 그냥 닫아 버리고 다시 여는 방식을 취하고 있습니다. (어느 게 정답일까요? ^^)




1) StartTrace의 반환 값으로 0xa1(0n161) 값이 나온다면?

//
// MessageId: ERROR_BAD_PATHNAME
//
// MessageText:
//
// The specified path is invalid.
//
#define ERROR_BAD_PATHNAME               161L

3번째 EVENT_TRACE_PROPERTIES 인자의 LogFileMode에, 예를 들어 EVENT_TRACE_REAL_TIME_MODE(0x00000100) 등의 값을 지정하지 않았을 수 있습니다.

2) StartTrace의 반환 값으로 0x57(0n87) 값이 나온다면?

//
// MessageId: ERROR_INVALID_PARAMETER
//
// MessageText:
//
// The parameter is incorrect.
//
#define ERROR_INVALID_PARAMETER          87L    // dderror

SystemTraceControlGuid를 지정한 경우, SessionName을 KERNEL_LOGGER_NAME("NT Kernel Logger") 이외의 문자열로 지정했을 수 있습니다. SystemTraceControlGuid에 대해서는 반드시 세션 이름을 "NT Kernel Logger"로 지정해야 합니다.

3) StartTrace의 반환 값으로 0x5 값이 나온다면? SystemTraceControlGuid와 같은 ETW Provider들은 관리자 권한으로만 열 수 있습니다.




참고로, 이번 글에 소개한 ETW 예제는 예전에 Microsoft.Diagnostics.Tracing.TraceEvent를 이용해 구현한 것의 Win32 Interop 버전이라고 보면 됩니다.

C# - 특정 EXE 프로세스를 종료시킨 EXE를 찾아내는 방법
; https://www.sysnet.pe.kr/2/0/11172




[이 글에 대해서 여러분들과 의견을 공유하고 싶습니다. 틀리거나 미흡한 부분 또는 의문 사항이 있으시면 언제든 댓글 남겨주십시오.]







[최초 등록일: ]
[최종 수정일: 6/28/2023]

Creative Commons License
이 저작물은 크리에이티브 커먼즈 코리아 저작자표시-비영리-변경금지 2.0 대한민국 라이센스에 따라 이용하실 수 있습니다.
by SeongTae Jeong, mailto:techsharer at outlook.com

비밀번호

댓글 작성자
 




... [106]  107  108  109  110  111  112  113  114  115  116  117  118  119  120  ...
NoWriterDateCnt.TitleFile(s)
11274정성태8/22/201719484.NET Framework: 674. Thread 타입의 Suspend/Resume/Join 사용 관련 예외 처리
11273정성태8/22/201721648오류 유형: 415. 윈도우 업데이트 에러 Error 0x80070643
11272정성태8/21/201724863VS.NET IDE: 120. 비주얼 스튜디오 2017 버전 15.3.1 - C# 7.1 공개 [2]
11271정성태8/19/201719282VS.NET IDE: 119. Visual Studio 2017에서 .NET Core 2.0 프로젝트 환경 구성하는 방법
11270정성태8/17/201730757.NET Framework: 673. C#에서 enum을 boxing 없이 int로 변환하기 [2]
11269정성태8/17/201721512디버깅 기술: 93. windbg - 풀 덤프에서 .NET 스레드의 상태를 알아내는 방법
11268정성태8/14/201721111디버깅 기술: 92. windbg - C# Monitor Lock을 획득하고 있는 스레드 찾는 방법
11267정성태8/10/201725186.NET Framework: 672. 모노 개발 환경
11266정성태8/10/201724995.NET Framework: 671. C# 6.0 이상의 소스 코드를 Visual Studio 설치 없이 명령행에서 컴파일하는 방법
11265정성태8/10/201753222기타: 66. 도서: 시작하세요! C# 7.1 프로그래밍: 기본 문법부터 실전 예제까지 [11]
11264정성태8/9/201724199오류 유형: 414. UWP app을 signtool.exe로 서명 시 0x8007000b 오류 발생
11263정성태8/9/201719656오류 유형: 413. The C# project "..." is targeting ".NETFramework, Version=v4.0", which is not installed on this machine. [3]
11262정성태8/5/201718297오류 유형: 412. windbg - SOS does not support the current target architecture. [3]
11261정성태8/4/201720857디버깅 기술: 91. windbg - 풀 덤프 파일로부터 강력한 이름의 어셈블리 추출 후 사용하는 방법
11260정성태8/3/201718988.NET Framework: 670. C# - 실행 파일로부터 공개키를 추출하는 방법
11259정성태8/2/201718212.NET Framework: 669. 지연 서명된 어셈블리를 sn.exe -Vr 등록 없이 사용하는 방법
11258정성태8/1/201719049.NET Framework: 668. 지연 서명된 DLL과 서명된 DLL의 차이점파일 다운로드1
11257정성태7/31/201719196.NET Framework: 667. bypassTrustedAppStrongNames 옵션 설명파일 다운로드1
11256정성태7/25/201720670디버깅 기술: 90. windbg의 lm 명령으로 보이지 않는 .NET 4.0 ClassLibrary를 명시적으로 로드하는 방법 [1]
11255정성태7/18/201723245디버깅 기술: 89. Win32 Debug CRT Heap Internals의 0xBAADF00D 표시 재현 [1]파일 다운로드3
11254정성태7/17/201719630개발 환경 구성: 322. "Visual Studio Emulator for Android" 에뮬레이터를 "Android Studio"와 함께 쓰는 방법
11253정성태7/17/201720008Math: 21. "Coding the Matrix" 문제 2.5.1 풀이 [1]파일 다운로드1
11252정성태7/13/201718512오류 유형: 411. RTVS 또는 PTVS 실행 시 Could not load type 'Microsoft.VisualStudio.InteractiveWindow.Shell.IVsInteractiveWindowFactory2'
11251정성태7/13/201717213디버깅 기술: 88. windbg 분석 - webengine4.dll의 MgdExplicitFlush에서 발생한 System.AccessViolationException의 crash 문제 (2)
11250정성태7/13/201720780디버깅 기술: 87. windbg 분석 - webengine4.dll의 MgdExplicitFlush에서 발생한 System.AccessViolationException의 crash 문제 [1]
11249정성태7/12/201718614오류 유형: 410. LoadLibrary("[...].dll") failed - The specified procedure could not be found.
... [106]  107  108  109  110  111  112  113  114  115  116  117  118  119  120  ...