| line |
stmt |
bran |
cond |
sub |
pod |
time |
code |
|
1
|
|
|
|
|
|
|
package Template::Timer; |
|
2
|
|
|
|
|
|
|
|
|
3
|
2
|
|
|
2
|
|
101987
|
use warnings; |
|
|
2
|
|
|
|
|
5
|
|
|
|
2
|
|
|
|
|
259
|
|
|
4
|
2
|
|
|
2
|
|
12
|
use strict; |
|
|
2
|
|
|
|
|
4
|
|
|
|
2
|
|
|
|
|
116
|
|
|
5
|
|
|
|
|
|
|
|
|
6
|
|
|
|
|
|
|
=head1 NAME |
|
7
|
|
|
|
|
|
|
|
|
8
|
|
|
|
|
|
|
Template::Timer - Rudimentary profiling for Template Toolkit |
|
9
|
|
|
|
|
|
|
|
|
10
|
|
|
|
|
|
|
=head1 VERSION |
|
11
|
|
|
|
|
|
|
|
|
12
|
|
|
|
|
|
|
Version 1.00 |
|
13
|
|
|
|
|
|
|
|
|
14
|
|
|
|
|
|
|
=cut |
|
15
|
|
|
|
|
|
|
|
|
16
|
|
|
|
|
|
|
our $VERSION = '1.00'; |
|
17
|
|
|
|
|
|
|
|
|
18
|
|
|
|
|
|
|
=head1 SYNOPSIS |
|
19
|
|
|
|
|
|
|
|
|
20
|
|
|
|
|
|
|
Template::Timer provides inline timings of the template processing |
|
21
|
|
|
|
|
|
|
througout your code. It's an overridden version of L<Template::Context> |
|
22
|
|
|
|
|
|
|
that wraps the C<process()> and C<include()> methods. |
|
23
|
|
|
|
|
|
|
|
|
24
|
|
|
|
|
|
|
Using Template::Timer is simple. |
|
25
|
|
|
|
|
|
|
|
|
26
|
|
|
|
|
|
|
use Template::Timer; |
|
27
|
|
|
|
|
|
|
|
|
28
|
|
|
|
|
|
|
my %config = ( # Whatever your config is |
|
29
|
|
|
|
|
|
|
INCLUDE_PATH => '/my/template/path', |
|
30
|
|
|
|
|
|
|
COMPILE_EXT => '.ttc', |
|
31
|
|
|
|
|
|
|
COMPILE_DIR => '/tmp/tt', |
|
32
|
|
|
|
|
|
|
); |
|
33
|
|
|
|
|
|
|
|
|
34
|
|
|
|
|
|
|
if ( $development_mode ) { |
|
35
|
|
|
|
|
|
|
$config{ CONTEXT } = Template::Timer->new( %config ); |
|
36
|
|
|
|
|
|
|
} |
|
37
|
|
|
|
|
|
|
|
|
38
|
|
|
|
|
|
|
my $template = Template->new( \%config ); |
|
39
|
|
|
|
|
|
|
|
|
40
|
|
|
|
|
|
|
Now when you process templates, HTML comments will get embedded in your |
|
41
|
|
|
|
|
|
|
output, which you can easily grep for. The nesting level is also shown. |
|
42
|
|
|
|
|
|
|
|
|
43
|
|
|
|
|
|
|
<!-- TIMER START: L1 process mainmenu/mainmenu.ttml --> |
|
44
|
|
|
|
|
|
|
<!-- TIMER START: L2 include mainmenu/cssindex.tt --> |
|
45
|
|
|
|
|
|
|
<!-- TIMER START: L3 process mainmenu/cssindex.tt --> |
|
46
|
|
|
|
|
|
|
<!-- TIMER END: L3 process mainmenu/cssindex.tt (17.279 ms) --> |
|
47
|
|
|
|
|
|
|
<!-- TIMER END: L2 include mainmenu/cssindex.tt (17.401 ms) --> |
|
48
|
|
|
|
|
|
|
|
|
49
|
|
|
|
|
|
|
.... |
|
50
|
|
|
|
|
|
|
|
|
51
|
|
|
|
|
|
|
<!-- TIMER END: L3 process mainmenu/footer.tt (3.016 ms) --> |
|
52
|
|
|
|
|
|
|
<!-- TIMER END: L2 include mainmenu/footer.tt (3.104 ms) --> |
|
53
|
|
|
|
|
|
|
<!-- TIMER END: L1 process mainmenu/mainmenu.ttml (400.409 ms) --> |
|
54
|
|
|
|
|
|
|
|
|
55
|
|
|
|
|
|
|
Note that since INCLUDE is a wrapper around PROCESS, calls to INCLUDEs |
|
56
|
|
|
|
|
|
|
will be doubled up, and slightly longer than the PROCESS call. |
|
57
|
|
|
|
|
|
|
|
|
58
|
|
|
|
|
|
|
=cut |
|
59
|
|
|
|
|
|
|
|
|
60
|
2
|
|
|
2
|
|
10
|
use base qw( Template::Context ); |
|
|
2
|
|
|
|
|
7
|
|
|
|
2
|
|
|
|
|
3479
|
|
|
61
|
2
|
|
|
2
|
|
25090
|
use Time::HiRes (); |
|
|
2
|
|
|
|
|
3998
|
|
|
|
2
|
|
|
|
|
136
|
|
|
62
|
|
|
|
|
|
|
|
|
63
|
|
|
|
|
|
|
our $depth = 0; |
|
64
|
|
|
|
|
|
|
our $epoch = undef; |
|
65
|
|
|
|
|
|
|
our @totals; |
|
66
|
|
|
|
|
|
|
|
|
67
|
|
|
|
|
|
|
foreach my $sub ( qw( process include ) ) { |
|
68
|
2
|
|
|
2
|
|
15
|
no strict; |
|
|
2
|
|
|
|
|
4
|
|
|
|
2
|
|
|
|
|
1037
|
|
|
69
|
|
|
|
|
|
|
my $super = __PACKAGE__->can("SUPER::$sub") or die; |
|
70
|
|
|
|
|
|
|
*{$sub} = sub { |
|
71
|
2
|
|
|
2
|
|
68692
|
my $self = shift; |
|
72
|
2
|
|
|
|
|
5
|
my $what = shift; |
|
73
|
|
|
|
|
|
|
|
|
74
|
0
|
|
|
|
|
0
|
my $template = |
|
75
|
|
|
|
|
|
|
ref($what) eq 'ARRAY' |
|
76
|
2
|
50
|
|
|
|
34
|
? join( ' + ', @{$what} ) |
|
|
|
50
|
|
|
|
|
|
|
77
|
|
|
|
|
|
|
: ref($what) |
|
78
|
|
|
|
|
|
|
? $what->name |
|
79
|
|
|
|
|
|
|
: $what; |
|
80
|
|
|
|
|
|
|
|
|
81
|
1
|
|
|
|
|
13
|
my $level; |
|
82
|
|
|
|
|
|
|
my $processed_data; |
|
83
|
0
|
|
|
|
|
0
|
my $epoch_elapsed_start; |
|
84
|
0
|
|
|
|
|
0
|
my $epoch_elapsed_end; |
|
85
|
1
|
|
|
|
|
14
|
my $now = [Time::HiRes::gettimeofday]; |
|
86
|
1
|
|
|
|
|
2
|
my $start = [@{$now}]; |
|
|
1
|
|
|
|
|
3
|
|
|
87
|
1
|
|
|
|
|
2
|
DOIT: { |
|
88
|
1
|
50
|
|
|
|
2
|
local $epoch = $epoch ? $epoch : [@{$now}]; |
|
|
1
|
|
|
|
|
5
|
|
|
89
|
1
|
|
|
|
|
3
|
local $depth = $depth + 1; |
|
90
|
1
|
|
|
|
|
2
|
$level = $depth; |
|
91
|
1
|
|
|
|
|
4
|
$epoch_elapsed_start = _diff_disp($epoch); |
|
92
|
1
|
|
|
|
|
43
|
$processed_data = $super->($self, $what, @_); |
|
93
|
0
|
|
|
|
|
0
|
$epoch_elapsed_end = _diff_disp($epoch); |
|
94
|
|
|
|
|
|
|
} |
|
95
|
0
|
|
|
|
|
0
|
my $spacing = ' ' x $level; |
|
96
|
0
|
|
|
|
|
0
|
my $level_elapsed = _diff_disp($start); |
|
97
|
0
|
|
|
|
|
0
|
my $ip = uc substr( $sub, 0, 1 ); |
|
98
|
0
|
|
|
|
|
0
|
my $start_stats = "L$level $epoch_elapsed_start $spacing$ip $template"; |
|
99
|
0
|
|
|
|
|
0
|
my $end_stats = "L$level $epoch_elapsed_end $level_elapsed $spacing$ip $template"; |
|
100
|
0
|
|
|
|
|
0
|
@totals = ( $start_stats, @totals, $end_stats ); |
|
101
|
0
|
0
|
|
|
|
0
|
if ( $level > 1 ) { |
|
102
|
0
|
|
|
|
|
0
|
return $processed_data; |
|
103
|
|
|
|
|
|
|
} |
|
104
|
|
|
|
|
|
|
|
|
105
|
0
|
|
|
|
|
0
|
my $summary = join( "\n", |
|
106
|
|
|
|
|
|
|
'<!-- SUMMARY', |
|
107
|
|
|
|
|
|
|
@totals, |
|
108
|
|
|
|
|
|
|
'-->', |
|
109
|
|
|
|
|
|
|
'', |
|
110
|
|
|
|
|
|
|
); |
|
111
|
0
|
|
|
|
|
0
|
@totals = (); |
|
112
|
0
|
|
|
|
|
0
|
return "$processed_data\n$summary\n"; |
|
113
|
|
|
|
|
|
|
}; # sub |
|
114
|
|
|
|
|
|
|
} # for |
|
115
|
|
|
|
|
|
|
|
|
116
|
|
|
|
|
|
|
|
|
117
|
|
|
|
|
|
|
sub _diff_disp { |
|
118
|
1
|
|
|
1
|
|
2
|
my $starting_point = shift; |
|
119
|
|
|
|
|
|
|
|
|
120
|
1
|
|
|
|
|
6
|
return sprintf( '%7.3f', Time::HiRes::tv_interval($starting_point) * 1000 ); |
|
121
|
|
|
|
|
|
|
} |
|
122
|
|
|
|
|
|
|
|
|
123
|
|
|
|
|
|
|
|
|
124
|
|
|
|
|
|
|
=head1 AUTHOR |
|
125
|
|
|
|
|
|
|
|
|
126
|
|
|
|
|
|
|
Andy Lester, C<< <andy at petdance.com> >> |
|
127
|
|
|
|
|
|
|
|
|
128
|
|
|
|
|
|
|
=head1 BUGS |
|
129
|
|
|
|
|
|
|
|
|
130
|
|
|
|
|
|
|
Please report any bugs or feature requests to |
|
131
|
|
|
|
|
|
|
C<bug-template-timer at rt.cpan.org>, or through the web interface at |
|
132
|
|
|
|
|
|
|
L<http://rt.cpan.org>. I will be notified, and then you'll automatically |
|
133
|
|
|
|
|
|
|
be notified of progress on your bug as I make changes. |
|
134
|
|
|
|
|
|
|
|
|
135
|
|
|
|
|
|
|
=head1 ACKNOWLEDGEMENTS |
|
136
|
|
|
|
|
|
|
|
|
137
|
|
|
|
|
|
|
Thanks to |
|
138
|
|
|
|
|
|
|
Randal Schwartz, |
|
139
|
|
|
|
|
|
|
Bill Moseley, |
|
140
|
|
|
|
|
|
|
and to Gavin Estey for the original code. |
|
141
|
|
|
|
|
|
|
|
|
142
|
|
|
|
|
|
|
=head1 COPYRIGHT & LICENSE |
|
143
|
|
|
|
|
|
|
|
|
144
|
|
|
|
|
|
|
This library is free software; you can redistribute it and/or modify |
|
145
|
|
|
|
|
|
|
it under the terms of either the GNU Public License v3, or the Artistic |
|
146
|
|
|
|
|
|
|
License 2.0. |
|
147
|
|
|
|
|
|
|
|
|
148
|
|
|
|
|
|
|
* http://www.gnu.org/copyleft/gpl.html |
|
149
|
|
|
|
|
|
|
|
|
150
|
|
|
|
|
|
|
* http://www.opensource.org/licenses/artistic-license-2.0.php |
|
151
|
|
|
|
|
|
|
|
|
152
|
|
|
|
|
|
|
=cut |
|
153
|
|
|
|
|
|
|
|
|
154
|
|
|
|
|
|
|
1; # End of Template::Timer |