Microsoft MVP성태의 닷넷 이야기
.NET Framework: 225. 닷넷 네트워크 라이브러리의 트레이스 기능 [링크 복사], [링크+제목 복사]
조회: 12932
글쓴 사람
정성태 (techsharer at outlook.com)
홈페이지
첨부 파일

닷넷 네트워크 라이브러리의 트레이스 기능

아마도, .NET Reflector 등의 도구를 가지고 닷넷 소스 코드를 보신 분들이라면 네트워크 관련 코드 내부에 Logging 클래스가 있는 것을 기억할 것입니다. 일례로, 소켓의 경우에 Connect 메서드를 보면 다음과 같은 식으로 코드가 엮어져 있는데요.

public void Connect(IPAddress address, int port)
{
    if (s_LoggingEnabled)
    {
        Logging.Enter(Logging.Sockets, this, "Connect", address);
    }
    ...[생략]...
    this.Connect(remoteEP);
    if (s_LoggingEnabled)
    {
        Logging.Exit(Logging.Sockets, this, "Connect", (string) null);
    }
}

보시는 것처럼, Connect 기능의 앞뒤로 Logging.Enter/Exit 메서드가 호출됩니다. 좀 더 살펴보기 위해 Logging 클래스의 초기화 코드를 보면 더욱 흥미로운 것을 발견할 수 있는데요.

private static void InitializeLogging()
{
    lock (InternalSyncObject)
    {
        if (!s_LoggingInitialized)
        {
            bool flag = false;
            s_WebTraceSource = new NclTraceSource("System.Net");
            s_HttpListenerTraceSource = new NclTraceSource("System.Net.HttpListener");
            s_SocketsTraceSource = new NclTraceSource("System.Net.Sockets");
            s_CacheTraceSource = new NclTraceSource("System.Net.Cache");
            ...[생략]...
        }
    }
}

즉, 대부분의 통신 관련 코드에 대한 트레이스 기능이 활성화되어 있음을 알 수 있습니다. 그럼, BCL을 사용하는 개발자 입장에서 어떻게 Trace기능을 이용할 수 있을까요? 이에 관해서는 다음의 글에서 설명됩니다.

How to: Configure network tracing
; https://docs.microsoft.com/en-us/dotnet/framework/network-programming/how-to-configure-network-tracing

오호~~~ 그렇군요. 닷넷의 전형적인 TraceListener 기능이기 때문에 누구나 등록해서 네트워크 호출에 대한 모니터링이 가능하다는 이야기가 됩니다.

실습 삼아서, 간단한 확장을 해볼까요?

예를 들어서... 우리가 잘 알고 있는 HttpWebRequest 타입으로 시작해보겠습니다.

HttpWebRequest hwr = WebRequest.Create("http://blog.naver.com/techshare") as HttpWebRequest;
HttpWebResponse response = hwr.GetResponse() as HttpWebResponse;
response.Close();

잘 아시는 것처럼, HttpWebRequest 타입은 Connect라는 메서드가 명시적으로 제공되지 않습니다. 따라서 위와 같이 사용하는 경우, 실제 대상이 되는 웹 서버로의 'TCP 연결에 소요된 시간'을 계산하는 것이 불가능합니다. 꼭! 반드시! 'TCP 연결에 소요된 시간'을 알아내야 한다면, 기존의 지식으로는 이런 경우 어쩔 수 없이 HttpWebRequest에 대한 사용을 포기하고 TcpClient로 내려가서 HTTP 요청/응답에 대한 처리를 모두 구현해야 하는 불편함이 따르게 됩니다.

하지만 이제는 ^^ Logging이 내장되었다는 것을 알았으니, 사용자 정의 TraceListener로 간접적으로 알아낼 수 있습니다. 가령, 다음과 같이 별도의 TraceListener를 정의하고,

public class SocketTraceListener : TraceListener
{
    public override void Write(string message)
    {
    }

    public override void WriteLine(string message)
    {
    }

    public override void TraceEvent(TraceEventCache eventCache, string source, TraceEventType eventType, int id)
    {
        base.TraceEvent(eventCache, source, eventType, id);
    }

    public override void TraceEvent(TraceEventCache eventCache, string source, TraceEventType eventType, int id, string message)
    {
        base.TraceEvent(eventCache, source, eventType, id, message);
    }

    public override void TraceEvent(TraceEventCache eventCache, string source, TraceEventType eventType, int id, string format, params object[] args)
    {
        base.TraceEvent(eventCache, source, eventType, id, format, args);
    }
}

app.config에는 다음과 같이 설정을 추가해 주면 이제부터 System.Net.Sockets 영역의 네트워크 메서드에 대한 호출을 가로챌 수 있습니다.

<system.diagnostics>
    <trace autoflush="true" />
    <sources>
        <source name="System.Net.Sockets">
            <listeners>
                <add name="MyTrace"/>
            </listeners>
        </source>
    </sources>
    <sharedListeners>
        <add name="MyTrace" type="ConsoleApplication1.SocketTraceListener, ConsoleApplication1" />
    </sharedListeners>
    <switches>
        <add name="System.Net.Sockets" value="Verbose" />
    </switches>
</system.diagnostics>

Connect가 호출될 때의 메서드 수행을 감시해 보면 TraceEvent의 message 인자로 다음과 같이 넘어오는 것을 확인할 수 있습니다.

Logging.Enter - "[9940] Socket#49385318::Connect(23:80#397718426)"
Logging.Exit - "[9940] Exiting Socket#49385318::Connect() "

