← Index
NYTProf Performance Profile   « block view • line view • sub view »
For -e
  Run on Wed Nov 17 21:42:38 2010
Reported on Wed Nov 17 22:07:14 2010

Filename-e
StatementsExecuted 4 statements in 3.66ms
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
3131402826.6ms26.6msUNIVERSAL::::isa UNIVERSAL::isa (xsub)
2534362120.7ms20.7msUNIVERSAL::::can UNIVERSAL::can (xsub)
10631120.4ms104msClass::MOP::Class:::::around Class::MOP::Class:::around (recurses: max depth 3, inclusive time 5.06ms)
11113.6ms6.37smain::::BEGIN@0 main::BEGIN@0 (xsub)
131310938µs938µsUNIVERSAL::::VERSION UNIVERSAL::VERSION (xsub)
421288µs676msClass::MOP::Class:::::before Class::MOP::Class:::before
511288µs403µsMoose::Meta::TypeConstraint::::BoolMoose::Meta::TypeConstraint::Bool
21164µs95µsClass::MOP::Class:::::after Class::MOP::Class:::after
71142µs42µsInternals::::SvREADONLY Internals::SvREADONLY (xsub)
11133µs33µsversion::::(bool version::(bool (xsub)
52121µs21µsRegexp::::DESTROY Regexp::DESTROY (xsub)
11120µs20µsversion::::(cmp version::(cmp (xsub)
0000s0smain::::RUNTIME main::RUNTIME
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments
Time
on line
Calls Time
in subs
Code
016.37sProfile data that couldn't be associated with a specific line:
# spent 6.37s making 1 call to main::BEGIN@0
143.66ms1
 
# spent 42µs within Internals::SvREADONLY which was called 7 times, avg 6µs/call: # 7 times (42µs+0s) by constant::import at line 111 of constant.pm, avg 6µs/call
sub Internals::SvREADONLY; # xsub
# spent 21µs within Regexp::DESTROY which was called 5 times, avg 4µs/call: # 4 times (15µs+0s) by Moose::Meta::Attribute::BEGIN@20 at line 596 of Moose/Util/TypeConstraints.pm, avg 4µs/call # once (6µs+0s) by Moose::Meta::Attribute::BEGIN@20 at line 604 of Moose/Util/TypeConstraints.pm
sub Regexp::DESTROY; # xsub
# spent 938µs within UNIVERSAL::VERSION which was called 13 times, avg 72µs/call: # once (134µs+0s) by Throwable::BEGIN@5 at line 5 of Throwable.pm # once (125µs+0s) by namespace::clean::BEGIN@14 at line 14 of namespace/clean.pm # once (80µs+0s) by namespace::clean::BEGIN@15 at line 15 of namespace/clean.pm # once (75µs+0s) by namespace::clean::BEGIN@17 at line 17 of namespace/clean.pm # once (70µs+0s) by Data::OptList::BEGIN@8 at line 8 of Data/OptList.pm # once (62µs+0s) by Moose::Deprecated::BEGIN@10 at line 22 of Moose/Deprecated.pm # once (60µs+0s) by B::Hooks::EndOfScope::BEGIN@14 at line 14 of B/Hooks/EndOfScope.pm # once (60µs+0s) by KiokuDB::Collapser::BEGIN@18 at line 18 of KiokuDB/Collapser.pm # once (59µs+0s) by Moose::Util::BEGIN@13 at line 13 of Moose/Util.pm # once (58µs+0s) by Moose::Exporter::BEGIN@15 at line 15 of Moose/Exporter.pm # once (57µs+0s) by Moose::BEGIN@17 at line 17 of Moose.pm # once (51µs+0s) by namespace::clean::BEGIN@16 at line 16 of namespace/clean.pm # once (47µs+0s) by Sub::Exporter::BEGIN@9 at line 9 of Sub/Exporter.pm
sub UNIVERSAL::VERSION; # xsub
# spent 20.7ms within UNIVERSAL::can which was called 2534 times, avg 8µs/call: # 531 times (3.43ms+0s) by Class::MOP::Class::_single_metaclass_can_be_made_compatible at line 352 of Class/MOP/Class.pm, avg 6µs/call # 361 times (4.64ms+0s) by Class::MOP::Method::Generated::_compile_code at line 47 of Class/MOP/Method/Generated.pm, avg 13µs/call # 273 times (1.68ms+0s) by Class::MOP::Class::_single_metaclass_is_compatible at line 285 of Class/MOP/Class.pm, avg 6µs/call # 254 times (1.82ms+0s) by Class::MOP::Mixin::HasAttributes::add_attribute at line 42 of Class/MOP/Mixin/HasAttributes.pm, avg 7µs/call # 152 times (791µs+0s) by Moose::Meta::Class::new_object at line 260 of Moose/Meta/Class.pm, avg 5µs/call # 134 times (871µs+0s) by Moose::Meta::Class::excludes_role at line 245 of Moose/Meta/Class.pm, avg 6µs/call # 123 times (1.15ms+0s) by Moose::Meta::Class::add_attribute at line 491 of Moose/Meta/Class.pm, avg 9µs/call # 111 times (805µs+0s) by Moose::Meta::Class::_inline_triggers at line 436 of Moose/Meta/Class.pm, avg 7µs/call # 111 times (753µs+0s) by Moose::Meta::Method::Constructor::_eval_environment at line 71 of Moose/Meta/Method/Constructor.pm, avg 7µs/call # 105 times (819µs+0s) by Moose::Meta::Class::_inline_check_required_attr at line 355 of Moose/Meta/Class.pm, avg 8µs/call # 92 times (699µs+0s) by Class::MOP::Mixin::HasMethods::_add_meta_method at line 23 of Class/MOP/Mixin/HasMethods.pm, avg 8µs/call # 66 times (844µs+0s) by Moose::Exporter::__ANON__[/home/doy/coding/src/Moose/blib/lib//Moose/Exporter.pm:456] at line 417 of Moose/Exporter.pm, avg 13µs/call # 49 times (315µs+0s) by Moose::Meta::Role::Composite::add_method at line 83 of Moose/Meta/Role/Composite.pm, avg 6µs/call # 28 times (475µs+0s) by Moose::Meta::Attribute::install_delegation at line 983 of Moose/Meta/Attribute.pm, avg 17µs/call # 24 times (355µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 68 of Class/MOP/Method/Inlined.pm, avg 15µs/call # 24 times (223µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 56 of Class/MOP/Method/Inlined.pm, avg 9µs/call # 11 times (118µs+0s) by Sub::Exporter::default_generator at line 848 of Sub/Exporter.pm, avg 11µs/call # 10 times (76µs+0s) by Moose::Meta::TypeConstraint::Parameterizable::parameterize at line 59 of Moose/Meta/TypeConstraint/Parameterizable.pm, avg 8µs/call # 10 times (74µs+0s) by Moose::Util::TypeConstraints::_create_parameterized_type_constraint at line 123 of Moose/Util/TypeConstraints.pm, avg 7µs/call # 10 times (74µs+0s) by Moose::Meta::Method::Accessor::Native::Collection::_tc_member_type at line 40 of Moose/Meta/Method/Accessor/Native/Collection.pm, avg 7µs/call # 10 times (68µs+0s) by Moose::Util::add_method_modifier at line 216 of Moose/Util.pm, avg 7µs/call # 6 times (117µs+0s) by Moose::Meta::Class::new_object at line 278 of Moose/Meta/Class.pm, avg 20µs/call # 6 times (78µs+0s) by Moose::Util::resolve_metaclass_alias at line 207 of Moose/Util.pm, avg 13µs/call # 5 times (82µs+0s) by Class::MOP::Class:::around at line 146 of Moose/Meta/Attribute/Native/Trait.pm, avg 16µs/call # 5 times (64µs+0s) by Moose::Object::does at line 125 of Moose/Object.pm, avg 13µs/call # 5 times (61µs+0s) by Moose::Meta::Attribute::Native::Trait::_check_handles_values at line 113 of Moose/Meta/Attribute/Native/Trait.pm, avg 12µs/call # 4 times (44µs+0s) by Hash::Util::FieldHash::Compat::import at line 28 of Hash/Util/FieldHash/Compat.pm, avg 11µs/call # 2 times (26µs+0s) by if::work at line 14 of if.pm, avg 13µs/call # 2 times (17µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 173 of Moose/Meta/Attribute.pm, avg 8µs/call # 2 times (15µs+0s) by Class::MOP::Class::__ANON__::SERIAL::2::_native_type or Class::MOP::Class::__ANON__::SERIAL::5::_native_type at line 7 of accessor _native_type (defined at Moose/Meta/Attribute/Native/Trait.pm line 191), avg 7µs/call # 2 times (14µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 223 of Moose/Meta/Attribute.pm, avg 7µs/call # 2 times (14µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 213 of Moose/Meta/Attribute.pm, avg 7µs/call # once (37µs+0s) by Class::MOP::Class:::before at line 26 of Moose/Meta/Attribute/Native/Trait.pm # once (14µs+0s) by KiokuDB::Collapser::BEGIN@18 at line 443 of Data/Visitor.pm # once (13µs+0s) by Moose::Object::BUILDALL at line 54 of Moose/Object.pm # once (8µs+0s) by Moose::Object::BEGIN@109 at line 110 of Moose/Object.pm
sub UNIVERSAL::can; # xsub
# spent 26.6ms within UNIVERSAL::isa which was called 3131 times, avg 8µs/call: # 830 times (7.30ms+0s) by Class::MOP::Object::_is_compatible_with at line 50 of Class/MOP/Object.pm, avg 9µs/call # 366 times (2.52ms+0s) by Class::MOP::Method::Accessor::new at line 27 of Class/MOP/Method/Accessor.pm, avg 7µs/call # 254 times (1.97ms+0s) by Class::MOP::Mixin::HasAttributes::add_attribute at line 21 of Class/MOP/Mixin/HasAttributes.pm, avg 8µs/call # 239 times (1.34ms+0s) by Class::MOP::Attribute::attach_to_class at line 238 of Class/MOP/Attribute.pm, avg 6µs/call # 188 times (1.85ms+0s) by Moose::Meta::Role::Application::ToRole::apply_methods at line 84 of Moose/Meta/Role/Application/ToRole.pm, avg 10µs/call # 185 times (1.86ms+0s) by Moose::Meta::Role::Application::ToClass::apply_methods at line 155 of Moose/Meta/Role/Application/ToClass.pm, avg 10µs/call # 104 times (1.58ms+0s) by Class::MOP::Class::new_object at line 550 of Class/MOP/Class.pm, avg 15µs/call # 98 times (645µs+0s) by Moose::Meta::TypeConstraint::Registry::add_type_constraint at line 47 of Moose/Meta/TypeConstraint/Registry.pm, avg 7µs/call # 87 times (463µs+0s) by Moose::Util::TypeConstraints::find_type_constraint at line 256 of Moose/Util/TypeConstraints.pm, avg 5µs/call # 78 times (912µs+0s) by base::import at line 81 of base.pm, avg 12µs/call # 76 times (1.14ms+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 41 of Class/MOP/Method/Inlined.pm, avg 15µs/call # 66 times (454µs+0s) by Class::MOP::Method::Wrapped::wrap at line 74 of Class/MOP/Method/Wrapped.pm, avg 7µs/call # 60 times (457µs+0s) by Moose::Meta::Role::Application::RoleSummation::apply_methods at line 167 of Moose/Meta/Role/Application/RoleSummation.pm, avg 8µs/call # 60 times (431µs+0s) by Moose::Meta::Role::apply at line 440 of Moose/Meta/Role.pm, avg 7µs/call # 45 times (344µs+0s) by Moose::Util::_apply_all_roles at line 117 of Moose/Util.pm, avg 8µs/call # 38 times (505µs+0s) by Moose::Meta::Class::superclasses at line 474 of Moose/Meta/Class.pm, avg 13µs/call # 36 times (284µs+0s) by Class::MOP::Class::superclasses at line 989 of Class/MOP/Class.pm, avg 8µs/call # 35 times (377µs+0s) by Moose::Role::init_meta at line 113 of Moose/Role.pm, avg 11µs/call # 31 times (218µs+0s) by Class::MOP::Method::Constructor::new at line 21 of Class/MOP/Method/Constructor.pm, avg 7µs/call # 28 times (253µs+0s) by Moose::Meta::Attribute::install_delegation at line 983 of Moose/Meta/Attribute.pm, avg 9µs/call # 25 times (279µs+0s) by Moose::init_meta at line 158 of Moose.pm, avg 11µs/call # 24 times (165µs+0s) by Moose::Meta::Method::Destructor::is_needed at line 58 of Moose/Meta/Method/Destructor.pm, avg 7µs/call # 23 times (137µs+0s) by Moose::Meta::Method::Delegation::new at line 25 of Moose/Meta/Method/Delegation.pm, avg 6µs/call # 21 times (127µs+0s) by Moose::Meta::Class::add_role at line 179 of Moose/Meta/Class.pm, avg 6µs/call # 21 times (116µs+0s) by Moose::Meta::Class::add_role_application at line 192 of Moose/Meta/Class.pm, avg 6µs/call # 20 times (257µs+0s) by metaclass::import at line 32 of metaclass.pm, avg 13µs/call # 18 times (111µs+0s) by Moose::Meta::Role::add_role at line 397 of Moose/Meta/Role.pm, avg 6µs/call # 15 times (80µs+0s) by Moose::Meta::Role::Attribute::attach_to_role at line 55 of Moose/Meta/Role/Attribute.pm, avg 5µs/call # 10 times (58µs+0s) by Moose::Meta::TypeConstraint::Parameterizable::parameterize at line 67 of Moose/Meta/TypeConstraint/Parameterizable.pm, avg 6µs/call # 10 times (54µs+0s) by Moose::Meta::TypeConstraint::Parameterized::compile_type_constraint at line 46 of Moose/Meta/TypeConstraint/Parameterized.pm, avg 5µs/call # 9 times (45µs+0s) by Moose::Meta::Role::Composite::new at line 44 of Moose/Meta/Role/Composite.pm, avg 5µs/call # 8 times (54µs+0s) by Moose::Meta::Role::add_attribute at line 239 of Moose/Meta/Role.pm, avg 7µs/call # 7 times (79µs+0s) by Class::MOP::Class::__ANON__[/home/doy/coding/src/Class-MOP/blib/lib//Class/MOP/Class.pm:1108] at line 1101 of Class/MOP/Class.pm, avg 11µs/call # 7 times (50µs+0s) by Moose::Meta::Class::add_attribute at line 484 of Moose/Meta/Class.pm, avg 7µs/call # 2 times (19µs+0s) by Class::MOP::Method::Inlined::_uninlined_body at line 22 of Class/MOP/Method/Inlined.pm, avg 10µs/call # 2 times (18µs+0s) by Class::MOP::Mixin::HasMethods::_add_meta_method at line 26 of Class/MOP/Mixin/HasMethods.pm, avg 9µs/call # 2 times (16µs+0s) by Moose::Meta::Class::_process_inherited_attribute at line 630 of Moose/Meta/Class.pm, avg 8µs/call # once (8µs+0s) by Moose::Meta::Attribute::_process_accessors at line 912 of Moose/Meta/Attribute.pm # once (6µs+0s) by Moose::Meta::Attribute::_process_accessors at line 924 of Moose/Meta/Attribute.pm # once (5µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 77 of Class/MOP/Method/Inlined.pm
sub UNIVERSAL::isa; # xsub
# spent 6.37s (13.6ms+6.36) within main::BEGIN@0 which was called: # once (13.6ms+6.36s) by main::RUNTIME at line 0
sub main::BEGIN@0; # xsub
# spent 33µs within version::(bool which was called: # once (33µs+0s) by DynaLoader::BEGIN@25 at line 47 of Config.pm
sub version::(bool; # xsub
# spent 20µs within version::(cmp which was called: # once (20µs+0s) by DynaLoader::BEGIN@25 at line 50 of Config.pm
sub version::(cmp; # xsub