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

ETW(Event Tracing for Windows)를 이용한 닷넷 프로그램의 내부 이벤트 활용

ETW로,

ETW(Event Tracing for Windows)를 C#에서 사용하는 방법
; https://www.sysnet.pe.kr/2/0/1804

당연히 CLR에서도 관련 이벤트를 제공하고 있습니다. 이를 구현할 수 있는 간단한 소스 코드를 다음의 글에서 소개하고 있는데,

Grab ETW Session, Providers and Events
; http://labs.criteo.com/2018/07/grab-etw-session-providers-and-events/

가만 보면 예전에 윈도우 시스템 측의 ETW 제공자를 다루던 방식과 크게 다르지 않습니다.

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

단지, "Grab ETW Session, Providers and Events" 글에서의 예제와 차이점이라면 EnableProvider에 전달한 인자 정도만 다르다고 보면 됩니다.

// register handlers for events on the session source
// more on this later...

// decide which provider to listen to with filters if needed
userSession.EnableProvider(
    ClrTraceEventParser.ProviderGuid,
    TraceEventLevel.Verbose,
    (ulong)(
    ClrTraceEventParser.Keywords.Contention |  // thread contention timing
    ClrTraceEventParser.Keywords.Threading |   // threadpool events
    ClrTraceEventParser.Keywords.Exception |   // get the first chance exceptions
    ClrTraceEventParser.Keywords.GCHeapAndTypeNames | 
    ClrTraceEventParser.Keywords.Type | // for finalizer and exceptions type names
    ClrTraceEventParser.Keywords.GC     // garbage collector details
    )
);

그나저나, CLR ETW 제공자(Microsoft-Windows-DotNETRuntime)가 지원하는 이벤트 종류를 보면 "Exception"이 있는데... 그렇다면 혹시 이것을 이용해 procdump로,

try/catch로 조용히 사라진 예외를 파악하고 싶다면?
; https://www.sysnet.pe.kr/2/0/10965

소개했던 역할을 구현할 수 있지 않을까요? ^^ 실제로 다음과 같이 코딩을 하면,

using System;

namespace ConsoleApp1
{
    class Program
    {
        static void Main(string[] args)
        {
            ClrEventSourceMonitor.Run();
        }
    }

    // Install-Package Microsoft.Diagnostics.Tracing.TraceEvent -Version 2.0.56
    class ClrEventSourceMonitor
    {
        static TextWriter Out = Console.Out;

        public static int Run()
        {
            // Today you have to be Admin to turn on ETW events (anyone can write ETW events).   
            if (!(TraceEventSession.IsElevated() ?? false))
            {
                Out.WriteLine("To turn on ETW events you need to be Administrator, please run from an Admin process.");
                Debugger.Break();
                return -1;
            }

            var sessionName = "SimpleMontitorSession";
            using (var session = new TraceEventSession(sessionName))
            {
                Console.CancelKeyPress += delegate (object sender, ConsoleCancelEventArgs e) { session.Dispose(); };

                var firstEventTimeMSec = new Dictionary<int, double>();

                session.Source.Clr.ExceptionStart += delegate (ExceptionTraceData data)
                {
                    Process process = null;

                    try
                    {
                        process = Process.GetProcessById(data.ProcessID);
                        string processName = process.ProcessName;

                        /* 원하지 않는 프로세스는 필터링 
                        if (processName == "devenv")
                        {
                            return;
                        }
                        */
                    } 
                    catch
                    {
                        return;
                    }

                    Console.WriteLine($"({data.ProcessID}:{data.TimeStamp}): {data.EventName} --> {data.ExceptionType} : {data.ExceptionMessage}");
                };

                // ClrTraceEventParser.ProviderGuid == {e13c0d23-ccbc-4e12-931b-d9cc2eee27e4}
                // ClrTraceEventParser.ProviderName == "Microsoft-Windows-DotNETRuntime"

                var restarted = session.EnableProvider(
                    ClrTraceEventParser.ProviderGuid, TraceEventLevel.Verbose,
                    (ulong)(ClrTraceEventParser.Keywords.Exception));

                if (restarted)      // Generally you don't bother with this warning, but for the demo we do. 
                    Out.WriteLine("The session {0} was already active, it has been restarted.", sessionName);

                Out.WriteLine("**** Start listening for events");

                session.Source.Process();
                Out.WriteLine();
                Out.WriteLine("Stopping the collection of events.");
            }
            return 0;
        }
    }
}
/*
C:\Windows\System32>logman query providers | findstr "Microsoft-Windows-DotNETRuntime"
Microsoft-Windows-DotNETRuntime          {E13C0D23-CCBC-4E12-931B-D9CC2EEE27E4}
Microsoft-Windows-DotNETRuntimeRundown   {A669021C-C450-4609-A035-5AF59AF4DF18}
*/

닷넷 프로세스 내에서 발생하는 (try/catch로 먹어버렸어도) 모든 예외를 출력해 줍니다.

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




첨언하자면, ETW의 특성상 "관리자 권한"으로 실행해야 하는 단점이 있습니다. 또한, 닷넷 4.0 이상의 응용 프로그램에서는 출력이 잘 나오지만,

