Microsoft MVP성태의 닷넷 이야기
개발 환경 구성: 383. BenchmarkDotNet 사용 시 주의 사항 [링크 복사], [링크+제목 복사],
조회: 20545
글쓴 사람
정성태 (techsharer at outlook.com)
홈페이지
첨부 파일
 

BenchmarkDotNet 사용 시 주의 사항

BenchmarkDotNet으로,

BenchmarkDotNet 라이브러리 소개
; https://www.sysnet.pe.kr/2/0/11547

struct 타입을 좀 더 테스트해봤습니다.

C# 7.2의 특징 - GC 및 메모리 복사 방지를 위한 struct 타입 개선
; https://www.sysnet.pe.kr/2/0/11546

성능 비교를 위해 다음의 타입들을 생성하고,

ClassVector                 : class로 구현
StructVector                : struct로 구현
InStructVector              : StructVector이고, op_Addition 메서드의 인자를 in 처리
ReadonlyInStructVector      : InStructVector이고, readonly struct로 정의
ReadonlyInlineStructVector  : ReadonlyInStructVector이고, op_Addition에 AggressiveInlining 특성 부여

각각의 타입에 대해 op_Addition을 테스트하는 코드만을 Benchmark에 추가했습니다.

public class VectorBenchmark
{
    [Benchmark]
    public void ClassVectorTest()
    {
        var player = new ClassVector(10.0, 20.0, 30.0);
        var speed = new ClassVector(10.0, 20.0, 30.0);
        var result = player + speed;
    }

    [Benchmark]
    public void StructVectorTest()
    {
        var player = new StructVector(10.0, 20.0, 30.0);
        var speed = new StructVector(10.0, 20.0, 30.0);
        var result = player + speed;
    }

    [Benchmark]
    public void InStructVectorTest()
    {
        var player = new InStructVector(10.0, 20.0, 30.0);
        var speed = new InStructVector(10.0, 20.0, 30.0);
        var result = player + speed;
    }

    [Benchmark]
    public void ReadonlyInStructVectorTest()
    {
        var player = new ReadonlyInStructVector(10.0, 20.0, 30.0);
        var speed = new ReadonlyInStructVector(10.0, 20.0, 30.0);
        var result = player + speed;
    }

    [Benchmark]
    public void ReadonlyInlineStructVectorTest()
    {
        var player = new ReadonlyInlineStructVector(10.0, 20.0, 30.0);
        var speed = new ReadonlyInlineStructVector(10.0, 20.0, 30.0);
        var result = player + speed;
    }
}

결과는 이렇습니다.

// * Summary *

BenchmarkDotNet=v0.10.14, OS=Windows 10.0.17134
Intel Core i5-4670 CPU 3.40GHz (Haswell), 1 CPU, 4 logical and 4 physical cores
  [Host]     : .NET Framework 4.7.1 (CLR 4.0.30319.42000), 64bit RyuJIT-v4.7.3101.0
  DefaultJob : .NET Framework 4.7.1 (CLR 4.0.30319.42000), 64bit RyuJIT-v4.7.3101.0


                             Method |       Mean |     Error |    StdDev |
----------------------------------- |-----------:|----------:|----------:|
                    ClassVectorTest | 13.7698 ns | 0.1722 ns | 0.1611 ns |
                   StructVectorTest |  2.5874 ns | 0.0320 ns | 0.0299 ns |
                 InStructVectorTest | 10.5442 ns | 0.0892 ns | 0.0834 ns |
         ReadonlyInStructVectorTest | 10.2489 ns | 0.0843 ns | 0.0788 ns |
     ReadonlyInlineStructVectorTest |  0.5599 ns | 0.0106 ns | 0.0099 ns |

메서드가 inline 처리된 StructVectorTest, ReadonlyInlineStructVectorTest의 성능이 우세한 것은 당연해 보이고, 또한 그 둘 중에서도 복사가 발생하지 않는 ReadonlyInlineStructVectorTest의 성능이 좀 더 높게 나옵니다.

