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 하나 정도가 불리는 것 정도로는 성능상 손실이 없으므로 그런 의미에서 본다면 이런 차이는 감안할 수 있을 것입니다.

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




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

[연관 글]





[최초 등록일: ]
[최종 수정일: 7/7/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)
12290정성태8/11/2020138.NET Framework: 931. C# - IP 주소에 따른 국가별 위치 확인파일 다운로드1
12289정성태8/6/2020109개발 환경 구성: 502. Portainer에 윈도우 컨테이너를 등록하는 방법
12288정성태8/5/202045오류 유형: 637. WCF - The protocol 'net.tcp' does not have an implementation of HostedTransportConfiguration type registered.
12287정성태8/5/202037오류 유형: 636. C# - libdl.so를 DllImport로 연결 시 docker container 내에서 System.DllNotFoundException 예외 발생
12286정성태8/5/202036개발 환경 구성: 501. .NET Core 용 container 이미지 만들 때 unzip이 필요한 경우
12285정성태8/4/202069오류 유형: 635. 윈도우 10 업데이트 - 0xc1900209 [2]
12284정성태8/4/202098디버깅 기술: 169. Hyper-V의 VM에 대한 메모리 덤프를 뜨는 방법
12283정성태8/3/202043디버깅 기술: 168. windbg - 필터 드라이버 확인하는 확장 명령어(!fltkd)
12282정성태8/2/202051디버깅 기술: 167. windbg 디버깅 사례: AppDomain 간의 static 변수 사용으로 인한 crash (2)
12281정성태8/2/2020162개발 환경 구성: 500. (PDB 연결이 없는) DLL의 소스 코드 디버깅을 dotPeek 도구로 해결하는 방법
12280정성태8/2/202059오류 유형: 634. 오라클 (평생) 무료 클라우드 VM 생성 후 SSH 접속 시 키 오류 발생
12279정성태7/29/202065개발 환경 구성: 499. 닷넷에서 접근해보는 InterSystems의 Cache 데이터베이스파일 다운로드1
12278정성태8/2/202065VS.NET IDE: 149. ("Binary was not built with debug information" 상태로) 소스 코드 디버깅이 안되는 경우
12277정성태8/2/2020101개발 환경 구성: 498. DEVPATH 환경 변수의 사용 예 - .NET Reflector의 (PDB 연결이 없는) DLL의 소스 코드 디버깅
12276정성태7/23/2020124.NET Framework: 930. 개발자를 위한 닷넷 어셈블리 바인딩 - DEVPATH 환경 변수
12275정성태7/28/2020101개발 환경 구성: 497. 닷넷에서 접근해보는 InterSystems의 IRIS Data Platform 데이터베이스파일 다운로드1
12274정성태7/21/202080개발 환경 구성: 496. Azure - Blob Storage Account의 Location 이전 방법파일 다운로드1
12273정성태7/20/2020208개발 환경 구성: 495. Azure - Location이 다른 웹/DB 서버의 경우 발생하는 성능 하락
12272정성태7/16/2020100.NET Framework: 929. (StrongName의 버전 구분이 필요 없는) .NET Core 어셈블리 바인딩 규칙파일 다운로드1
12271정성태7/16/202081.NET Framework: 928. .NET Framework의 Strong-named 어셈블리 바인딩 (2) - 런타임에 바인딩 리디렉션파일 다운로드1
12270정성태7/16/202075오류 유형: 633. SSL_CTX_use_certificate_file - error:140AB18F:SSL routines:SSL_CTX_use_certificate:ee key too small
12269정성태7/16/202070오류 유형: 632. .NET Core 웹 응용 프로그램 - The process was terminated due to an unhandled exception.
12268정성태7/15/202075오류 유형: 631. .NET Core 웹 응용 프로그램 오류 - HTTP Error 500.35 - ANCM Multiple In-Process Applications in same Process
12267정성태7/15/2020142.NET Framework: 927. C# - 윈도우 프로그램에서 Credential Manager를 이용한 보안 정보 저장파일 다운로드1
12266정성태7/14/2020165오류 유형: 630. 사용자 계정을 지정해 CreateService API로 서비스를 등록한 경우 "Error 1069: The service did not start due to a logon failure." 오류발생
[1]  2  3  4  5  6  7  8  9  10  11  12  13  14  15  ...