| line |
stmt |
bran |
cond |
sub |
pod |
time |
code |
|
1
|
|
|
|
|
|
|
package DashProfiler; |
|
2
|
|
|
|
|
|
|
|
|
3
|
1
|
|
|
1
|
|
19159
|
use strict; |
|
|
1
|
|
|
|
|
2
|
|
|
|
1
|
|
|
|
|
43
|
|
|
4
|
1
|
|
|
1
|
|
5
|
use warnings; |
|
|
1
|
|
|
|
|
2
|
|
|
|
1
|
|
|
|
|
48
|
|
|
5
|
|
|
|
|
|
|
|
|
6
|
|
|
|
|
|
|
our $VERSION = "1.13"; # $Revision: 48 $ |
|
7
|
|
|
|
|
|
|
|
|
8
|
|
|
|
|
|
|
=head1 NAME |
|
9
|
|
|
|
|
|
|
|
|
10
|
|
|
|
|
|
|
DashProfiler - efficiently collect call count and timing data aggregated by context |
|
11
|
|
|
|
|
|
|
|
|
12
|
|
|
|
|
|
|
=head1 SYNOPSIS |
|
13
|
|
|
|
|
|
|
|
|
14
|
|
|
|
|
|
|
The DashProfiler modules enable you to efficiently collect performance data |
|
15
|
|
|
|
|
|
|
by adding just a line of code to the functions or objects you want to monitor. |
|
16
|
|
|
|
|
|
|
|
|
17
|
|
|
|
|
|
|
Data is aggregated by context and optionally also by a granular time axis. |
|
18
|
|
|
|
|
|
|
|
|
19
|
|
|
|
|
|
|
See L for a general introduction. |
|
20
|
|
|
|
|
|
|
|
|
21
|
|
|
|
|
|
|
=head1 DESCRIPTION |
|
22
|
|
|
|
|
|
|
|
|
23
|
|
|
|
|
|
|
=head2 Performance |
|
24
|
|
|
|
|
|
|
|
|
25
|
|
|
|
|
|
|
DashProfiler is fast, very fast. Especially given the functionality and flexibility it offers. |
|
26
|
|
|
|
|
|
|
|
|
27
|
|
|
|
|
|
|
When you build DashProfiler, the test suite shows the performance on your |
|
28
|
|
|
|
|
|
|
system when you run "make test". On my system, for example it reports: |
|
29
|
|
|
|
|
|
|
|
|
30
|
|
|
|
|
|
|
t/02.sample.......ok 1/0 you're using perl 5.010000 on darwin-2level -O3 |
|
31
|
|
|
|
|
|
|
Average 'cold' sample overhead is 0.000021s (max 0.000104s, min 0.000019s) |
|
32
|
|
|
|
|
|
|
Average 'hot' sample overhead is 0.000017s (max 0.000102s, min 0.000016s) |
|
33
|
|
|
|
|
|
|
|
|
34
|
|
|
|
|
|
|
=head2 Apache mod_perl |
|
35
|
|
|
|
|
|
|
|
|
36
|
|
|
|
|
|
|
DashProfiler was designed to work well with Apache mod_perl in high volume production environments. |
|
37
|
|
|
|
|
|
|
|
|
38
|
|
|
|
|
|
|
Refer to L for details. |
|
39
|
|
|
|
|
|
|
|
|
40
|
|
|
|
|
|
|
=cut |
|
41
|
|
|
|
|
|
|
|
|
42
|
1
|
|
|
1
|
|
5
|
use Carp; |
|
|
1
|
|
|
|
|
5
|
|
|
|
1
|
|
|
|
|
94
|
|
|
43
|
1
|
|
|
1
|
|
22368
|
use Data::Dumper; |
|
|
1
|
|
|
|
|
16193
|
|
|
|
1
|
|
|
|
|
63
|
|
|
44
|
|
|
|
|
|
|
|
|
45
|
1
|
|
|
1
|
|
791
|
use DashProfiler::Core; |
|
|
1
|
|
|
|
|
3
|
|
|
|
1
|
|
|
|
|
14
|
|
|
46
|
|
|
|
|
|
|
|
|
47
|
|
|
|
|
|
|
my %profiles; |
|
48
|
|
|
|
|
|
|
my %precondition; |
|
49
|
|
|
|
|
|
|
|
|
50
|
|
|
|
|
|
|
=head1 PRIMARY METHODS |
|
51
|
|
|
|
|
|
|
|
|
52
|
|
|
|
|
|
|
=head2 add_profile |
|
53
|
|
|
|
|
|
|
|
|
54
|
|
|
|
|
|
|
DashProfiler->add_profile( 'my_profile_name' ); |
|
55
|
|
|
|
|
|
|
DashProfiler->add_profile( my_profile_name => { ... } ); |
|
56
|
|
|
|
|
|
|
$core = DashProfiler->add_core( my_profile_name => { ... } ); |
|
57
|
|
|
|
|
|
|
|
|
58
|
|
|
|
|
|
|
Calls DashProfiler::Core->new to create a new DashProfiler::Core object and |
|
59
|
|
|
|
|
|
|
then caches it, using the name as the key, so it can be refered to by name. |
|
60
|
|
|
|
|
|
|
|
|
61
|
|
|
|
|
|
|
See L for details of the arguments. |
|
62
|
|
|
|
|
|
|
|
|
63
|
|
|
|
|
|
|
=cut |
|
64
|
|
|
|
|
|
|
|
|
65
|
|
|
|
|
|
|
sub add_profile { |
|
66
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
67
|
0
|
0
|
|
|
|
|
croak "A profile called '$_[0]' already exists" if $profiles{$_[0]}; |
|
68
|
0
|
|
|
|
|
|
my $self = DashProfiler::Core->new(@_); |
|
69
|
0
|
|
|
|
|
|
$profiles{ $self->{profile_name} } = $self; |
|
70
|
0
|
|
|
|
|
|
return $self; |
|
71
|
|
|
|
|
|
|
} |
|
72
|
|
|
|
|
|
|
|
|
73
|
|
|
|
|
|
|
|
|
74
|
|
|
|
|
|
|
=head2 prepare |
|
75
|
|
|
|
|
|
|
|
|
76
|
|
|
|
|
|
|
$sampler = DashProfiler->prepare($profile_name, ...); |
|
77
|
|
|
|
|
|
|
|
|
78
|
|
|
|
|
|
|
Calls prepare(...) on the DashProfiler named by $profile_name. |
|
79
|
|
|
|
|
|
|
Returns a sampler code reference prepared to take samples. |
|
80
|
|
|
|
|
|
|
|
|
81
|
|
|
|
|
|
|
If no profile with that name exists then it will warn, but only once per name. |
|
82
|
|
|
|
|
|
|
|
|
83
|
|
|
|
|
|
|
=cut |
|
84
|
|
|
|
|
|
|
|
|
85
|
|
|
|
|
|
|
sub prepare { |
|
86
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
87
|
0
|
|
|
|
|
|
my $profile_name = shift; |
|
88
|
0
|
|
|
|
|
|
my $profile_ref = $profiles{$profile_name}; |
|
89
|
0
|
0
|
|
|
|
|
unless ($profile_ref) { # to catch spelling mistakes |
|
90
|
0
|
0
|
|
|
|
|
carp "No $class profiler called '$profile_name' exists" |
|
91
|
|
|
|
|
|
|
unless defined $profile_ref; |
|
92
|
0
|
|
|
|
|
|
$profiles{$profile_name} = 0; # only warn once |
|
93
|
0
|
|
|
|
|
|
return; |
|
94
|
|
|
|
|
|
|
}; |
|
95
|
0
|
|
|
|
|
|
return $profile_ref->prepare(@_); |
|
96
|
|
|
|
|
|
|
} |
|
97
|
|
|
|
|
|
|
|
|
98
|
|
|
|
|
|
|
|
|
99
|
|
|
|
|
|
|
=head2 profile_names |
|
100
|
|
|
|
|
|
|
|
|
101
|
|
|
|
|
|
|
@profile_names = DashProfiler->profile_names; |
|
102
|
|
|
|
|
|
|
|
|
103
|
|
|
|
|
|
|
Returns a list of all the profile names added via L. |
|
104
|
|
|
|
|
|
|
|
|
105
|
|
|
|
|
|
|
=cut |
|
106
|
|
|
|
|
|
|
|
|
107
|
|
|
|
|
|
|
sub profile_names { |
|
108
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
109
|
|
|
|
|
|
|
# return keys but skip 0 entries that might be added by prepare() |
|
110
|
0
|
|
|
|
|
|
return grep { $profiles{$_} } keys %profiles; |
|
|
0
|
|
|
|
|
|
|
|
111
|
|
|
|
|
|
|
} |
|
112
|
|
|
|
|
|
|
|
|
113
|
|
|
|
|
|
|
|
|
114
|
|
|
|
|
|
|
=head2 get_profile |
|
115
|
|
|
|
|
|
|
|
|
116
|
|
|
|
|
|
|
$core = DashProfiler->get_profile( $profile_name ); |
|
117
|
|
|
|
|
|
|
|
|
118
|
|
|
|
|
|
|
Returns the DashProfiler::Core object associated with that name. |
|
119
|
|
|
|
|
|
|
|
|
120
|
|
|
|
|
|
|
=cut |
|
121
|
|
|
|
|
|
|
|
|
122
|
|
|
|
|
|
|
sub get_profile { |
|
123
|
0
|
|
|
0
|
1
|
|
my ($self, $profile_name) = @_; |
|
124
|
0
|
|
|
|
|
|
return $profiles{$profile_name}; |
|
125
|
|
|
|
|
|
|
} |
|
126
|
|
|
|
|
|
|
|
|
127
|
|
|
|
|
|
|
|
|
128
|
|
|
|
|
|
|
=head2 profile_as_text |
|
129
|
|
|
|
|
|
|
|
|
130
|
|
|
|
|
|
|
$text = DashProfiler->profile_as_text( $profile_name ) |
|
131
|
|
|
|
|
|
|
|
|
132
|
|
|
|
|
|
|
Calls profile_as_text(...) on the DashProfiler named by $profile_name. |
|
133
|
|
|
|
|
|
|
Returns undef if no profile with that name exists. |
|
134
|
|
|
|
|
|
|
|
|
135
|
|
|
|
|
|
|
=cut |
|
136
|
|
|
|
|
|
|
|
|
137
|
|
|
|
|
|
|
sub profile_as_text { |
|
138
|
0
|
|
|
0
|
1
|
|
my $self = shift; |
|
139
|
0
|
|
|
|
|
|
my $profile_name = shift; |
|
140
|
0
|
0
|
|
|
|
|
my $profile_ref = $self->get_profile($profile_name) or return; |
|
141
|
0
|
|
|
|
|
|
return $profile_ref->profile_as_text(@_); |
|
142
|
|
|
|
|
|
|
} |
|
143
|
|
|
|
|
|
|
|
|
144
|
|
|
|
|
|
|
|
|
145
|
|
|
|
|
|
|
=head1 METHODS AFFECTING ALL PROFILES |
|
146
|
|
|
|
|
|
|
|
|
147
|
|
|
|
|
|
|
=head2 all_profiles_as_text |
|
148
|
|
|
|
|
|
|
|
|
149
|
|
|
|
|
|
|
@text = DashProfiler->all_profiles_as_text |
|
150
|
|
|
|
|
|
|
|
|
151
|
|
|
|
|
|
|
Calls profile_as_text() on all profiles, ordered by name. |
|
152
|
|
|
|
|
|
|
|
|
153
|
|
|
|
|
|
|
=cut |
|
154
|
|
|
|
|
|
|
|
|
155
|
|
|
|
|
|
|
sub all_profiles_as_text { |
|
156
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
157
|
0
|
|
|
|
|
|
return map { $profiles{$_}->profile_as_text() } sort keys %profiles; |
|
|
0
|
|
|
|
|
|
|
|
158
|
|
|
|
|
|
|
} |
|
159
|
|
|
|
|
|
|
|
|
160
|
|
|
|
|
|
|
|
|
161
|
|
|
|
|
|
|
=head2 dump_all_profiles |
|
162
|
|
|
|
|
|
|
|
|
163
|
|
|
|
|
|
|
dump_all_profiles() |
|
164
|
|
|
|
|
|
|
|
|
165
|
|
|
|
|
|
|
Equivalent to |
|
166
|
|
|
|
|
|
|
|
|
167
|
|
|
|
|
|
|
warn $_ for DashProfiler->all_profiles_as_text(); |
|
168
|
|
|
|
|
|
|
|
|
169
|
|
|
|
|
|
|
=cut |
|
170
|
|
|
|
|
|
|
|
|
171
|
|
|
|
|
|
|
sub dump_all_profiles { |
|
172
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
173
|
0
|
|
|
|
|
|
warn $_ for $class->all_profiles_as_text(); |
|
174
|
0
|
|
|
|
|
|
return 1; |
|
175
|
|
|
|
|
|
|
} |
|
176
|
|
|
|
|
|
|
|
|
177
|
|
|
|
|
|
|
|
|
178
|
|
|
|
|
|
|
=head2 reset_all_profiles |
|
179
|
|
|
|
|
|
|
|
|
180
|
|
|
|
|
|
|
Calls C for all profiles. |
|
181
|
|
|
|
|
|
|
|
|
182
|
|
|
|
|
|
|
Typically called from mod_perl PerlChildInitHandler. |
|
183
|
|
|
|
|
|
|
|
|
184
|
|
|
|
|
|
|
=cut |
|
185
|
|
|
|
|
|
|
|
|
186
|
|
|
|
|
|
|
sub reset_all_profiles { # eg PerlChildInitHandler |
|
187
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
188
|
0
|
0
|
|
|
|
|
if (my $pre = $precondition{reset_all_profiles}) { |
|
189
|
0
|
0
|
|
|
|
|
return 1 unless $pre->(); |
|
190
|
|
|
|
|
|
|
} |
|
191
|
0
|
|
|
|
|
|
$_->reset_profile_data for values %profiles; |
|
192
|
0
|
|
|
|
|
|
return -1; # DECLINED |
|
193
|
|
|
|
|
|
|
} |
|
194
|
|
|
|
|
|
|
$precondition{reset_all_profiles} = undef; |
|
195
|
|
|
|
|
|
|
|
|
196
|
|
|
|
|
|
|
|
|
197
|
|
|
|
|
|
|
=head2 flush_all_profiles |
|
198
|
|
|
|
|
|
|
|
|
199
|
|
|
|
|
|
|
flush_all_profiles() |
|
200
|
|
|
|
|
|
|
|
|
201
|
|
|
|
|
|
|
Calls flush() for all profiles. |
|
202
|
|
|
|
|
|
|
Typically called from mod_perl PerlChildExitHandler |
|
203
|
|
|
|
|
|
|
|
|
204
|
|
|
|
|
|
|
=cut |
|
205
|
|
|
|
|
|
|
|
|
206
|
|
|
|
|
|
|
sub flush_all_profiles { # eg PerlChildExitHandler |
|
207
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
208
|
0
|
0
|
|
|
|
|
if (my $pre = $precondition{flush_all_profiles}) { |
|
209
|
0
|
0
|
|
|
|
|
return -1 # DECLINED |
|
210
|
|
|
|
|
|
|
unless $pre->(); |
|
211
|
|
|
|
|
|
|
} |
|
212
|
0
|
|
|
|
|
|
$_->flush for values %profiles; |
|
213
|
0
|
|
|
|
|
|
return -1; # DECLINED |
|
214
|
|
|
|
|
|
|
} |
|
215
|
|
|
|
|
|
|
$precondition{flush_all_profiles} = undef; |
|
216
|
|
|
|
|
|
|
|
|
217
|
|
|
|
|
|
|
|
|
218
|
|
|
|
|
|
|
=head2 start_sample_period_all_profiles |
|
219
|
|
|
|
|
|
|
|
|
220
|
|
|
|
|
|
|
start_sample_period_all_profiles() |
|
221
|
|
|
|
|
|
|
|
|
222
|
|
|
|
|
|
|
Calls start_sample_period() for all profiles. |
|
223
|
|
|
|
|
|
|
Typically called from mod_perl PerlPostReadRequestHandler |
|
224
|
|
|
|
|
|
|
|
|
225
|
|
|
|
|
|
|
=cut |
|
226
|
|
|
|
|
|
|
|
|
227
|
|
|
|
|
|
|
sub start_sample_period_all_profiles { # eg PerlPostReadRequestHandler |
|
228
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
229
|
0
|
0
|
|
|
|
|
if (my $pre = $precondition{start_sample_period_all_profiles}) { |
|
230
|
0
|
0
|
|
|
|
|
return -1 # DECLINED |
|
231
|
|
|
|
|
|
|
unless $pre->(); |
|
232
|
|
|
|
|
|
|
} |
|
233
|
0
|
|
|
|
|
|
$_->start_sample_period for values %profiles; |
|
234
|
0
|
|
|
|
|
|
return -1; # DECLINED |
|
235
|
|
|
|
|
|
|
} |
|
236
|
|
|
|
|
|
|
$precondition{start_sample_period_all_profiles} = undef; |
|
237
|
|
|
|
|
|
|
|
|
238
|
|
|
|
|
|
|
|
|
239
|
|
|
|
|
|
|
=head2 end_sample_period_all_profiles |
|
240
|
|
|
|
|
|
|
|
|
241
|
|
|
|
|
|
|
end_sample_period_all_profiles() |
|
242
|
|
|
|
|
|
|
|
|
243
|
|
|
|
|
|
|
Calls end_sample_period() for all profiles. |
|
244
|
|
|
|
|
|
|
Then calls flush_if_due() for all profiles. |
|
245
|
|
|
|
|
|
|
Typically called from mod_perl PerlCleanupHandler |
|
246
|
|
|
|
|
|
|
|
|
247
|
|
|
|
|
|
|
=cut |
|
248
|
|
|
|
|
|
|
|
|
249
|
|
|
|
|
|
|
sub end_sample_period_all_profiles { # eg PerlCleanupHandler |
|
250
|
0
|
|
|
0
|
1
|
|
my $class = shift; |
|
251
|
0
|
0
|
|
|
|
|
if (my $pre = $precondition{end_sample_period_all_profiles}) { |
|
252
|
0
|
0
|
|
|
|
|
return -1 # DECLINED |
|
253
|
|
|
|
|
|
|
unless $pre->(); |
|
254
|
|
|
|
|
|
|
} |
|
255
|
0
|
|
|
|
|
|
$_->end_sample_period for values %profiles; |
|
256
|
0
|
|
|
|
|
|
$_->flush_if_due for values %profiles; |
|
257
|
0
|
|
|
|
|
|
return -1; # DECLINED |
|
258
|
|
|
|
|
|
|
} |
|
259
|
|
|
|
|
|
|
$precondition{end_sample_period_all_profiles} = undef; |
|
260
|
|
|
|
|
|
|
|
|
261
|
|
|
|
|
|
|
=head1 OTHER METHODS |
|
262
|
|
|
|
|
|
|
|
|
263
|
|
|
|
|
|
|
=head2 set_precondition |
|
264
|
|
|
|
|
|
|
|
|
265
|
|
|
|
|
|
|
DashProfiler->set_precondition( function => sub { ... } ); |
|
266
|
|
|
|
|
|
|
|
|
267
|
|
|
|
|
|
|
Available functions are: |
|
268
|
|
|
|
|
|
|
|
|
269
|
|
|
|
|
|
|
reset_all_profiles |
|
270
|
|
|
|
|
|
|
flush_all_profiles |
|
271
|
|
|
|
|
|
|
start_sample_period_all_profiles |
|
272
|
|
|
|
|
|
|
end_sample_period_all_profiles |
|
273
|
|
|
|
|
|
|
|
|
274
|
|
|
|
|
|
|
The set_precondition method associates a code reference with a function. |
|
275
|
|
|
|
|
|
|
When the function is called the corresponding precondition code is executed |
|
276
|
|
|
|
|
|
|
first. If the precondition code does not return true then the function returns |
|
277
|
|
|
|
|
|
|
immediately. |
|
278
|
|
|
|
|
|
|
|
|
279
|
|
|
|
|
|
|
This mechanism is most useful for fine-tuning when periods start and end. |
|
280
|
|
|
|
|
|
|
For example, there may be times when start_sample_period_all_profiles() is |
|
281
|
|
|
|
|
|
|
being called when you might not want to actually start a new period. |
|
282
|
|
|
|
|
|
|
|
|
283
|
|
|
|
|
|
|
Alternatively the precondition code could itself call start_sample_period() |
|
284
|
|
|
|
|
|
|
for one or more specific profiles and then return false. |
|
285
|
|
|
|
|
|
|
|
|
286
|
|
|
|
|
|
|
See L for an example use. |
|
287
|
|
|
|
|
|
|
|
|
288
|
|
|
|
|
|
|
=cut |
|
289
|
|
|
|
|
|
|
|
|
290
|
|
|
|
|
|
|
sub set_precondition { |
|
291
|
0
|
|
|
0
|
1
|
|
my ($class, $name, $code) = @_; |
|
292
|
0
|
0
|
0
|
|
|
|
croak "Not a CODE reference" if $code and ref $code ne 'CODE'; |
|
293
|
0
|
0
|
|
|
|
|
croak "Invalid function name '$name'" unless exists $precondition{$name}; |
|
294
|
0
|
|
|
|
|
|
$precondition{$name} = $code; |
|
295
|
0
|
|
|
|
|
|
return; |
|
296
|
|
|
|
|
|
|
} |
|
297
|
|
|
|
|
|
|
|
|
298
|
|
|
|
|
|
|
|
|
299
|
|
|
|
|
|
|
=head1 AUTHOR |
|
300
|
|
|
|
|
|
|
|
|
301
|
|
|
|
|
|
|
DashProfiler by Tim Bunce, L and |
|
302
|
|
|
|
|
|
|
L |
|
303
|
|
|
|
|
|
|
|
|
304
|
|
|
|
|
|
|
=head1 COPYRIGHT |
|
305
|
|
|
|
|
|
|
|
|
306
|
|
|
|
|
|
|
The DashProfiler distribution is Copyright (c) 2007-2008 Tim Bunce. Ireland. |
|
307
|
|
|
|
|
|
|
All rights reserved. |
|
308
|
|
|
|
|
|
|
|
|
309
|
|
|
|
|
|
|
You may distribute under the terms of either the GNU General Public |
|
310
|
|
|
|
|
|
|
License or the Artistic License, as specified in the Perl README file. |
|
311
|
|
|
|
|
|
|
|
|
312
|
|
|
|
|
|
|
=cut |
|
313
|
|
|
|
|
|
|
|
|
314
|
|
|
|
|
|
|
1; |