이 상태에서 테스트 코드마다 GetHashCode 호출을 추가했더니,

public void ...VectorTest()
{
    var player = new ...Vector(10.0, 20.0, 30.0);
    var speed = new ...Vector(10.0, 20.0, 30.0);
    var result = player + speed;

    result.GetHashCode();
}

단지 하나의 코드를 추가했을 뿐인데 이제 결과가 완전히 달라집니다.

                             Method |       Mean |     Error |    StdDev |
----------------------------------- |-----------:|----------:|----------:|
                    ClassVectorTest | 34.8412 ns | 0.2300 ns | 0.2151 ns |
                   StructVectorTest | 47.8031 ns | 0.3171 ns | 0.2966 ns |
                 InStructVectorTest | 53.4127 ns | 1.2505 ns | 1.4401 ns |
         ReadonlyInStructVectorTest | 52.1917 ns | 0.3648 ns | 0.3412 ns |
     ReadonlyInlineStructVectorTest | 39.5150 ns | 0.2622 ns | 0.2452 ns |

확인은 해보지 않았지만, GetHashCode 시 값 형식에서 object.GetHashCode 호출로 인한 내부 박싱이 발생해 힙을 사용하도록 변경된 것이 아닌가 예상됩니다. 일단 그건 그렇다 치고, 문제는 모든 Vector 코드에 GetHashCode를 다음과 같이 직접 구현했을 때 발생합니다.

public override int GetHashCode()
{
    return (int)(_x + _y + _z);
}

그럼, 각각의 결과물들이 다음과 같이 바뀝니다.

// GetHashCode() 추가
                             Method |       Mean |     Error |    StdDev |
----------------------------------- |-----------:|----------:|----------:|
                    ClassVectorTest | 15.7360 ns | 0.1863 ns | 0.1651 ns |
                   StructVectorTest | 10.0317 ns | 0.0746 ns | 0.0623 ns |
                 InStructVectorTest | 12.0021 ns | 0.0687 ns | 0.0643 ns |
         ReadonlyInStructVectorTest | 11.8757 ns | 0.1092 ns | 0.1022 ns |
     ReadonlyInlineStructVectorTest |  0.0000 ns | 0.0000 ns | 0.0000 ns |

ReadonlyInlineStructVectorTest의 결과가 놀라운데요, 어떻게 저럴 수 있을까요? 저 때의 JIT 코드를 windbg로 살펴보면 다음과 같이 나옵니다.

0:000> !DumpMD /d 00007ffa11f47790
Method Name:  ConsoleApp1.VectorBenchmark.ReadonlyInlineStructVectorTest()
Class:        00007ffa11f54858
MethodTable:  00007ffa11f477a8
mdToken:      0000000006000007
Module:       00007ffa11f470d0
IsJitted:     yes
CodeAddr:     00007ffa11ea6450
Transparency: Critical