자, 그럼 실제적인 이야기는 끝난 것 같군요. 이제 위의 메시지를 인식하는 코드를 TraceEvent 메서드에 넣어두고 Connect 메서드가 수행된 시간만을 반환해주는 "ConnectionTime"이라는 속성을 제공해 주면 '연결에 걸린 시간'을 구해올 수 있습니다.

==== SocketTraceListener.cs ====
public override void TraceEvent(TraceEventCache eventCache, string source, TraceEventType eventType, int id, string message)
{
    ...[생략]...
    switch (source)
    {
        case "System.Net.Sockets":
            if (message.IndexOf("::Connect") != -1)
            {
                if (message.IndexOf("Exiting Socket") != -1)
                {
                    connectionTime = new TimeSpan(DateTime.Now.Ticks - startConnect);
                }
                else
                {
                    startConnect = DateTime.Now.Ticks;
                }
            }
                    
            break;
    }
    base.TraceEvent(eventCache, source, eventType, id, message);
}

==== Program.cs ====
static void Main(string[] args)
{
    SocketTraceListener.TraceThread = Thread.CurrentThread.ManagedThreadId;

    HttpWebRequest hwr = WebRequest.Create("http://blog.naver.com/techshare") as HttpWebRequest;
    HttpWebResponse response = hwr.GetResponse() as HttpWebResponse;
    response.Close();

    Console.WriteLine("blog.naver.com - ConnectionTime: " + SocketTraceListener.ConnectionTime.ToString());
}

==== 실행 결과 ====
D:\...[생략]...\ConsoleApplication1\bin\Debug>ConsoleApplication1.exe
blog.naver.com - ConnectionTime: 00:00:00.0140008

첨부된 파일은 위의 예제 코드를 담고 있습니다.




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

[연관 글]






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

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

비밀번호

댓글 작성자
 




[1]  2  3  4  5  6  7  8  9  10  11  12  13  14  15  ...
NoWriterDateCnt.TitleFile(s)
13117정성태8/17/202269.NET Framework: 2042. C# 11 - 파일 범위 내에서 유효한 타입 정의 (File-local types)파일 다운로드1
13116정성태8/4/2022186.NET Framework: 2041. C# - Socket.Close 시 Socket.Receive 메서드에서 예외가 발생하는 문제파일 다운로드1
13115정성태8/3/2022271.NET Framework: 2040. C# - ValueTask와 Task의 성능 비교파일 다운로드1
13114정성태8/2/2022252.NET Framework: 2039. C# - Task와 비교해 본 ValueTask 사용법파일 다운로드1
13113정성태7/31/2022248.NET Framework: 2038. C# 11 - Span 타입에 대한 패턴 매칭 (Pattern matching on ReadOnlySpan<char>)
13112정성태7/30/2022283.NET Framework: 2037. C# 11 - 목록 패턴(List patterns)파일 다운로드1
13111정성태7/29/2022251.NET Framework: 2036. C# 11 - IntPtr/UIntPtr과 nint/nuint의 통합파일 다운로드1
13110정성태7/27/2022291.NET Framework: 2035. C# 11 - 새로운 연산자 ">>>" (Unsigned Right Shift)파일 다운로드1
13109정성태7/27/2022227VS.NET IDE: 177. 비주얼 스튜디오 2022를 이용한 (소스 코드가 없는) 닷넷 모듈 디버깅 - "외부 원본(External Sources)"
13108정성태7/26/2022170Linux: 53. container에 실행 중인 Golang 프로세스를 디버깅하는 방법
13107정성태7/25/2022136Linux: 52. Debian/Ubuntu 계열의 docker container에서 자주 설치하게 되는 명령어
13106정성태7/24/2022113오류 유형: 819. 닷넷 6 프로젝트의 "Conditional compilation symbols" 기본값 오류
13105정성태7/23/2022237.NET Framework: 2034. .NET Core/5+ 환경에서 (프로젝트가 아닌) C# 코드 파일을 입력으로 컴파일하는 방법 - 두 번째 이야기 [1]
13104정성태7/23/2022212Linux: 51. WSL - init에서 systemd로 전환하는 방법
13103정성태7/22/2022114오류 유형: 818. WSL - systemd-genie와 관련한 2가지(systemd-remount-fs.service, multipathd.socket) 에러
13102정성태7/19/2022197.NET Framework: 2033. .NET Core/5+에서는 구할 수 없는 HttpRuntime.AppDomainAppId
13101정성태7/15/2022165[내용 예약] (비어 있는 글)
13100정성태7/15/2022282.NET Framework: 2032. C# 11 - shift 연산자 재정의에 대한 제약 완화 (Relaxing Shift Operator)
13099정성태7/14/2022674.NET Framework: 2031. C# 11 - 사용자 정의 checked 연산자파일 다운로드1
13098정성태7/13/2022203개발 환경 구성: 647. Azure - scale-out 상태의 App Service에서 특정 인스턴스에 요청을 보내는 방법 [1]
13097정성태7/12/2022156오류 유형: 817. Golang - binary.Read: invalid type int32
13096정성태7/8/2022456.NET Framework: 2030. C# 11 - UTF-8 문자열 리터럴
13095정성태7/7/2022235Windows: 208. AD 도메인에 참여하지 않은 컴퓨터에서 Kerberos 인증을 사용하는 방법
13094정성태7/6/2022166오류 유형: 816. Golang - "short write" 오류 원인
13093정성태7/5/2022283.NET Framework: 2029. C# - HttpWebRequest로 localhost 접속 시 2초 이상 지연
[1]  2  3  4  5  6  7  8  9  10  11  12  13  14  15  ...