← Index
NYTProf Performance Profile   « line view »
For starman worker -M FindBin --max-requests 50 --workers 2 --user=kohadev-koha --group kohadev-koha --pid /var/run/koha/kohadev/plack.pid --daemonize --access-log /var/log/koha/kohadev/plack.log --error-log /var/log/koha/kohadev/plack-error.log -E deployment --socket /var/run/koha/kohadev/plack.sock /etc/koha/sites/kohadev/plack.psgi
  Run on Fri Jan 8 14:31:06 2016
Reported on Fri Jan 8 14:33:30 2016

Filename/home/vagrant/kohaclone/errors/Parser.yp
StatementsExecuted 2967 statements in 6.23ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
6411112.7ms16.8msTemplate::Grammar::::__ANON__[Parser.yp:79]Template::Grammar::__ANON__[Parser.yp:79]
976112.60ms33.7msTemplate::Grammar::::__ANON__[Parser.yp:76]Template::Grammar::__ANON__[Parser.yp:76]
1245111.95ms1.95msTemplate::Grammar::::__ANON__[Parser.yp:72]Template::Grammar::__ANON__[Parser.yp:72]
597111.88ms4.45msTemplate::Grammar::::__ANON__[Parser.yp:305]Template::Grammar::__ANON__[Parser.yp:305]
372111.52ms2.85msTemplate::Grammar::::__ANON__[Parser.yp:67]Template::Grammar::__ANON__[Parser.yp:67]
797111.20ms1.20msTemplate::Grammar::::__ANON__[Parser.yp:345]Template::Grammar::__ANON__[Parser.yp:345]
295111.04ms1.58msTemplate::Grammar::::__ANON__[Parser.yp:90]Template::Grammar::__ANON__[Parser.yp:90]
23911872µs2.55msTemplate::Grammar::::__ANON__[Parser.yp:141]Template::Grammar::__ANON__[Parser.yp:141]
76411861µs861µsTemplate::Grammar::::__ANON__[Parser.yp:341]Template::Grammar::__ANON__[Parser.yp:341]
37211583µs583µsTemplate::Grammar::::__ANON__[Parser.yp:73]Template::Grammar::__ANON__[Parser.yp:73]
22711379µs379µsTemplate::Grammar::::__ANON__[Parser.yp:364]Template::Grammar::__ANON__[Parser.yp:364]
19511338µs338µsTemplate::Grammar::::__ANON__[Parser.yp:334]Template::Grammar::__ANON__[Parser.yp:334]
18311251µs251µsTemplate::Grammar::::__ANON__[Parser.yp:152]Template::Grammar::__ANON__[Parser.yp:152]
4011188µs726µsTemplate::Grammar::::__ANON__[Parser.yp:64]Template::Grammar::__ANON__[Parser.yp:64]
12711167µs167µsTemplate::Grammar::::__ANON__[Parser.yp:387]Template::Grammar::__ANON__[Parser.yp:387]
3311159µs285µsTemplate::Grammar::::__ANON__[Parser.yp:342]Template::Grammar::__ANON__[Parser.yp:342]
3311149µs556µsTemplate::Grammar::::__ANON__[Parser.yp:203]Template::Grammar::__ANON__[Parser.yp:203]
3611148µs507µsTemplate::Grammar::::__ANON__[Parser.yp:118]Template::Grammar::__ANON__[Parser.yp:118]
6911128µs128µsTemplate::Grammar::::__ANON__[Parser.yp:407]Template::Grammar::__ANON__[Parser.yp:407]
8111125µs125µsTemplate::Grammar::::__ANON__[Parser.yp:412]Template::Grammar::__ANON__[Parser.yp:412]
4911108µs108µsTemplate::Grammar::::__ANON__[Parser.yp:416]Template::Grammar::__ANON__[Parser.yp:416]
1311108µs591µsTemplate::Grammar::::__ANON__[Parser.yp:168]Template::Grammar::__ANON__[Parser.yp:168]
601198µs98µsTemplate::Grammar::::__ANON__[Parser.yp:151]Template::Grammar::__ANON__[Parser.yp:151]
491185µs85µsTemplate::Grammar::::__ANON__[Parser.yp:382]Template::Grammar::__ANON__[Parser.yp:382]
131151µs101µsTemplate::Grammar::::__ANON__[Parser.yp:167]Template::Grammar::__ANON__[Parser.yp:167]
221151µs51µsTemplate::Grammar::::__ANON__[Parser.yp:360]Template::Grammar::__ANON__[Parser.yp:360]
81132µs126µsTemplate::Grammar::::__ANON__[Parser.yp:227]Template::Grammar::__ANON__[Parser.yp:227]
41132µs63µsTemplate::Grammar::::__ANON__[Parser.yp:145]Template::Grammar::__ANON__[Parser.yp:145]
51130µs128µsTemplate::Grammar::::__ANON__[Parser.yp:115]Template::Grammar::__ANON__[Parser.yp:115]
131128µs28µsTemplate::Grammar::::__ANON__[Parser.yp:176]Template::Grammar::__ANON__[Parser.yp:176]
41127µs33µsTemplate::Grammar::::__ANON__[Parser.yp:109]Template::Grammar::__ANON__[Parser.yp:109]
41126µs120µsTemplate::Grammar::::__ANON__[Parser.yp:187]Template::Grammar::__ANON__[Parser.yp:187]
61125µs25µsTemplate::Grammar::::__ANON__[Parser.yp:357]Template::Grammar::__ANON__[Parser.yp:357]
41124µs58µsTemplate::Grammar::::__ANON__[Parser.yp:144]Template::Grammar::__ANON__[Parser.yp:144]
41122µs78µsTemplate::Grammar::::__ANON__[Parser.yp:440]Template::Grammar::__ANON__[Parser.yp:440]
121121µs21µsTemplate::Grammar::::__ANON__[Parser.yp:408]Template::Grammar::__ANON__[Parser.yp:408]
41120µs37µsTemplate::Grammar::::__ANON__[Parser.yp:307]Template::Grammar::__ANON__[Parser.yp:307]
41120µs69µsTemplate::Grammar::::__ANON__[Parser.yp:229]Template::Grammar::__ANON__[Parser.yp:229]
111117µs17µsTemplate::Grammar::::__ANON__[Parser.yp:359]Template::Grammar::__ANON__[Parser.yp:359]
41114µs14µsTemplate::Grammar::::__ANON__[Parser.yp:198]Template::Grammar::__ANON__[Parser.yp:198]
51114µs14µsTemplate::Grammar::::__ANON__[Parser.yp:374]Template::Grammar::__ANON__[Parser.yp:374]
121112µs12µsTemplate::Grammar::::__ANON__[Parser.yp:386]Template::Grammar::__ANON__[Parser.yp:386]
41112µs12µsTemplate::Grammar::::__ANON__[Parser.yp:435]Template::Grammar::__ANON__[Parser.yp:435]
41111µs11µsTemplate::Grammar::::__ANON__[Parser.yp:200]Template::Grammar::__ANON__[Parser.yp:200]
4119µs9µsTemplate::Grammar::::__ANON__[Parser.yp:420]Template::Grammar::__ANON__[Parser.yp:420]
4116µs6µsTemplate::Grammar::::__ANON__[Parser.yp:436]Template::Grammar::__ANON__[Parser.yp:436]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:113]Template::Grammar::__ANON__[Parser.yp:113]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:114]Template::Grammar::__ANON__[Parser.yp:114]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:116]Template::Grammar::__ANON__[Parser.yp:116]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:117]Template::Grammar::__ANON__[Parser.yp:117]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:119]Template::Grammar::__ANON__[Parser.yp:119]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:120]Template::Grammar::__ANON__[Parser.yp:120]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:121]Template::Grammar::__ANON__[Parser.yp:121]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:122]Template::Grammar::__ANON__[Parser.yp:122]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:123]Template::Grammar::__ANON__[Parser.yp:123]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:124]Template::Grammar::__ANON__[Parser.yp:124]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:127]Template::Grammar::__ANON__[Parser.yp:127]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:135]Template::Grammar::__ANON__[Parser.yp:135]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:142]Template::Grammar::__ANON__[Parser.yp:142]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:150]Template::Grammar::__ANON__[Parser.yp:150]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:156]Template::Grammar::__ANON__[Parser.yp:156]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:161]Template::Grammar::__ANON__[Parser.yp:161]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:162]Template::Grammar::__ANON__[Parser.yp:162]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:163]Template::Grammar::__ANON__[Parser.yp:163]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:164]Template::Grammar::__ANON__[Parser.yp:164]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:169]Template::Grammar::__ANON__[Parser.yp:169]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:170]Template::Grammar::__ANON__[Parser.yp:170]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:171]Template::Grammar::__ANON__[Parser.yp:171]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:172]Template::Grammar::__ANON__[Parser.yp:172]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:175]Template::Grammar::__ANON__[Parser.yp:175]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:177]Template::Grammar::__ANON__[Parser.yp:177]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:181]Template::Grammar::__ANON__[Parser.yp:181]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:183]Template::Grammar::__ANON__[Parser.yp:183]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:192]Template::Grammar::__ANON__[Parser.yp:192]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:195]Template::Grammar::__ANON__[Parser.yp:195]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:199]Template::Grammar::__ANON__[Parser.yp:199]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:206]Template::Grammar::__ANON__[Parser.yp:206]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:208]Template::Grammar::__ANON__[Parser.yp:208]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:211]Template::Grammar::__ANON__[Parser.yp:211]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:215]Template::Grammar::__ANON__[Parser.yp:215]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:219]Template::Grammar::__ANON__[Parser.yp:219]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:223]Template::Grammar::__ANON__[Parser.yp:223]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:238]Template::Grammar::__ANON__[Parser.yp:238]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:243]Template::Grammar::__ANON__[Parser.yp:243]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:247]Template::Grammar::__ANON__[Parser.yp:247]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:258]Template::Grammar::__ANON__[Parser.yp:258]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:261]Template::Grammar::__ANON__[Parser.yp:261]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:265]Template::Grammar::__ANON__[Parser.yp:265]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:266]Template::Grammar::__ANON__[Parser.yp:266]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:270]Template::Grammar::__ANON__[Parser.yp:270]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:273]Template::Grammar::__ANON__[Parser.yp:273]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:274]Template::Grammar::__ANON__[Parser.yp:274]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:275]Template::Grammar::__ANON__[Parser.yp:275]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:278]Template::Grammar::__ANON__[Parser.yp:278]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:285]Template::Grammar::__ANON__[Parser.yp:285]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:286]Template::Grammar::__ANON__[Parser.yp:286]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:287]Template::Grammar::__ANON__[Parser.yp:287]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:299]Template::Grammar::__ANON__[Parser.yp:299]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:300]Template::Grammar::__ANON__[Parser.yp:300]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:301]Template::Grammar::__ANON__[Parser.yp:301]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:302]Template::Grammar::__ANON__[Parser.yp:302]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:306]Template::Grammar::__ANON__[Parser.yp:306]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:312]Template::Grammar::__ANON__[Parser.yp:312]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:317]Template::Grammar::__ANON__[Parser.yp:317]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:322]Template::Grammar::__ANON__[Parser.yp:322]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:325]Template::Grammar::__ANON__[Parser.yp:325]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:330]Template::Grammar::__ANON__[Parser.yp:330]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:331]Template::Grammar::__ANON__[Parser.yp:331]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:337]Template::Grammar::__ANON__[Parser.yp:337]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:346]Template::Grammar::__ANON__[Parser.yp:346]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:349]Template::Grammar::__ANON__[Parser.yp:349]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:352]Template::Grammar::__ANON__[Parser.yp:352]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:353]Template::Grammar::__ANON__[Parser.yp:353]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:354]Template::Grammar::__ANON__[Parser.yp:354]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:355]Template::Grammar::__ANON__[Parser.yp:355]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:356]Template::Grammar::__ANON__[Parser.yp:356]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:358]Template::Grammar::__ANON__[Parser.yp:358]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:361]Template::Grammar::__ANON__[Parser.yp:361]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:362]Template::Grammar::__ANON__[Parser.yp:362]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:363]Template::Grammar::__ANON__[Parser.yp:363]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:368]Template::Grammar::__ANON__[Parser.yp:368]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:375]Template::Grammar::__ANON__[Parser.yp:375]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:383]Template::Grammar::__ANON__[Parser.yp:383]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:385]Template::Grammar::__ANON__[Parser.yp:385]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:397]Template::Grammar::__ANON__[Parser.yp:397]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:402]Template::Grammar::__ANON__[Parser.yp:402]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:406]Template::Grammar::__ANON__[Parser.yp:406]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:411]Template::Grammar::__ANON__[Parser.yp:411]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:415]Template::Grammar::__ANON__[Parser.yp:415]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:439]Template::Grammar::__ANON__[Parser.yp:439]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:441]Template::Grammar::__ANON__[Parser.yp:441]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:68]Template::Grammar::__ANON__[Parser.yp:68]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:91]Template::Grammar::__ANON__[Parser.yp:91]
0000s0sTemplate::Grammar::::__ANON__[Parser.yp:95]Template::Grammar::__ANON__[Parser.yp:95]
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1
- -
641041µs40538µs
# spent 726µs (188+538) within Template::Grammar::__ANON__[Parser.yp:64] which was called 40 times, avg 18µs/call: # 40 times (188µs+538µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 18µs/call
# spent 538µs making 40 calls to Template::Directive::template, avg 13µs/call
65 ],
66 [#Rule 2
67111270µs3721.33ms
# spent 2.85ms (1.52+1.33) within Template::Grammar::__ANON__[Parser.yp:67] which was called 372 times, avg 8µs/call: # 372 times (1.52ms+1.33ms) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 8µs/call
'block', 1,
# spent 1.33ms making 372 calls to Template::Directive::block, avg 4µs/call
68 ],
69 ],
70 [#Rule 4
71372251µs
# spent 1.95ms within Template::Grammar::__ANON__[Parser.yp:72] which was called 1245 times, avg 2µs/call: # 1245 times (1.95ms+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
'chunks', 2,
72372615µs if defined $_[2]; $_[1] }
73111260µs
# spent 583µs within Template::Grammar::__ANON__[Parser.yp:73] which was called 372 times, avg 2µs/call: # 372 times (583µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
74 ],
75 [#Rule 6
76292665µs97631.1ms
# spent 33.7ms (2.60+31.1) within Template::Grammar::__ANON__[Parser.yp:76] which was called 976 times, avg 35µs/call: # 976 times (2.60ms+31.1ms) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 35µs/call
'chunk', 1,
# spent 31.1ms making 976 calls to Template::Directive::textblock, avg 32µs/call
7719133µs
# spent 16.8ms (12.7+4.13) within Template::Grammar::__ANON__[Parser.yp:79] which was called 641 times, avg 26µs/call: # 641 times (12.7ms+4.13ms) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 26µs/call
],
78191640µs6414.13ms $_[0]->location() . $_[1];
# spent 4.13ms making 641 calls to Template::Parser::location, avg 6µs/call
79 }
80 ],
81 [#Rule 8
82 'statement', 1, undef
83 ],
84 [#Rule 9
85 'statement', 1, undef
86 ],
87 [#Rule 10
88 'statement', 1, undef
89 ],
9085222µs295542µs
# spent 1.58ms (1.04+542µs) within Template::Grammar::__ANON__[Parser.yp:90] which was called 295 times, avg 5µs/call: # 295 times (1.04ms+542µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 5µs/call
[#Rule 11
# spent 542µs making 295 calls to Template::Directive::get, avg 2µs/call
91 ],
92 ],
93 [#Rule 18
94 'statement', 0, undef
95 ],
96 ],
97 [#Rule 20
98 'directive', 1, undef
99 ],
100 [#Rule 21
101 'directive', 1, undef
102 ],
103 [#Rule 22
104 'directive', 1, undef
105 ],
106 [#Rule 23
107 'directive', 1, undef
108 ],
10914µs46µs
# spent 33µs (27+6) within Template::Grammar::__ANON__[Parser.yp:109] which was called 4 times, avg 8µs/call: # 4 times (27µs+6µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 8µs/call
[#Rule 24
# spent 6µs making 4 calls to Template::Directive::get, avg 2µs/call
110 ],
111 [#Rule 27
112 'atomexpr', 1, undef
113 ],
114 ],
115212µs598µs
# spent 128µs (30+98) within Template::Grammar::__ANON__[Parser.yp:115] which was called 5 times, avg 26µs/call: # 5 times (30µs+98µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 26µs/call
],
# spent 98µs making 5 calls to Template::Directive::set, avg 20µs/call
116 ],
117 ],
118931µs36359µs
# spent 507µs (148+359) within Template::Grammar::__ANON__[Parser.yp:118] which was called 36 times, avg 14µs/call: # 36 times (148µs+359µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 14µs/call
],
# spent 359µs making 36 calls to Template::Directive::include, avg 10µs/call
119 ],
120 ],
121 ],
122 ],
123 ],
124 ],
125 ],
126 ? $factory->next($_[0]->block_label)
127 : $_[0]->block_label('next ', ';') }
128 ],
129 $_[0]->{ DEBUG_DIRS } = ($1 eq 'on');
130 $factory->debug($_[2]);
131 }
132 else {
133 $_[0]->{ DEBUG_DIRS } ? $factory->debug($_[2]) : '';
134 }
135 }
136 ],
137 [#Rule 42
138 'atomdir', 1, undef
139 ],
140 [#Rule 43
14177203µs2391.68ms
# spent 2.55ms (872µs+1.68) within Template::Grammar::__ANON__[Parser.yp:141] which was called 239 times, avg 11µs/call: # 239 times (872µs+1.68ms) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 11µs/call
'atomdir', 1, undef
# spent 1.68ms making 239 calls to Template::Directive::if, avg 7µs/call
142 ],
143 ],
14415µs434µs
# spent 58µs (24+34) within Template::Grammar::__ANON__[Parser.yp:144] which was called 4 times, avg 14µs/call: # 4 times (24µs+34µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 14µs/call
[#Rule 46
# spent 34µs making 4 calls to Template::Directive::if, avg 9µs/call
14518µs432µs
# spent 63µs (32+32) within Template::Grammar::__ANON__[Parser.yp:145] which was called 4 times, avg 16µs/call: # 4 times (32µs+32µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 16µs/call
],
# spent 32µs making 4 calls to Template::Directive::if, avg 8µs/call
146 ],
147 [#Rule 48
148 'else', 5,
149sub
150 $_[5]; }
1511533µs
# spent 98µs within Template::Grammar::__ANON__[Parser.yp:151] which was called 60 times, avg 2µs/call: # 60 times (98µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
15263125µs
# spent 251µs within Template::Grammar::__ANON__[Parser.yp:152] which was called 183 times, avg 1µs/call: # 183 times (251µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 1µs/call
],
153 ],
154 [#Rule 51
155 'switch', 6,
156sub
157 ],
158 [#Rule 52
159 'case', 5,
160sub
161 $_[5]; }
162 ],
163 ],
164 ],
165 ],
166 [#Rule 56
167414µs1350µs
# spent 101µs (51+50) within Template::Grammar::__ANON__[Parser.yp:167] which was called 13 times, avg 8µs/call: # 13 times (51µs+50µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 8µs/call
'@1-3', 0,
# spent 50µs making 13 calls to Template::Parser::enter_block, avg 4µs/call
168438µs26483µs
# spent 591µs (108+483) within Template::Grammar::__ANON__[Parser.yp:168] which was called 13 times, avg 45µs/call: # 13 times (108µs+483µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 45µs/call
],
# spent 300µs making 13 calls to Template::Directive::foreach, avg 23µs/call # spent 182µs making 13 calls to Template::Parser::leave_block, avg 14µs/call
169 ],
170 ],
171 ],
172 ],
173 ],
174 [#Rule 62
175 'loopvar', 4,
176415µs
# spent 28µs within Template::Grammar::__ANON__[Parser.yp:176] which was called 13 times, avg 2µs/call: # 13 times (28µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
177 ],
178 ],
179 [#Rule 65
180 'wrapper', 5,
181sub
182 ],
183 [#Rule 66
184 ],
185 [#Rule 67
186 'try', 5,
18717µs495µs
# spent 120µs (26+95) within Template::Grammar::__ANON__[Parser.yp:187] which was called 4 times, avg 30µs/call: # 4 times (26µs+95µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 30µs/call
sub
# spent 95µs making 4 calls to Template::Directive::try, avg 24µs/call
188 ],
189 [#Rule 68
190 'final', 5,
191sub
192 $_[5]; }
193 ],
194 [#Rule 69
195 $_[5]; }
196 ],
19712µs
# spent 14µs within Template::Grammar::__ANON__[Parser.yp:198] which was called 4 times, avg 4µs/call: # 4 times (14µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 4µs/call
[#Rule 70
19812µs $_[4]; }
199 ],
20013µs
# spent 11µs within Template::Grammar::__ANON__[Parser.yp:200] which was called 4 times, avg 3µs/call: # 4 times (11µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 3µs/call
],
201 ],
202 [#Rule 73
203938µs33407µs
# spent 556µs (149+407) within Template::Grammar::__ANON__[Parser.yp:203] which was called 33 times, avg 17µs/call: # 33 times (149µs+407µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 17µs/call
'use', 2,
# spent 407µs making 33 calls to Template::Directive::use, avg 12µs/call
204 ],
205 [#Rule 74
206 '@3-3', 0,
207 ],
208 $_[0]->pop_defblock) }
209 ],
210 [#Rule 76
211 '@4-2', 0,
212 ],
213 $_[0]->{ EVAL_PERL }
214 ? $factory->perl($_[4])
215 : $factory->no_perl(); }
216 ],
217 [#Rule 78
218 '@5-1', 0,
219 $rawstart = ${$_[0]->{'LINE'}}; }
220 ],
221 $_[0]->{ EVAL_PERL }
222 ? $factory->rawperl($_[4], $rawstart)
223 : $factory->no_perl(); }
224 ],
225 [#Rule 80
226 'filter', 5,
227210µs894µs
# spent 126µs (32+94) within Template::Grammar::__ANON__[Parser.yp:227] which was called 8 times, avg 16µs/call: # 8 times (32µs+94µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 16µs/call
sub
# spent 94µs making 8 calls to Template::Directive::filter, avg 12µs/call
228 ],
22915µs449µs
# spent 69µs (20+49) within Template::Grammar::__ANON__[Parser.yp:229] which was called 4 times, avg 17µs/call: # 4 times (20µs+49µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 17µs/call
[#Rule 81
# spent 49µs making 4 calls to Template::Directive::filter, avg 12µs/call
230 ],
231 [#Rule 82
232 'defblock', 5,
233sub
234{ my $name = join('/', @{ $_[0]->{ DEFBLOCKS } });
235 pop(@{ $_[0]->{ DEFBLOCKS } });
236 $_[0]->define_block($name, $_[4]);
237 undef
238 }
239 ],
240 [#Rule 83
241 'defblockname', 2,
242 $_[2];
243 }
244 ],
245 [#Rule 84
246 'blockname', 1, undef
247 ],
248 ],
249 [#Rule 86
250 'blockargs', 1, undef
251 ],
252 [#Rule 87
253 'blockargs', 0, undef
254 ],
255 [#Rule 88
256 print STDERR "experimental block args: [@{ $_[2] }]\n"
257 if $_[2];
258 $factory->anon_block($_[4]) }
259 ],
260 [#Rule 89
261 'capture', 3,
262 ],
263 [#Rule 90
264 'macro', 6,
265sub
266 ],
267 ],
268 [#Rule 92
269 'mdir', 1, undef
270 ],
271 ],
272 [#Rule 94
273 'margs', 2,
274 ],
275 ],
276 ],
277 [#Rule 97
278 'metadata', 2,
279 ],
280 [#Rule 98
281 'metadata', 2, undef
282 ],
283 [#Rule 99
284 s/\\'/'/g };
285 [ @_[1,3] ] }
286 ],
287 ],
288 ],
289 [#Rule 103
290 'term', 1, undef
291 ],
292 [#Rule 104
293 'term', 1, undef
294 ],
295 [#Rule 105
296 'lterm', 3,
297sub
298{ "[ $_[2] ]" }
299
300 ],
301 ],
302 ],
303 ],
304 [#Rule 109
305192486µs5972.57ms
# spent 4.45ms (1.88+2.57) within Template::Grammar::__ANON__[Parser.yp:305] which was called 597 times, avg 7µs/call: # 597 times (1.88ms+2.57ms) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 7µs/call
'sterm', 1,
# spent 2.57ms making 597 calls to Template::Directive::ident, avg 4µs/call
306 ],
30714µs416µs
# spent 37µs (20+16) within Template::Grammar::__ANON__[Parser.yp:307] which was called 4 times, avg 9µs/call: # 4 times (20µs+16µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 9µs/call
],
# spent 16µs making 4 calls to Template::Directive::quoted, avg 4µs/call
308 ],
309 [#Rule 112
310 'sterm', 1, undef
311 ],
312 [#Rule 113
313 ],
314 [#Rule 115
315 'list', 2, undef
316 ],
317 [#Rule 116
318 ],
319 [#Rule 118
320 'hash', 1, undef
321 ],
322 [#Rule 119
323 ],
324 [#Rule 120
325 'params', 2,
326 ],
327 [#Rule 121
328 'params', 2, undef
329 ],
330 [#Rule 122
331 ],
332 ],
333 [#Rule 125
334114466µs
# spent 338µs within Template::Grammar::__ANON__[Parser.yp:334] which was called 195 times, avg 2µs/call: # 195 times (338µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
'ident', 3,
335 ],
336 map {($_, 0)} split(/\./, $_[3]));
337 $_[1]; }
338 ],
339 [#Rule 127
340 'ident', 1, undef
341242491µs
# spent 861µs within Template::Grammar::__ANON__[Parser.yp:341] which was called 764 times, avg 1µs/call: # 764 times (861µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 1µs/call
],
342942µs33126µs
# spent 285µs (159+126) within Template::Grammar::__ANON__[Parser.yp:342] which was called 33 times, avg 9µs/call: # 33 times (159µs+126µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 9µs/call
],
# spent 126µs making 33 calls to Template::Directive::args, avg 4µs/call
343 ],
344 [#Rule 130
345251591µs
# spent 1.20ms within Template::Grammar::__ANON__[Parser.yp:345] which was called 797 times, avg 2µs/call: # 797 times (1.20ms+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
'item', 1,
346 ],
347 ],
348 ? "'$_[2]'"
349 : $factory->ident(["'$_[2]'", 0]) }
350 ],
351 [#Rule 133
352 'expr', 3,
353 ],
354 ],
355 ],
356 ],
357316µs
# spent 25µs within Template::Grammar::__ANON__[Parser.yp:357] which was called 6 times, avg 4µs/call: # 6 times (25µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 4µs/call
],
358 ],
3591142µs
# spent 17µs within Template::Grammar::__ANON__[Parser.yp:359] which was called 11 times, avg 2µs/call: # 11 times (17µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
3601036µs
# spent 51µs within Template::Grammar::__ANON__[Parser.yp:360] which was called 22 times, avg 2µs/call: # 22 times (51µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
361 ],
362 ],
363 ],
36477198µs
# spent 379µs within Template::Grammar::__ANON__[Parser.yp:364] which was called 227 times, avg 2µs/call: # 227 times (379µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
365 ],
366 [#Rule 146
367 'expr', 1, undef
368 ],
369 ],
370 [#Rule 148
371 'setlist', 2, undef
372 ],
373 [#Rule 149
37427µs
# spent 14µs within Template::Grammar::__ANON__[Parser.yp:374] which was called 5 times, avg 3µs/call: # 5 times (14µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 3µs/call
'setlist', 1, undef
375 ],
376 ],
377 [#Rule 152
378 'args', 2,
379sub
380{ push(@{$_[1]}, $_[2]); $_[1] }
381
3822639µs
# spent 85µs within Template::Grammar::__ANON__[Parser.yp:382] which was called 49 times, avg 2µs/call: # 49 times (85µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
383 ],
384 ],
385 $factory->assign(@_[2,4])); $_[1] }
38638µs
# spent 12µs within Template::Grammar::__ANON__[Parser.yp:386] which was called 12 times, avg 1µs/call: # 12 times (12µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 1µs/call
],
3873482µs
# spent 167µs within Template::Grammar::__ANON__[Parser.yp:387] which was called 127 times, avg 1µs/call: # 127 times (167µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 1µs/call
],
388 ],
389 [#Rule 157
390 'lnameargs', 3,
391sub
392{ push(@{$_[3]}, $_[1]); $_[3] }
393
- -
397
398 ],
399 [#Rule 158
400 'lnameargs', 1, undef
401 ],
402 [#Rule 159
403 ],
404 [#Rule 161
405 'lvalue', 1, undef
406 ],
4071850µs
# spent 128µs within Template::Grammar::__ANON__[Parser.yp:407] which was called 69 times, avg 2µs/call: # 69 times (128µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
40839µs
# spent 21µs within Template::Grammar::__ANON__[Parser.yp:408] which was called 12 times, avg 2µs/call: # 12 times (21µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
409 ],
410 [#Rule 165
411 'names', 3,
4122151µs
# spent 125µs within Template::Grammar::__ANON__[Parser.yp:412] which was called 81 times, avg 2µs/call: # 81 times (125µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
413 ],
414 [#Rule 167
415 'name', 3,
4161340µs
# spent 108µs within Template::Grammar::__ANON__[Parser.yp:416] which was called 49 times, avg 2µs/call: # 49 times (108µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
417 ],
418 [#Rule 169
419 'name', 1, undef
42018µs
# spent 9µs within Template::Grammar::__ANON__[Parser.yp:420] which was called 4 times, avg 2µs/call: # 4 times (9µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
421 ],
422 [#Rule 171
423 'filename', 1, undef
424 ],
425 [#Rule 172
426 'filepart', 1, undef
427 ],
428 [#Rule 173
429 'filepart', 1, undef
430 ],
431 [#Rule 174
432 'filepart', 1, undef
433 ],
43411µs
# spent 12µs within Template::Grammar::__ANON__[Parser.yp:435] which was called 4 times, avg 3µs/call: # 4 times (12µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 3µs/call
[#Rule 175
43512µs if defined $_[2]; $_[1] }
43613µs
# spent 6µs within Template::Grammar::__ANON__[Parser.yp:436] which was called 4 times, avg 2µs/call: # 4 times (6µs+0s) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 2µs/call
],
437 ],
438 [#Rule 177
439 'quotable', 1,
44014µs457µs
# spent 78µs (22+57) within Template::Grammar::__ANON__[Parser.yp:440] which was called 4 times, avg 20µs/call: # 4 times (22µs+57µs) by Template::Parser::_parse at line 882 of Template/Parser.pm, avg 20µs/call
],
# spent 57µs making 4 calls to Template::Directive::text, avg 14µs/call
441 ],
442 ]
443];
444
- -
4471;
448
449__END__
Report produced by the NYTProf 6.02 Perl profiler, developed by Tim Bunce and Adam Kaplan.