0:000> !U /d 00007ffa11ea6450
Normal JIT generated code
ConsoleApp1.VectorBenchmark.ReadonlyInlineStructVectorTest()
Begin 00007ffa11ea6450, size 1
>>> 00007ffa`11ea6450 c3              ret

보는 바와 같이 정상적인 테스트 코드를 생성하지 못하고 ret만 포함하는 메서드로 테스트하고 있는 것입니다. 따라서 BenchmarkDotNet으로 성능 측정을 할 때는 그 자체의 버그나 Release 모드에서의 최적화에 따른 의도치 않은 코드 삭제를 주의해야 합니다.

게다가 전통적인 Stopwatch를 이용할 때는 Visual Studio 내에서 disassembly 코드를 보며 곧바로 확인할 수 있는 여지가 있는데, BenchmarkDotNet으로 하게 되면 ConsoleApp1.exe의 자식 프로세스로 임시 exe 프로세스를 생성시켜 성능 측정을 하기 때문에 그런 부분을 파헤치기가 불편한 점도 있습니다.




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







[최초 등록일: ]
[최종 수정일: 6/14/2018]

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

비밀번호

댓글 작성자
 




... 121  122  123  124  125  126  127  128  [129]  130  131  132  133  134  135  ...
NoWriterDateCnt.TitleFile(s)
1831정성태1/1/201528532디버깅 기술: 66. windbg 분석 사례 - cpu 100% 현상 (1) [1]
1830정성태1/1/201527569오류 유형: 265. svchost.exe 프로세스(IP Helper: IPHLPSVC)의 CPU 100% 현상
1829정성태12/16/201431339VC++: 86. Windows Vista부터 바뀐 Credential Provider 예제 분석 (2) [2]파일 다운로드1
1828정성태12/15/201427801VC++: 85. Windows Vista부터 바뀐 Credential Provider 예제 분석 (1) [4]파일 다운로드1
1827정성태12/12/201424150VC++: 84. CredUIPromptForWindowsCredentials Win32 API 사용법 정리
1826정성태12/11/201428627.NET Framework: 489. Socket.Listen에 전달된 backlog 인자의 의미 [6]
1825정성태12/11/201475794.NET Framework: 488. TCP 소켓 연결의 해제를 알 수 있는 방법 [10]파일 다운로드1
1824정성태12/10/201425841.NET Framework: 487. Socket.Receive 메서드의 SocketFlags.Peek 동작을 이용해 소켓 연결 유무를 확인? [8]파일 다운로드1
1823정성태12/10/201422777.NET Framework: 486. Java의 ScheduledExecutorService에 대응하는 C#의 System.Threading.Timer [2]
1822정성태12/3/201425431개발 환경 구성: 252. Xamarin 라이선스 관리 [8]
1821정성태12/1/201431876VS.NET IDE: 96. Visual Studio에서 /* ~ */ 주석을 위한 단축키(매크로)
1820정성태11/23/201420597.NET Framework: 485. Mono Profiler에서 IL 코드 변경이 가능할까? - 두 번째 이야기파일 다운로드1
1819정성태11/22/201420593오류 유형: 264. MSBuild 에러 - warning MSB3052: The parameter to the compiler is invalid,
1818정성태11/22/201423008개발 환경 구성: 251. NUMA 노드 수에 따른 IIS Worker Process(w3wp.exe) 설정 [1]
1817정성태11/22/201426163VS.NET IDE: 95. Macros for Visual Studio 2013 [1]
1816정성태11/20/201420849.NET Framework: 484. Mono Profiler에서 IL 코드 변경이 가능할까?
1815정성태11/18/201418849.NET Framework: 483. 코드로 살펴 보는 ETW의 활성화 시점 [2]
1814정성태11/18/201420390오류 유형: 263. Unable to find the requested .Net Framework Data Provider. It may not be installed.
1813정성태11/18/201422192오류 유형: 262. Build Events에 robocopy 작업이 있는 경우 "VCEnd exited with code 3" 오류 발생
1812정성태11/17/201419674.NET Framework: 482. ETW 자동 생성 코드의 VerificationException 예외 발생
1811정성태11/16/201420997.NET Framework: 481. Mono 내부의 문자열 처리 방식은 UTF-8
1810정성태11/15/201457629.NET Framework: 480. C# - 배치 파일 실행하고 출력 결과를 얻는 방법 [4]
1809정성태11/11/201422887.NET Framework: 479. Mono Profiler를 Unity의 Plugin으로 사용하는 방법
1808정성태11/11/201423218.NET Framework: 478. C# - 폴더 경로 문자열에서 "..", "." 표기를 고려한 최종 문자열을 얻는 방법 [2]
1807정성태11/10/201429341개발 환경 구성: 250. 서버용 Socket에서 사용하는 포트가 충돌한다면?파일 다운로드1
1806정성태11/10/201425133.NET Framework: 477. SeCreateGlobalPrivilege 특권과 WCF NamedPipe
... 121  122  123  124  125  126  127  128  [129]  130  131  132  133  134  135  ...