Microsoft MVP성태의 닷넷 이야기
.NET Framework: 926. C# - ETW를 이용한 ThreadPool 스레드 감시 [링크 복사], [링크+제목 복사],
조회: 21244
글쓴 사람
정성태 (techsharer at outlook.com)
홈페이지
첨부 파일

(시리즈 글이 2개 있습니다.)
.NET Framework: 651. C# - 특정 EXE 프로세스를 종료시킨 EXE를 찾아내는 방법
; https://www.sysnet.pe.kr/2/0/11172

.NET Framework: 926. C# - ETW를 이용한 ThreadPool 스레드 감시
; https://www.sysnet.pe.kr/2/0/12260




C# - ETW를 이용한 ThreadPool 스레드 감시

ETW를 이용해 ThreadPool을 감시하는 것은 1) Worker 스레드를 위한 ThreadPoolWorkerThreadStart, ThreadPoolWorkerThreadStop 이벤트와 2) I/O 스레드를 위한 IOThreadCreationStart, IOThreadCreationStop 이벤트를 구독하면 됩니다.

using (var session = new TraceEventSession(sessionName, null))
{
    var restarted = session.EnableProvider(
        ClrTraceEventParser.ProviderGuid, TraceEventLevel.Verbose,
        (ulong)(ClrTraceEventParser.Keywords.Threading));

    Console.CancelKeyPress += delegate (object sender, ConsoleCancelEventArgs e) { session.Dispose(); };

    using (TraceLogEventSource traceLogSource = TraceLog.CreateFromTraceEventSession(session))
    {
        traceLogSource.Clr.ThreadPoolWorkerThreadStart += delegate (ThreadPoolWorkerThreadTraceData data)
        {
            if (data.ProcessID != _processId)
            {
                return;
            }

            Console.WriteLine($"[{data.TimeStamp}] {data.ThreadID} Worker Start");
        };

        traceLogSource.Clr.ThreadPoolWorkerThreadStop += delegate (ThreadPoolWorkerThreadTraceData data)
        {
            if (data.ProcessID != _processId)
            {
                return;
            }

            Console.WriteLine($"[{data.TimeStamp}] {data.ThreadID} Worker Stop");
        };

        traceLogSource.Clr.IOThreadCreationStart += delegate (IOThreadTraceData data)
        {
            if (data.ProcessID != _processId)
            {
                return;
            }

            Console.WriteLine($"[{data.TimeStamp}] {data.ThreadID} IO Start");
        };

        traceLogSource.Clr.IOThreadCreationStop += delegate (IOThreadTraceData data)
        {
            if (data.ProcessID != _processId)
            {
                return;
            }

            Console.WriteLine($"[{data.TimeStamp}] {data.ThreadID} IO Stop");
        };

        traceLogSource.Process();
    }

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

테스트할 수 있는 예제로 다음과 같이 Worker 스레드와 I/O 스레드를 사용하는 코드를 넣고,

using System;
using System.Diagnostics;
using System.IO;
using System.Threading;
using System.Threading.Tasks;

namespace TestApp
{
    class Program
    {
        static async Task Main(string[] args)
        {
            Debug.Assert(ThreadPool.SetMinThreads(2, 1));
            Debug.Assert(ThreadPool.SetMaxThreads(4, 4)); // 4개로 제한

            Thread.Sleep(5000);
            Console.WriteLine(Process.GetCurrentProcess().Id);

            for (int i = 0; i < 4; i++) 
            {
                ThreadPool.QueueUserWorkItem(async (arg) =>
                {
                    // 이 코드는 닷넷 프레임워크 환경에서 테스트한 것입니다. (참고: 닷넷 런타임에 따라 달라지는 AppDomain.GetCurrentThreadId의 반환값)
                    Console.WriteLine($"[{DateTime.Now}] {AppDomain.GetCurrentThreadId()} {Thread.CurrentThread.ManagedThreadId} WorkerThread: " + arg);
                    await ReadFileAsync();
                    Console.WriteLine($"[{DateTime.Now}] {AppDomain.GetCurrentThreadId()} {Thread.CurrentThread.ManagedThreadId} WorkerThread: " + arg + ": End");
                }, i);
            }

            Console.ReadLine();
        }

        static async Task ReadFileAsync()
        {
            string filePath = typeof(Program).Assembly.Location;
            FileStream fs = new FileStream(filePath, FileMode.Open, FileAccess.Read, FileShare.ReadWrite, 4096, true);
            byte[] buf = new byte[1024];
            await fs.ReadAsync(buf, 0, buf.Length);

            fs.Dispose(); 
            Console.WriteLine($"[{DateTime.Now}] {AppDomain.GetCurrentThreadId()} {Thread.CurrentThread.ManagedThreadId} Done");
        }
    }
}

실행해 보면,

[2020-07-08 오전 9:04:02] 44576 3 WorkerThread: 0
[2020-07-08 오전 9:04:02] 27876 6 WorkerThread: 3
[2020-07-08 오전 9:04:02] 24556 5 WorkerThread: 2
[2020-07-08 오전 9:04:02] 11376 4 WorkerThread: 1
[2020-07-08 오전 9:04:02] 24556 5 Done
[2020-07-08 오전 9:04:02] 11376 4 Done
[2020-07-08 오전 9:04:02] 11376 4 WorkerThread: 0: End
[2020-07-08 오전 9:04:02] 11376 4 Done
[2020-07-08 오전 9:04:02] 11376 4 WorkerThread: 2: End
[2020-07-08 오전 9:04:02] 27876 6 Done
[2020-07-08 오전 9:04:02] 27876 6 WorkerThread: 3: End
[2020-07-08 오전 9:04:02] 24556 5 WorkerThread: 1: End

위의 상황에 대한 ETW 모니터링 결과가 다소 실망스럽습니다.

[2020-07-08 오전 9:04:02] 44576 Worker Start
[2020-07-08 오전 9:04:02] 11376 Worker Start
[2020-07-08 오전 9:04:02] 24556 Worker Start
[2020-07-08 오전 9:04:02] 27876 Worker Start
[2020-07-08 오전 9:04:02] 11376 IO Start
[2020-07-08 오전 9:04:02] 24556 IO Start
[2020-07-08 오전 9:04:02] 44576 IO Start
[2020-07-08 오전 9:04:17] 55484 IO Stop
[2020-07-08 오전 9:04:17] 53212 IO Stop
[2020-07-08 오전 9:04:22] 44576 Worker Stop
[2020-07-08 오전 9:04:22] 24556 Worker Stop
[2020-07-08 오전 9:04:22] 27876 Worker Stop
[2020-07-08 오전 9:04:22] 11376 Worker Stop

보는 바와 같이 IO Start/Stop의 짝이 안 맞을뿐더러, 테스트 코드를 약간 바꿔서,

static async Task Main(string[] args)
{
    Debug.Assert(ThreadPool.SetMinThreads(2, 1));
    Debug.Assert(ThreadPool.SetMaxThreads(4, 4)); // 4개로 제한

    Thread.Sleep(5000);
    Console.WriteLine(Process.GetCurrentProcess().Id);

    for (int i = 0; i < 4; i++) 
    {
        ThreadPool.QueueUserWorkItem(async (arg) =>
        {
            Console.WriteLine($"[{DateTime.Now}] {AppDomain.GetCurrentThreadId()} {Thread.CurrentThread.ManagedThreadId} WorkerThread: " + arg);
            ReadFile();
            Console.WriteLine($"[{DateTime.Now}] {AppDomain.GetCurrentThreadId()} {Thread.CurrentThread.ManagedThreadId} WorkerThread: " + arg + ": End");
        }, i);
    }

    Console.ReadLine();
}

static void ReadFile()
{
    string filePath = typeof(Program).Assembly.Location;
    FileStream fs = new FileStream(filePath, FileMode.Open, FileAccess.Read, FileShare.ReadWrite, 4096, true);
    byte[] buf = new byte[1024];

    fs.BeginRead(buf, 0, buf.Length, (obj) =>
    {
        IAsyncResult result = obj as IAsyncResult;
        fs.EndRead(result);

        fs.Dispose();
        Console.WriteLine($"[{DateTime.Now}] {AppDomain.GetCurrentThreadId()} {Thread.CurrentThread.ManagedThreadId} Done");
    }, fs);
}

실행해 보면, BeginRead의 콜백 메서드가 출력한 I/O 스레드의 thread id가,

[2020-07-08 오전 9:18:13] 9180 5 WorkerThread: 1
[2020-07-08 오전 9:18:13] 38904 6 WorkerThread: 2
[2020-07-08 오전 9:18:13] 20752 4 WorkerThread: 3
[2020-07-08 오전 9:18:13] 53232 3 WorkerThread: 0
[2020-07-08 오전 9:18:13] 38904 6 WorkerThread: 2: End
[2020-07-08 오전 9:18:13] 20752 4 WorkerThread: 3: End
[2020-07-08 오전 9:18:13] 9180 5 WorkerThread: 1: End
[2020-07-08 오전 9:18:13] 53232 3 WorkerThread: 0: End
[2020-07-08 오전 9:18:13] 20752 4 Done
[2020-07-08 오전 9:18:13] 20752 4 Done
[2020-07-08 오전 9:18:13] 53232 3 Done
[2020-07-08 오전 9:18:13] 9180 5 Done

ETW 모니터링의 IO Stop 이벤트에서는 연결이 안 됩니다.

[2020-07-08 오전 9:18:13] 53232 Worker Start
[2020-07-08 오전 9:18:13] 9180 Worker Start
[2020-07-08 오전 9:18:13] 38904 Worker Start
[2020-07-08 오전 9:18:13] 20752 Worker Start
[2020-07-08 오전 9:18:13] 9180 IO Start
[2020-07-08 오전 9:18:13] 20752 IO Start
[2020-07-08 오전 9:18:13] 38904 IO Start
[2020-07-08 오전 9:18:13] 53232 IO Start
[2020-07-08 오전 9:18:28] 3664 IO Stop
[2020-07-08 오전 9:18:28] 13716 IO Stop
[2020-07-08 오전 9:18:28] 3980 IO Stop
[2020-07-08 오전 9:18:33] 38904 Worker Stop
[2020-07-08 오전 9:18:33] 9180 Worker Stop
[2020-07-08 오전 9:18:33] 53232 Worker Stop
[2020-07-08 오전 9:18:33] 20752 Worker Stop

위의 결과만으로는, IO Start와 IO Stop의 정보를 연결할 단서가 없어 모니터링으로써의 효과가 거의 없습니다. 그래도 일단 이번에는 여기까지라도 알아두고. ^^

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




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







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

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

비밀번호

댓글 작성자
 




... 46  47  48  49  [50]  51  52  53  54  55  56  57  58  59  60  ...
NoWriterDateCnt.TitleFile(s)
12780정성태8/13/202119235.NET Framework: 1088. C# - 버스 노선 및 위치 정보 조회 API 사용을 위한 기초 라이브러리 [2]
12779정성태8/13/202121644개발 환경 구성: 596. 공공 데이터 포털에서 버스 노선 및 위치 정보 조회 API 사용법
12778정성태8/12/202117128오류 유형: 755. PyCharm - "Manage Repositories"의 목록이 나오지 않는 문제
12777정성태8/12/202119478오류 유형: 754. Visual Studio - Input or output cannot be redirected because the specified file is invalid.
12776정성태8/12/202117610오류 유형: 753. gunicorn과 uwsgi 함께 사용 시 ERR_CONNECTION_REFUSED
12775정성태8/12/202131595스크립트: 22. 파이썬 - 윈도우 환경에서 개발한 Django 앱을 WSL 환경의 gunicorn을 이용해 실행
12774정성태8/11/202121609.NET Framework: 1087. C# - Collection 개체의 다중 스레드 접근 시 "Operations that change non-concurrent collections must have exclusive access" 예외 발생
12773정성태8/11/202120968개발 환경 구성: 595. PyCharm - WSL과 연동해 Django App을 윈도우에서 리눅스 대상으로 개발
12772정성태8/11/202121035스크립트: 21. 파이썬 - 윈도우 환경에서 개발한 Django 앱을 WSL 환경의 uwsgi를 이용해 실행 [1]
12771정성태8/11/202119875Windows: 196. "Microsoft Windows Subsystem for Linux Background Host" / "Vmmem"을 종료하는 방법
12770정성태8/11/202119028.NET Framework: 1086. C# - Windows Forms 응용 프로그램의 자식 컨트롤 부하파일 다운로드1
12769정성태8/11/202115822오류 유형: 752. Python - ImportError: No module named pip._internal.cli.main 두 번째 이야기
12768정성태8/10/202117052.NET Framework: 1085. .NET 6에 포함된 신규 BCL API [1]파일 다운로드1
12767정성태8/10/202117833오류 유형: 752. Python - ImportError: No module named pip._internal.cli.main
12766정성태8/9/202116199Java: 32. closing inbound before receiving peer's close_notify
12765정성태8/9/202118215Java: 31. Cannot load JDBC driver class 'org.mysql.jdbc.Driver'
12764정성태8/9/202156314Java: 30. XML document from ServletContext resource [/WEB-INF/applicationContext.xml] is invalid
12763정성태8/9/202120931Java: 29. java.lang.NullPointerException - com.mysql.jdbc.ConnectionImpl.getServerCharset
12762정성태8/8/202122885Java: 28. IntelliJ - Unable to open debugger port 오류
12761정성태8/8/202119144Java: 27. IntelliJ - java: package javax.inject does not exist [2]
12760정성태8/8/202115009개발 환경 구성: 594. 전용 "Command Prompt for ..." 단축 아이콘 만들기
12759정성태8/8/202121735Java: 26. IntelliJ + Spring Framework + 새로운 Controller 추가 [2]파일 다운로드1
12758정성태8/7/202121305오류 유형: 751. Error assembling WAR: webxml attribute is required (or pre-existing WEB-INF/web.xml if executing in update mode)
12757정성태8/7/202121876Java: 25. IntelliJ + Spring Framework 프로젝트 생성
12756정성태8/6/202118445.NET Framework: 1084. C# - .NET Core Web API 단위 테스트 방법 [1]파일 다운로드1
12755정성태8/5/202119974개발 환경 구성: 593. MSTest - 단위 테스트에 static/instance 유형의 private 멤버 접근 방법파일 다운로드1
... 46  47  48  49  [50]  51  52  53  54  55  56  57  58  59  60  ...