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

ETW(Event Tracing for Windows)를 이용한 Finalizer 실행 감시

ETW를 이용해 예외를 먹은 것도 찾아냈는데,

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

이번엔 Finalizer를,

windbg로 확인하는 Finalizer를 가진 객체의 GC 과정
; https://www.sysnet.pe.kr/2/0/11510

감시해 보겠습니다. 이를 위해 필요한 것은 GCFinalizeObject 이벤트를 구독하면 되는데, 그 이벤트를 발생하려면 ClrTraceEventParser.Keywords.GC 항목을 활성화시키면 됩니다.

class Program
{
    static void Main(string[] args)
    {
        Thread t = new Thread(threadFunc);
        t.IsBackground = true;
        t.Start();

        ClrEventSourceMonitor.Run();
    }
}

class ClrEventSourceMonitor
{
    public static int Run()
    {
        // ...[생략]...

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

        using (TraceLogEventSource traceLogSource = TraceLog.CreateFromTraceEventSession(session))
        {
            // 이벤트 발생: ClrTraceEventParser.Keywords.GC
            traceLogSource.Clr.GCFinalizeObject += delegate (FinalizeObjectTraceData data)
            {
                if (_processId == 0 || data.ProcessID != _processId)
                {
                    return;
                }

                Out.WriteLine($"{data.TimeStamp}, {data.TypeID}");
            };

            traceLogSource.Process();
        }

        // ...[생략]...
    }
}

그리고, 원 글에서는 Finalizer를 소유한 TypeName을 알아내려면 ClrTraceEventParser.Keywords.Type을 구독하고 TypeBulkType 이벤트에서 미리 보관해 두라고 합니다.

public static int Run()
{
    // ...[생략]...

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

    using (TraceLogEventSource traceLogSource = TraceLog.CreateFromTraceEventSession(session))
    {
        traceLogSource.Clr.TypeBulkType += delegate (GCBulkTypeTraceData data)
        {
            if (data.ProcessID != processId)
            {
                return;
            }

            for (int currentType = 0; currentType < data.Count; currentType++)
            {
                GCBulkTypeValues value = data.Values(currentType);

                _types[value.TypeID] = value.TypeName;
            }
        };}

		// ...[생략]...

        traceLogSource.Process();
    }

    // ...[생략]...
}

그런데, 위의 작업을 굳이 하지 않아도 "ClrTraceEventParser.Keywords.GCHeapAndTypeNames" 옵션을 주는 것만으로 구할 수 있습니다. 그래서 다음과 같이만 구성하면 됩니다.

public static int Run()
{
    // ...[생략]...

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

    using (TraceLogEventSource traceLogSource = TraceLog.CreateFromTraceEventSession(session))
    {
        traceLogSource.Clr.GCFinalizeObject += delegate (FinalizeObjectTraceData data)
        {
            if (_processId == 0 || data.ProcessID != _processId)
            {
                return;
            }

            Out.WriteLine($"{data.TimeStamp}, {data.TypeID}");
        };

        traceLogSource.Process();
    }

    // ...[생략]...
}




일단 이렇게 구성하고 예제 프로젝트를 하나 돌리면,

using System;
using System.Diagnostics;

namespace TestApp
{
    class Program
    {
        static void Main(string[] args)
        {
            Console.WriteLine(Process.GetCurrentProcess().Id);

            for (int i = 0; i < 5; i++)
            {
                TestFinalizer t = new TestFinalizer();
                t.ToString();
            }

            GC.Collect();
        }
    }

    class TestFinalizer
    {
        ~TestFinalizer()
        {
            Console.WriteLine("~TestFinalizer Called!");
        }
    }
}

이런 출력 결과를 얻게 됩니다.

2020-06-26 오후 12:01:25, 7298040, 
2020-06-26 오후 12:01:25, 7298040, TestApp.TestFinalizer
2020-06-26 오후 12:01:25, 7298040, TestApp.TestFinalizer
2020-06-26 오후 12:01:25, 7298040, TestApp.TestFinalizer
2020-06-26 오후 12:01:25, 1725203260, 
2020-06-26 오후 12:01:25, 7298040, TestApp.TestFinalizer
2020-06-26 오후 12:01:25, 1813589520, 
2020-06-26 오후 12:01:25, 1813595776, 
2020-06-26 오후 12:01:25, 1813583760, 

위의 내용에서 재미있는 것은, 어떤 식으로든 GCFinalizeObject가 한 번은 발생해야 그 이후 이벤트에서 TypeName을 알 수 있다는 점입니다. 현실적인 기준에서 봤을 때, Finalizer 하나 정도가 불리는 것 정도로는 성능상 손실이 없으므로 그런 의미에서 본다면 이런 차이는 감안할 수 있을 것입니다.

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




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

