1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
| //===-- TimerTest.cpp -------------------------------------------*- C++ -*-===//
//
// Part of the LLVM Project, under the Apache License v2.0 with LLVM Exceptions.
// See https://llvm.org/LICENSE.txt for license information.
// SPDX-License-Identifier: Apache-2.0 WITH LLVM-exception
//
//===----------------------------------------------------------------------===//
#include "lldb/Utility/StreamString.h"
#include "lldb/Utility/Timer.h"
#include "gtest/gtest.h"
#include <thread>
using namespace lldb_private;
TEST(TimerTest, CategoryTimes) {
Timer::ResetCategoryTimes();
{
static Timer::Category tcat("CAT1");
Timer t(tcat, ".");
std::this_thread::sleep_for(std::chrono::milliseconds(10));
}
StreamString ss;
Timer::DumpCategoryTimes(&ss);
double seconds;
ASSERT_EQ(1, sscanf(ss.GetData(), "%lf sec for CAT1", &seconds));
EXPECT_LT(0.001, seconds);
EXPECT_GT(0.1, seconds);
}
TEST(TimerTest, CategoryTimesNested) {
Timer::ResetCategoryTimes();
{
static Timer::Category tcat1("CAT1");
Timer t1(tcat1, ".");
std::this_thread::sleep_for(std::chrono::milliseconds(10));
// Explicitly testing the same category as above.
Timer t2(tcat1, ".");
std::this_thread::sleep_for(std::chrono::milliseconds(10));
}
StreamString ss;
Timer::DumpCategoryTimes(&ss);
double seconds;
// It should only appear once.
ASSERT_EQ(ss.GetString().count("CAT1"), 1U);
ASSERT_EQ(1, sscanf(ss.GetData(), "%lf sec for CAT1", &seconds));
EXPECT_LT(0.002, seconds);
EXPECT_GT(0.2, seconds);
}
TEST(TimerTest, CategoryTimes2) {
Timer::ResetCategoryTimes();
{
static Timer::Category tcat1("CAT1");
Timer t1(tcat1, ".");
std::this_thread::sleep_for(std::chrono::milliseconds(100));
static Timer::Category tcat2("CAT2");
Timer t2(tcat2, ".");
std::this_thread::sleep_for(std::chrono::milliseconds(10));
}
StreamString ss;
Timer::DumpCategoryTimes(&ss);
double seconds1, seconds2;
ASSERT_EQ(2, sscanf(ss.GetData(),
"%lf sec (total: %*fs; child: %*fs; count: %*d) for "
"CAT1%*[\n ]%lf sec for CAT2",
&seconds1, &seconds2))
<< "String: " << ss.GetData();
EXPECT_LT(0.01, seconds1);
EXPECT_GT(1, seconds1);
EXPECT_LT(0.001, seconds2);
EXPECT_GT(0.1, seconds2);
}
TEST(TimerTest, CategoryTimesStats) {
Timer::ResetCategoryTimes();
{
static Timer::Category tcat1("CAT1");
Timer t1(tcat1, ".");
std::this_thread::sleep_for(std::chrono::milliseconds(100));
static Timer::Category tcat2("CAT2");
{
Timer t2(tcat2, ".");
std::this_thread::sleep_for(std::chrono::milliseconds(10));
}
{
Timer t3(tcat2, ".");
std::this_thread::sleep_for(std::chrono::milliseconds(10));
}
}
// Example output:
// 0.105202764 sec (total: 0.132s; child: 0.027s; count: 1) for CAT1
// 0.026772798 sec (total: 0.027s; child: 0.000s; count: 2) for CAT2
StreamString ss;
Timer::DumpCategoryTimes(&ss);
double seconds1, total1, child1, seconds2;
int count1, count2;
ASSERT_EQ(
6, sscanf(ss.GetData(),
"%lf sec (total: %lfs; child: %lfs; count: %d) for CAT1%*[\n ]"
"%lf sec (total: %*fs; child: %*fs; count: %d) for CAT2",
&seconds1, &total1, &child1, &count1, &seconds2, &count2))
<< "String: " << ss.GetData();
EXPECT_NEAR(total1 - child1, seconds1, 0.002);
EXPECT_EQ(1, count1);
EXPECT_NEAR(child1, seconds2, 0.002);
EXPECT_EQ(2, count2);
}
|