← Index
NYTProf Performance Profile   « block view • line view • sub view »
For -e
  Run on Wed Nov 17 22:00:36 2010
Reported on Wed Nov 17 22:10:06 2010

Filename/home/doy/nytprof/markdent-new/generated method (unknown origin)
StatementsExecuted 44013 statements in 374ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
71111.06ms1.06msEval::Closure::::__ANON__[generated method (unknown origin):7]Eval::Closure::__ANON__[generated method (unknown origin):7]
3911622µs622µsEval::Closure::::__ANON__[generated method (unknown origin):6]Eval::Closure::__ANON__[generated method (unknown origin):6]
1511616µs616µsEval::Closure::::__ANON__[generated method (unknown origin):45]Eval::Closure::__ANON__[generated method (unknown origin):45]
1411480µs480µsEval::Closure::::__ANON__[generated method (unknown origin):35]Eval::Closure::__ANON__[generated method (unknown origin):35]
1611235µs235µsEval::Closure::::__ANON__[generated method (unknown origin):4]Eval::Closure::__ANON__[generated method (unknown origin):4]
711189µs189µsEval::Closure::::__ANON__[generated method (unknown origin):40]Eval::Closure::__ANON__[generated method (unknown origin):40]
311144µs144µsEval::Closure::::__ANON__[generated method (unknown origin):59]Eval::Closure::__ANON__[generated method (unknown origin):59]
411118µs118µsEval::Closure::::__ANON__[generated method (unknown origin):25]Eval::Closure::__ANON__[generated method (unknown origin):25]
11198µs98µsEval::Closure::::__ANON__[generated method (unknown origin):228]Eval::Closure::__ANON__[generated method (unknown origin):228]
31168µs68µsEval::Closure::::__ANON__[generated method (unknown origin):28]Eval::Closure::__ANON__[generated method (unknown origin):28]
31165µs65µsEval::Closure::::__ANON__[generated method (unknown origin):22]Eval::Closure::__ANON__[generated method (unknown origin):22]
11163µs63µsEval::Closure::::__ANON__[generated method (unknown origin):210]Eval::Closure::__ANON__[generated method (unknown origin):210]
11156µs56µsEval::Closure::::__ANON__[generated method (unknown origin):106]Eval::Closure::__ANON__[generated method (unknown origin):106]
11155µs55µsEval::Closure::::__ANON__[generated method (unknown origin):150]Eval::Closure::__ANON__[generated method (unknown origin):150]
11152µs52µsEval::Closure::::__ANON__[generated method (unknown origin):105]Eval::Closure::__ANON__[generated method (unknown origin):105]
11151µs51µsEval::Closure::::__ANON__[generated method (unknown origin):93]Eval::Closure::__ANON__[generated method (unknown origin):93]
21150µs50µsEval::Closure::::__ANON__[generated method (unknown origin):24]Eval::Closure::__ANON__[generated method (unknown origin):24]
21148µs48µsEval::Closure::::__ANON__[generated method (unknown origin):43]Eval::Closure::__ANON__[generated method (unknown origin):43]
11147µs47µsEval::Closure::::__ANON__[generated method (unknown origin):73]Eval::Closure::__ANON__[generated method (unknown origin):73]
21147µs47µsEval::Closure::::__ANON__[generated method (unknown origin):37]Eval::Closure::__ANON__[generated method (unknown origin):37]
11145µs45µsEval::Closure::::__ANON__[generated method (unknown origin):83]Eval::Closure::__ANON__[generated method (unknown origin):83]
11145µs45µsEval::Closure::::__ANON__[generated method (unknown origin):58]Eval::Closure::__ANON__[generated method (unknown origin):58]
11133µs33µsEval::Closure::::__ANON__[generated method (unknown origin):30]Eval::Closure::__ANON__[generated method (unknown origin):30]
11131µs31µsEval::Closure::::__ANON__[generated method (unknown origin):139]Eval::Closure::__ANON__[generated method (unknown origin):139]
11130µs30µsEval::Closure::::__ANON__[generated method (unknown origin):130]Eval::Closure::__ANON__[generated method (unknown origin):130]
11130µs30µsEval::Closure::::__ANON__[generated method (unknown origin):94]Eval::Closure::__ANON__[generated method (unknown origin):94]
11129µs29µsEval::Closure::::__ANON__[generated method (unknown origin):97]Eval::Closure::__ANON__[generated method (unknown origin):97]
11128µs28µsEval::Closure::::__ANON__[generated method (unknown origin):103]Eval::Closure::__ANON__[generated method (unknown origin):103]
11125µs25µsEval::Closure::::__ANON__[generated method (unknown origin):16]Eval::Closure::__ANON__[generated method (unknown origin):16]
11121µs21µsEval::Closure::::__ANON__[generated method (unknown origin):13]Eval::Closure::__ANON__[generated method (unknown origin):13]
11120µs20µsEval::Closure::::__ANON__[generated method (unknown origin):19]Eval::Closure::__ANON__[generated method (unknown origin):19]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):102]Eval::Closure::__ANON__[generated method (unknown origin):102]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):104]Eval::Closure::__ANON__[generated method (unknown origin):104]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):129]Eval::Closure::__ANON__[generated method (unknown origin):129]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):12]Eval::Closure::__ANON__[generated method (unknown origin):12]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):138]Eval::Closure::__ANON__[generated method (unknown origin):138]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):149]Eval::Closure::__ANON__[generated method (unknown origin):149]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):15]Eval::Closure::__ANON__[generated method (unknown origin):15]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):18]Eval::Closure::__ANON__[generated method (unknown origin):18]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):209]Eval::Closure::__ANON__[generated method (unknown origin):209]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):21]Eval::Closure::__ANON__[generated method (unknown origin):21]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):227]Eval::Closure::__ANON__[generated method (unknown origin):227]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):23]Eval::Closure::__ANON__[generated method (unknown origin):23]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):27]Eval::Closure::__ANON__[generated method (unknown origin):27]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):29]Eval::Closure::__ANON__[generated method (unknown origin):29]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):34]Eval::Closure::__ANON__[generated method (unknown origin):34]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):36]Eval::Closure::__ANON__[generated method (unknown origin):36]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):39]Eval::Closure::__ANON__[generated method (unknown origin):39]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):3]Eval::Closure::__ANON__[generated method (unknown origin):3]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):42]Eval::Closure::__ANON__[generated method (unknown origin):42]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):44]Eval::Closure::__ANON__[generated method (unknown origin):44]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):57]Eval::Closure::__ANON__[generated method (unknown origin):57]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):5]Eval::Closure::__ANON__[generated method (unknown origin):5]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):72]Eval::Closure::__ANON__[generated method (unknown origin):72]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):82]Eval::Closure::__ANON__[generated method (unknown origin):82]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):92]Eval::Closure::__ANON__[generated method (unknown origin):92]
0000s0sEval::Closure::::__ANON__[generated method (unknown origin):96]Eval::Closure::__ANON__[generated method (unknown origin):96]
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1
# spent 1.06ms within Eval::Closure::__ANON__[generated method (unknown origin):7] which was called 71 times, avg 15µs/call: # 71 times (1.06ms+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 15µs/call # spent 98µs within Eval::Closure::__ANON__[generated method (unknown origin):228] which was called: # once (98µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 56µs within Eval::Closure::__ANON__[generated method (unknown origin):106] which was called: # once (56µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 144µs within Eval::Closure::__ANON__[generated method (unknown origin):59] which was called 3 times, avg 48µs/call: # 3 times (144µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 48µs/call # spent 65µs within Eval::Closure::__ANON__[generated method (unknown origin):22] which was called 3 times, avg 22µs/call: # 3 times (65µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 22µs/call # spent 30µs within Eval::Closure::__ANON__[generated method (unknown origin):94] which was called: # once (30µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 33µs within Eval::Closure::__ANON__[generated method (unknown origin):30] which was called: # once (33µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 52µs within Eval::Closure::__ANON__[generated method (unknown origin):105] which was called: # once (52µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 189µs within Eval::Closure::__ANON__[generated method (unknown origin):40] which was called 7 times, avg 27µs/call: # 7 times (189µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 27µs/call # spent 51µs within Eval::Closure::__ANON__[generated method (unknown origin):93] which was called: # once (51µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 31µs within Eval::Closure::__ANON__[generated method (unknown origin):139] which was called: # once (31µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 55µs within Eval::Closure::__ANON__[generated method (unknown origin):150] which was called: # once (55µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 50µs within Eval::Closure::__ANON__[generated method (unknown origin):24] which was called 2 times, avg 25µs/call: # 2 times (50µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 25µs/call # spent 21µs within Eval::Closure::__ANON__[generated method (unknown origin):13] which was called: # once (21µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 45µs within Eval::Closure::__ANON__[generated method (unknown origin):58] which was called: # once (45µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 45µs within Eval::Closure::__ANON__[generated method (unknown origin):83] which was called: # once (45µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 47µs within Eval::Closure::__ANON__[generated method (unknown origin):37] which was called 2 times, avg 23µs/call: # 2 times (47µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 23µs/call # spent 47µs within Eval::Closure::__ANON__[generated method (unknown origin):73] which was called: # once (47µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 20µs within Eval::Closure::__ANON__[generated method (unknown origin):19] which was called: # once (20µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 480µs within Eval::Closure::__ANON__[generated method (unknown origin):35] which was called 14 times, avg 34µs/call: # 14 times (480µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 34µs/call # spent 235µs within Eval::Closure::__ANON__[generated method (unknown origin):4] which was called 16 times, avg 15µs/call: # 16 times (235µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 15µs/call # spent 63µs within Eval::Closure::__ANON__[generated method (unknown origin):210] which was called: # once (63µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 118µs within Eval::Closure::__ANON__[generated method (unknown origin):25] which was called 4 times, avg 29µs/call: # 4 times (118µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 29µs/call # spent 616µs within Eval::Closure::__ANON__[generated method (unknown origin):45] which was called 15 times, avg 41µs/call: # 15 times (616µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 41µs/call # spent 68µs within Eval::Closure::__ANON__[generated method (unknown origin):28] which was called 3 times, avg 23µs/call: # 3 times (68µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 23µs/call # spent 28µs within Eval::Closure::__ANON__[generated method (unknown origin):103] which was called: # once (28µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 622µs within Eval::Closure::__ANON__[generated method (unknown origin):6] which was called 39 times, avg 16µs/call: # 39 times (622µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 16µs/call # spent 25µs within Eval::Closure::__ANON__[generated method (unknown origin):16] which was called: # once (25µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 29µs within Eval::Closure::__ANON__[generated method (unknown origin):97] which was called: # once (29µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm # spent 48µs within Eval::Closure::__ANON__[generated method (unknown origin):43] which was called 2 times, avg 24µs/call: # 2 times (48µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm, avg 24µs/call # spent 30µs within Eval::Closure::__ANON__[generated method (unknown origin):130] which was called: # once (30µs+0s) by Eval::Closure::_clean_eval_closure at line 94 of Eval/Closure.pm
sub {
243623366ms#line 1 "generated method (unknown origin)"
312395.87mssub {
# spent 5.87ms making 1239 calls to Scalar::Util::blessed, avg 5µs/call
416279µs418546msif (@_ > 1) {
# spent 343ms making 59 calls to Moose::Meta::Class::new_object, avg 5.81ms/call # spent 194ms making 150 calls to Class::MOP::Class::new_object, avg 1.30ms/call # spent 8.66ms making 209 calls to Class::MOP::Class::initialize, avg 41µs/call
516370µs$_[0]->{"compiled_type_constraint"} = $_[1];
639741µs}
71101.93ms$_[0]->{"compiled_type_constraint"};
8631.17ms}
9}
10;
11
121121.19ms
# spent 768µs making 71 calls to Moose::Meta::Role::Application::__ANON__[Moose/Meta/Role/Application.pm:20], avg 11µs/call # spent 383µs making 38 calls to Class::MOP::__ANON__[Class/MOP.pm:192], avg 10µs/call # spent 39µs making 3 calls to Moose::Meta::Role::Composite::__ANON__[Moose/Meta/Role/Composite.pm:30], avg 13µs/call
13124µs
14117µs
1573813µs
# spent 813µs making 73 calls to Moose::Meta::Role::Application::__ANON__[Moose/Meta/Role/Application.pm:20], avg 11µs/call
16130µs
17130µs
182042.18ms
# spent 637µs making 55 calls to Class::MOP::__ANON__[Class/MOP.pm:510], avg 12µs/call # spent 583µs making 59 calls to Moose::Meta::Role::Application::__ANON__[Moose/Meta/Role/Application.pm:14], avg 10µs/call # spent 506µs making 49 calls to Moose::Meta::TypeConstraint::__ANON__[Moose/Meta/TypeConstraint.pm:32], avg 10µs/call # spent 450µs making 41 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:302], avg 11µs/call
19124µs
20116µs
211201.19ms
# spent 697µs making 73 calls to Moose::Meta::Role::Application::__ANON__[Moose/Meta/Role/Application.pm:14], avg 10µs/call # spent 497µs making 47 calls to Moose::Meta::TypeConstraint::__ANON__[Moose/Meta/TypeConstraint.pm:32], avg 11µs/call
22366µs
23359µs
24258µs
256160µs
26474µs
- -
28371µs
29359µs
30128µs
31120µs
- -
3514330µs
3614287µs
37248µs
38257µs
- -
407180µs
417142µs112µs
# spent 12µs making 1 call to Class::MOP::__ANON__[Class/MOP.pm:510]
- -
43254µs
44234µs
4515405µs
4615337µs
- -
4838318µs
# spent 318µs making 38 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:302], avg 8µs/call
- -
54326µs
# spent 26µs making 3 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:302], avg 9µs/call
- -
58147µs
594118µs
60367µs38346µs
# spent 346µs making 38 calls to Class::MOP::__ANON__[Class/MOP.pm:234], avg 9µs/call
- -
6338379µs
# spent 379µs making 38 calls to Class::MOP::__ANON__[Class/MOP.pm:321], avg 10µs/call
- -
66329µs
# spent 29µs making 3 calls to Class::MOP::__ANON__[Class/MOP.pm:234], avg 10µs/call
- -
6941395µs
# spent 366µs making 38 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:302], avg 10µs/call # spent 29µs making 3 calls to Class::MOP::__ANON__[Class/MOP.pm:321], avg 10µs/call
- -
73136µs
74121µs
75325µs
# spent 25µs making 3 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:302], avg 8µs/call
- -
8138333µs
# spent 333µs making 38 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:89], avg 9µs/call
- -
83135µs
84120µs
- -
87326µs
# spent 26µs making 3 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:89], avg 9µs/call
- -
9038423µs
# spent 423µs making 38 calls to Class::MOP::__ANON__[Class/MOP.pm:276], avg 11µs/call
- -
93137µs
94256µs
95117µs
9638352µs
# spent 352µs making 38 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:347], avg 9µs/call
97134µs
98128µs
99327µs
# spent 27µs making 3 calls to Class::MOP::__ANON__[Class/MOP.pm:276], avg 9µs/call
- -
103136µs
104117µs
105137µs328µs
# spent 28µs making 3 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:347], avg 9µs/call
106258µs
107122µs
- -
11138337µs
# spent 337µs making 38 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:89], avg 9µs/call
- -
11738357µs
# spent 357µs making 38 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:392], avg 9µs/call
- -
12041359µs
# spent 334µs making 38 calls to Class::MOP::__ANON__[Class/MOP.pm:302], avg 9µs/call # spent 25µs making 3 calls to Moose::Meta::Role::__ANON__[Moose/Meta/Role.pm:89], avg 8µs/call
- -
129326µs
# spent 26µs making 3 calls to Class::MOP::__ANON__[Class/MOP.pm:302], avg 9µs/call
130135µs
131119µs
- -
139136µs
140119µs
- -
150140µs
151130µs
- -
210144µs
211126µs
- -
228144µs
229121µs
Report produced by the NYTProf 4.04 Perl profiler, developed by Tim Bunce and Adam Kaplan.