(12640:2020-06-24 오후 4:29:43): Exception/Start --> System.ApplicationException : TEST

3.5 이하의 응용 프로그램은 예외가 발생했다는 것을 감지만 하고 ExceptionType, ExceptionMessage에 대한 정보는 구할 수 없습니다.

(6516:2020-06-24 오후 4:27:02): Exception/Start -->  :

마지막으로, 실시간으로 이벤트가 전달되지 않고 약간의 시간 차가 생깁니다. 무언가 realtime으로 이벤트를 전달받을 수 있도록 하는 옵션이 있을 것 같은데 딱히 TraceEventSession에서 관련 설정을 찾을 수가 없군요. (혹시 방법을 아시는 분은 덧글 부탁드립니다. ^^)




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

[연관 글]





[최초 등록일: ]
[최종 수정일: 8/28/2020 ]

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

비밀번호

댓글 쓴 사람
 




1  [2]  3  4  5  6  7  8  9  10  11  12  13  14  15  ...
NoWriterDateCnt.TitleFile(s)
12326정성태9/12/2020138개발 환경 구성: 516. Azure VM의 Network Adapter를 실수로 비활성화한 경우
12325정성태9/12/2020154개발 환경 구성: 515. OpenVPN - 재부팅 후 ICS(Internet Connection Sharing) 기능이 동작 안하는 문제
12324정성태9/11/2020160개발 환경 구성: 514. smigdeploy.exe를 이용한 Windows Server 2016에서 2019로 마이그레이션 방법
12323정성태9/11/2020138오류 유형: 649. Copy Database Wizard - The job failed. Check the event log on the destination server for details.
12322정성태9/11/2020166개발 환경 구성: 513. Azure VM의 RDP 접속 위치 제한
12321정성태9/24/2020138오류 유형: 648. netsh http add urlacl - Error: 183 Cannot create a file when that file already exists.
12320정성태9/11/2020196개발 환경 구성: 512. RDP(원격 데스크톱) 접속 시 비밀 번호를 한 번 더 입력해야 하는 경우
12319정성태9/10/2020155오류 유형: 647. smigdeploy.exe를 Windows Server 2016에서 실행할 때 .NET Framework 미설치 오류 발생
12318정성태9/9/2020154오류 유형: 646. OpenVPN - "TAP-Windows Adapter V9" 어댑터의 "Network cable unplugged" 현상
12317정성태9/10/2020163개발 환경 구성: 511. Beats 용 Kibana 기본 대시 보드 구성 방법
12316정성태9/8/2020327디버깅 기술: 170. WinDbg Preview 버전부터 닷넷 코어 3.0 이후의 메모리 덤프에 대해 sos.dll 자동 로드
12315정성태9/7/2020289개발 환경 구성: 510. Logstash - FileBeat을 이용한 IIS 로그 처리 [2]
12314정성태9/7/2020185오류 유형: 645. IIS HTTPERR - Timer_MinBytesPerSecond, Timer_ConnectionIdle 로그
12313정성태9/7/2020341개발 환경 구성: 509. Logstash - 사용자 정의 grok 패턴 추가를 이용한 IIS 로그 처리
12312정성태9/5/2020279개발 환경 구성: 508. Logstash 기본 사용법
12311정성태9/4/2020398.NET Framework: 937. C# - 간단하게 만들어 보는 리눅스의 nc(netcat) 프로그램
12310정성태9/3/2020186오류 유형: 644. Windows could not start the Elasticsearch 7.9.0 (elasticsearch-service-x64) service on Local Computer.
12309정성태9/3/2020236개발 환경 구성: 507. Elasticsearch 6.6부터 기본 추가된 한글 형태소 분석기 노리(nori) 사용법
12308정성태9/2/2020295개발 환경 구성: 506. Windows - 단일 머신에서 단일 바이너리로 여러 개의 ElasticSearch 노드를 실행하는 방법
12307정성태9/2/2020173오류 유형: 643. curl - json_parse_exception / Invalid UTF-8 start byte
12306정성태9/1/2020180오류 유형: 642. SQL Server 시작 오류 - error code 10013
12305정성태9/24/2020236Windows: 172. "Administered port exclusions"이 아닌 포트 범위 항목을 삭제하는 방법
12304정성태9/1/2020281개발 환경 구성: 505. 윈도우 - (네트워크 어댑터의 우선 순위로 인한) 열거되는 IP 주소 순서를 조정하는 방법
12303정성태8/30/2020267개발 환경 구성: 504. ETW - 닷넷 프레임워크 기반의 응용 프로그램을 위한 명령행 도구 etrace 소개
12302정성태8/30/2020198.NET Framework: 936. C# - ETW 관련 Win32 API 사용 예제 코드 (5) - Private Logger파일 다운로드1
12301정성태8/30/2020162오류 유형: 641. error MSB4044: The "Fody.WeavingTask" task was not given a value for the required parameter "IntermediateDir".
1  [2]  3  4  5  6  7  8  9  10  11  12  13  14  15  ...