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
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
|
//=============================================================================
/**
* @file results.cpp
*
* $Id$
*
* Printing the results
*
*
* @author Aniruddha Gokhale
*/
//=============================================================================
#include "results.h"
#include "tao/debug.h"
#include "ace/Log_Msg.h"
Results::Results (void)
{
}
Results::~Results (void)
{
delete [] this->elapsed_time_;
}
void
Results::print_stats (void)
{
double
avg_real_time = 0,
avg_user_time = 0,
avg_system_time = 0,
cps; // calls per sec
CORBA::ULong i;
if (TAO_debug_level > 0 && this->error_count_ == 0)
{
//FUZZ: disable check_for_lack_ACE_OS
ACE_DEBUG ((LM_DEBUG,
"Iteration\tReal time (msec)\tUser time (msec)"
"\tSystem time (msec)\n\n"));
//FUZZ: enable check_for_lack_ACE_OS
for (i = 0; i < this->call_count_; i++)
{
this->elapsed_time_[i].real_time *= ACE_ONE_SECOND_IN_MSECS;
this->elapsed_time_[i].user_time *= ACE_ONE_SECOND_IN_MSECS;
this->elapsed_time_[i].system_time *= ACE_ONE_SECOND_IN_MSECS;
avg_real_time += this->elapsed_time_[i].real_time;
avg_user_time += this->elapsed_time_[i].user_time;
avg_system_time += this->elapsed_time_[i].system_time;
ACE_DEBUG ((LM_DEBUG,
"%u\t\t%0.06f\t\t%0.06f\t\t%0.06f\n",
i,
(this->elapsed_time_[i].real_time < 0.0?
0.0:this->elapsed_time_[i].real_time),
(this->elapsed_time_[i].user_time < 0.0?
0.0:this->elapsed_time_[i].user_time),
(this->elapsed_time_[i].system_time < 0.0?
0.0:this->elapsed_time_[i].system_time)));
} // end of for loop
// compute average
avg_real_time /= this->call_count_;
avg_user_time /= this->call_count_;
avg_system_time /= this->call_count_;
cps = 1000 / (avg_real_time < 0.01 ? 0.01 : avg_real_time);
ACE_DEBUG ((LM_DEBUG,
"\n*=*=*=*=*= Average *=*=*=*=*=*=\n"
"\treal_time\t= %0.06f ms,\n"
"\tuser_time\t= %0.06f ms,\n"
"\tsystem_time\t= %0.06f ms\n"
"\t%0.00f calls/second\n"
"*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=\n",
(avg_real_time < 0.0? 0.0:avg_real_time),
(avg_user_time < 0.0? 0.0:avg_user_time),
(avg_system_time < 0.0? 0.0:avg_system_time),
(cps < 0.0? 0.0 : cps)));
ACE_DEBUG ((LM_DEBUG,
"\t%d calls, %d errors\n"
"*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=\n",
this->call_count_,
this->error_count_));
}
else if (this->error_count_ != 0)
{
ACE_DEBUG ((LM_DEBUG,
"\tERROR %d faults in %d calls\n"
"*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=\n",
this->error_count_,
this->call_count_));
}
}
void
Results::print_exception (const char * /* call_name */)
{
//ACE_PRINT_EXCEPTION (call_name);
}
void
Results::start_timer (void)
{
this->timer_.start ();
}
void
Results::stop_timer (void)
{
this->timer_.stop ();
this->timer_.elapsed_time (this->elapsed_time_[this->call_count_-1]);
}
CORBA::ULong
Results::call_count (void)
{
return this->call_count_;
}
void
Results::call_count (CORBA::ULong c)
{
this->call_count_ = c;
}
CORBA::ULong
Results::error_count (void)
{
return this->error_count_;
}
void
Results::error_count (CORBA::ULong c)
{
this->error_count_ = c;
}
void
Results::iterations (CORBA::ULong iters)
{
this->elapsed_time_ = new ACE_Profile_Timer::ACE_Elapsed_Time [iters];
}
|
