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)
12362정성태10/11/20201824VS.NET IDE: 151. Visual Studio 2019에 .NET 5 rc/preview 적용하는 방법
12361정성태10/11/20202605.NET Framework: 946. C# 9.0을 위한 개발 환경 구성
12360정성태10/8/20201751오류 유형: 666. The type or namespace name '...' does not exist in the namespace 'Microsoft.VisualStudio.TestTools' (are you missing an assembly reference?)
12359정성태10/7/20201997오류 유형: 665. Windows - 재부팅 후 iSCSI 연결이 끊기는 문제
12358정성태10/7/20201937오류 유형: 664. Web Deploy 설치 시 "A newer version of Microsoft Web Deploy 3.6 was found on this machine." 오류
12357정성태10/7/20201623오류 유형: 663. 이벤트 로그 - The storage optimizer couldn't complete retrim on New Volume
12356정성태10/7/20202539오류 유형: 662. ASP.NET Core와 500.19, 500.21 오류 (0x8007000d)
12355정성태10/3/20201584오류 유형: 661. Hyper-V Linux VM의 Internal 유형의 가상 Switch에 대한 IP 연결이 되지 않는 경우
12354정성태10/2/20201788오류 유형: 660. Web Deploy (msdeploy.axd) 실행 시 오류 기록 [1]
12353정성태10/2/20201963개발 환경 구성: 518. 비주얼 스튜디오에서 IIS 웹 서버로 "Web Deploy"를 이용해 배포하는 방법
12352정성태10/2/20202258개발 환경 구성: 517. Hyper-V Internal 네트워크에 NAT을 이용한 인터넷 연결 제공
12351정성태10/2/20202789오류 유형: 659. Nox 실행이 안 되는 경우 - Unable to bind to the underlying transport for ...
12350정성태9/25/20203273Windows: 175. 윈도우 환경에서 클라이언트 소켓의 최대 접속 수 [2]파일 다운로드1
12349정성태9/25/20201916Linux: 32. Ubuntu 20.04 - docker를 위한 tcp 바인딩 추가
12348정성태9/25/20201913오류 유형: 658. 리눅스 docker - Got permission denied while trying to connect to the Docker daemon socket at unix:///var/run/docker.sock
12347정성태9/25/20206584Windows: 174. WSL 2의 네트워크 통신 방법 [1]
12346정성태9/25/20201856오류 유형: 657. IIS - http://localhost 방문 시 Service Unavailable 503 오류 발생
12345정성태9/25/20201920오류 유형: 656. iisreset 실행 시 "Restart attempt failed." 오류가 발생하지만 웹 서비스는 정상적인 경우
12344정성태9/25/20202073Windows: 173. 서비스 관리자에 "IIS Admin Service"가 등록되어 있지 않다면?
12343정성태9/24/20203449.NET Framework: 945. C# - 닷넷 응용 프로그램에서 메모리 누수가 발생할 수 있는 패턴 [2]
12342정성태9/24/20202320디버깅 기술: 171. windbg - 인스턴스가 살아 있어 메모리 누수가 발생하고 있는지 확인하는 방법
12341정성태9/23/20202362.NET Framework: 944. C# - 인스턴스가 살아 있어 메모리 누수가 발생하고 있는지 확인하는 방법파일 다운로드1
12340정성태9/23/20202292.NET Framework: 943. WPF - WindowsFormsHost를 담은 윈도우 생성 시 메모리 누수
12339정성태9/21/20202027오류 유형: 655. 코어 모드의 윈도우는 GUI 모드의 윈도우로 교체가 안 됩니다.
12338정성태9/21/20201845오류 유형: 654. 우분투 설치 시 "CHS: Error 2001 reading sector ..." 오류 발생
12337정성태9/21/20201933오류 유형: 653. Windows - Time zone 설정을 바꿔도 반영이 안 되는 경우
... 16  17  18  19  20  21  22  23  [24]  25  26  27  28  29  30  ...