[연관 글]


donaricano-btn



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

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

비밀번호

댓글 쓴 사람
 




... 16  17  18  19  20  21  22  23  [24]  25  26  27  28  29  30  ...
NoWriterDateCnt.TitleFile(s)
12273정성태7/18/20203856개발 환경 구성: 495. Azure - Location이 다른 웹/DB 서버의 경우 발생하는 성능 하락
12272정성태7/16/20201540.NET Framework: 929. (StrongName의 버전 구분이 필요 없는) .NET Core 어셈블리 바인딩 규칙파일 다운로드1
12271정성태7/16/20201915.NET Framework: 928. .NET Framework의 Strong-named 어셈블리 바인딩 (2) - 런타임에 바인딩 리디렉션파일 다운로드1
12270정성태7/16/20202476오류 유형: 633. SSL_CTX_use_certificate_file - error:140AB18F:SSL routines:SSL_CTX_use_certificate:ee key too small
12269정성태7/16/20201607오류 유형: 632. .NET Core 웹 응용 프로그램 - The process was terminated due to an unhandled exception.
12268정성태7/15/20201663오류 유형: 631. .NET Core 웹 응용 프로그램 오류 - HTTP Error 500.35 - ANCM Multiple In-Process Applications in same Process
12267정성태7/15/20202160.NET Framework: 927. C# - 윈도우 프로그램에서 Credential Manager를 이용한 보안 정보 저장파일 다운로드1
12266정성태7/14/20201642오류 유형: 630. 사용자 계정을 지정해 CreateService API로 서비스를 등록한 경우 "Error 1069: The service did not start due to a logon failure." 오류발생
12265정성태7/10/20201669오류 유형: 629. Visual Studio - 웹 애플리케이션 실행 시 "Unable to connect to web server 'IIS Express'." 오류 발생
12264정성태7/9/20202918오류 유형: 628. docker: Error response from daemon: Conflict. The container name "..." is already in use by container "...".
12261정성태7/9/20202217VS.NET IDE: 148. 윈도우 10에서 .NET Core 응용 프로그램을 리눅스 환경에서 실행하는 2가지 방법 - docker, WSL 2 [5]
12260정성태7/8/20201768.NET Framework: 926. C# - ETW를 이용한 ThreadPool 스레드 감시파일 다운로드1
12259정성태7/8/20201662오류 유형: 627. nvlddmkm.sys의 BAD_POOL_HEADER BSOD 문제
12258정성태7/8/20202512기타: 77. DataDog APM 간략 소개
12257정성태7/7/20201786.NET Framework: 925. C# - ETW를 이용한 Monitor Enter/Exit 감시파일 다운로드1
12256정성태7/7/20201867.NET Framework: 924. C# - Reflection으로 변경할 수 없는 readonly 정적 필드 [4]
12255정성태7/6/20201752.NET Framework: 923. C# - ETW(Event Tracing for Windows)를 이용한 Finalizer 실행 감시파일 다운로드1
12254정성태7/2/20201860오류 유형: 626. git - REMOTE HOST IDENTIFICATION HAS CHANGED!
12253정성태7/2/20202069.NET Framework: 922. C# - .NET ThreadPool의 Local/Global Queue파일 다운로드1
12252정성태7/2/20202493.NET Framework: 921. C# - I/O 스레드를 사용한 비동기 소켓 서버/클라이언트파일 다운로드2
12251정성태7/1/20202081.NET Framework: 920. C# - 파일의 비동기 처리 유무에 따른 스레드 상황 [1]파일 다운로드2
12250정성태6/30/20203069.NET Framework: 919. C# - 닷넷에서의 진정한 비동기 호출을 가능케 하는 I/O 스레드 사용법 [1]파일 다운로드1
12249정성태6/29/20201652오류 유형: 625. Microsoft SQL Server 2019 RC1 Setup - 설치 제거 시 Warning 26003 오류 발생
12248정성태6/29/20201627오류 유형: 624. SQL 서버 오류 - service-specific error code 17051
12247정성태6/29/20202062.NET Framework: 918. C# - 불린 형 상수를 반환값으로 포함하는 3항 연산자 사용 시 단축 표현 권장(IDE0075) [2]파일 다운로드1
12246정성태6/29/20201794.NET Framework: 917. C# - USB 관련 ETW(Event Tracing for Windows)를 이용한 키보드 입력을 감지하는 방법
... 16  17  18  19  20  21  22  23  [24]  25  26  27  28  29  30  ...