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

Filename-e
StatementsExecuted 4 statements in 3.61ms
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
4306412938.1ms38.1msUNIVERSAL::::isa UNIVERSAL::isa (xsub)
10661125.3ms115msClass::MOP::Class:::::around Class::MOP::Class:::around (recurses: max depth 3, inclusive time 6.09ms)
3147352022.9ms22.9msUNIVERSAL::::can UNIVERSAL::can (xsub)
11113.5ms8.60smain::::BEGIN@0 main::BEGIN@0 (xsub)
131310966µs966µsUNIVERSAL::::VERSION UNIVERSAL::VERSION (xsub)
511331µs474µsMoose::Meta::TypeConstraint::::BoolMoose::Meta::TypeConstraint::Bool
421278µs324msClass::MOP::Class:::::before Class::MOP::Class:::before
21162µs92µsClass::MOP::Class:::::after Class::MOP::Class:::after
71140µs40µsInternals::::SvREADONLY Internals::SvREADONLY (xsub)
11135µs35µ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
018.60sProfile data that couldn't be associated with a specific line:
# spent 8.60s making 1 call to main::BEGIN@0
143.61ms1
 
# spent 40µs within Internals::SvREADONLY which was called 7 times, avg 6µs/call: # 7 times (40µ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 966µs within UNIVERSAL::VERSION which was called 13 times, avg 74µs/call: # once (180µ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 (75µs+0s) by namespace::clean::BEGIN@15 at line 15 of namespace/clean.pm # once (69µ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 Moose::Util::BEGIN@13 at line 13 of Moose/Util.pm # once (59µs+0s) by Moose::BEGIN@17 at line 17 of Moose.pm # once (58µs+0s) by Moose::Exporter::BEGIN@15 at line 15 of Moose/Exporter.pm # once (57µs+0s) by namespace::clean::BEGIN@17 at line 17 of namespace/clean.pm # once (56µs+0s) by KiokuDB::Collapser::BEGIN@18 at line 18 of KiokuDB/Collapser.pm # once (55µs+0s) by Sub::Exporter::BEGIN@9 at line 9 of Sub/Exporter.pm # once (51µs+0s) by namespace::clean::BEGIN@16 at line 16 of namespace/clean.pm
sub UNIVERSAL::VERSION; # xsub
# spent 22.9ms within UNIVERSAL::can which was called 3147 times, avg 7µs/call: # 876 times (5.82ms+0s) by Class::MOP::Mixin::HasMethods::add_method at line 67 of Class/MOP/Mixin/HasMethods.pm, avg 7µs/call # 531 times (3.55ms+0s) by Class::MOP::Class::_single_metaclass_can_be_made_compatible at line 364 of Class/MOP/Class.pm, avg 7µs/call # 273 times (1.72ms+0s) by Class::MOP::Class::_single_metaclass_is_compatible at line 297 of Class/MOP/Class.pm, avg 6µs/call # 253 times (2.07ms+0s) by Class::MOP::Mixin::HasAttributes::add_attribute at line 45 of Class/MOP/Mixin/HasAttributes.pm, avg 8µs/call # 179 times (1.13ms+0s) by Moose::Meta::Role::Composite::add_method at line 83 of Moose/Meta/Role/Composite.pm, avg 6µs/call # 152 times (785µs+0s) by Moose::Meta::Class::new_object at line 260 of Moose/Meta/Class.pm, avg 5µs/call # 134 times (892µs+0s) by Moose::Meta::Class::excludes_role at line 245 of Moose/Meta/Class.pm, avg 7µs/call # 126 times (1.02ms+0s) by Class::MOP::Mixin::HasMethods::_add_meta_method at line 25 of Class/MOP/Mixin/HasMethods.pm, avg 8µs/call # 123 times (1.26ms+0s) by Moose::Meta::Class::add_attribute at line 307 of Moose/Meta/Class.pm, avg 10µs/call # 111 times (742µs+0s) by Moose::Meta::Method::Constructor::_generate_triggers at line 196 of Moose/Meta/Method/Constructor.pm, avg 7µs/call # 111 times (672µs+0s) by Moose::Meta::Method::Constructor::_initialize_body at line 91 of Moose/Meta/Method/Constructor.pm, avg 6µs/call # 100 times (1.20ms+0s) by Moose::Exporter::__ANON__[/home/doy/perl5/perlbrew/perls/perl-5.10.1/lib/site_perl/5.10.1/x86_64-linux/Moose/Exporter.pm:456] at line 417 of Moose/Exporter.pm, avg 12µs/call # 28 times (459µs+0s) by Moose::Meta::Attribute::install_delegation at line 738 of Moose/Meta/Attribute.pm, avg 16µs/call # 24 times (265µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 70 of Class/MOP/Method/Inlined.pm, avg 11µs/call # 24 times (231µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 58 of Class/MOP/Method/Inlined.pm, avg 10µs/call # 17 times (114µs+0s) by Moose::Util::add_method_modifier at line 216 of Moose/Util.pm, avg 7µs/call # 10 times (144µs+0s) by Sub::Exporter::default_generator at line 848 of Sub/Exporter.pm, avg 14µs/call # 10 times (76µs+0s) by Moose::Meta::Method::Accessor::Native::Collection::_tc_member_type at line 40 of Moose/Meta/Method/Accessor/Native/Collection.pm, avg 8µs/call # 10 times (74µs+0s) by Moose::Meta::TypeConstraint::Parameterizable::parameterize at line 59 of Moose/Meta/TypeConstraint/Parameterizable.pm, avg 7µs/call # 10 times (73µs+0s) by Moose::Util::TypeConstraints::_create_parameterized_type_constraint at line 123 of Moose/Util/TypeConstraints.pm, avg 7µs/call # 6 times (115µs+0s) by Moose::Meta::Class::new_object at line 278 of Moose/Meta/Class.pm, avg 19µs/call # 6 times (77µs+0s) by Moose::Util::resolve_metaclass_alias at line 207 of Moose/Util.pm, avg 13µs/call # 5 times (111µs+0s) by Class::MOP::Class:::around at line 146 of Moose/Meta/Attribute/Native/Trait.pm, avg 22µs/call # 5 times (65µs+0s) by Moose::Object::does at line 125 of Moose/Object.pm, avg 13µs/call # 5 times (60µ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 (43µs+0s) by Hash::Util::FieldHash::Compat::import at line 28 of Hash/Util/FieldHash/Compat.pm, avg 11µs/call # 2 times (20µs+0s) by if::work at line 14 of if.pm, avg 10µs/call # 2 times (14µs+0s) by Class::MOP::Class::__ANON__::SERIAL::2::_native_type or Class::MOP::Class::__ANON__::SERIAL::5::_native_type at line 192 of accessor _native_type defined at Moose/Meta/Attribute/Native/Trait.pm, avg 7µs/call # 2 times (14µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 168 of Moose/Meta/Attribute.pm, avg 7µs/call # 2 times (14µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 218 of Moose/Meta/Attribute.pm, avg 7µs/call # 2 times (13µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 208 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 (15µ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 # once (7µs+0s) by KiokuDB::Collapser::BEGIN@18 at line 443 of Data/Visitor.pm
sub UNIVERSAL::can; # xsub
# spent 38.1ms within UNIVERSAL::isa which was called 4306 times, avg 9µs/call: # 843 times (9.25ms+0s) by Moose::Meta::Role::Application::ToRole::apply_methods at line 84 of Moose/Meta/Role/Application/ToRole.pm, avg 11µs/call # 830 times (7.28ms+0s) by Class::MOP::Object::_is_compatible_with at line 50 of Class/MOP/Object.pm, avg 9µs/call # 364 times (2.50ms+0s) by Class::MOP::Method::Accessor::new at line 26 of Class/MOP/Method/Accessor.pm, avg 7µs/call # 253 times (2.00ms+0s) by Class::MOP::Mixin::HasAttributes::add_attribute at line 24 of Class/MOP/Mixin/HasAttributes.pm, avg 8µs/call # 243 times (1.88ms+0s) by Moose::Meta::Role::Application::RoleSummation::apply_methods at line 167 of Moose/Meta/Role/Application/RoleSummation.pm, avg 8µs/call # 238 times (1.29ms+0s) by Class::MOP::Attribute::attach_to_class at line 232 of Class/MOP/Attribute.pm, avg 5µs/call # 178 times (1.80ms+0s) by Moose::Meta::Role::Application::ToClass::apply_methods at line 155 of Moose/Meta/Role/Application/ToClass.pm, avg 10µs/call # 138 times (2.00ms+0s) by Class::MOP::Class::new_object at line 574 of Class/MOP/Class.pm, avg 14µs/call # 132 times (882µs+0s) by Moose::Meta::TypeConstraint::Registry::add_type_constraint at line 47 of Moose/Meta/TypeConstraint/Registry.pm, avg 7µs/call # 111 times (722µs+0s) by Moose::Meta::Method::Constructor::_generate_slot_initializer at line 226 of Moose/Meta/Method/Constructor.pm, avg 7µs/call # 93 times (614µs+0s) by Moose::Meta::Role::apply at line 440 of Moose/Meta/Role.pm, avg 7µs/call # 87 times (451µs+0s) by Moose::Util::TypeConstraints::find_type_constraint at line 256 of Moose/Util/TypeConstraints.pm, avg 5µs/call # 86 times (665µs+0s) by Moose::Util::_apply_all_roles at line 117 of Moose/Util.pm, avg 8µs/call # 78 times (908µs+0s) by base::import at line 81 of base.pm, avg 12µs/call # 76 times (1.05ms+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 43 of Class/MOP/Method/Inlined.pm, avg 14µs/call # 75 times (532µs+0s) by Class::MOP::Method::Wrapped::wrap at line 74 of Class/MOP/Method/Wrapped.pm, avg 7µs/call # 69 times (715µs+0s) by Moose::Role::init_meta at line 113 of Moose/Role.pm, avg 10µs/call # 51 times (357µs+0s) by Moose::Meta::Role::add_role at line 397 of Moose/Meta/Role.pm, avg 7µs/call # 38 times (466µs+0s) by Moose::Meta::Class::superclasses at line 290 of Moose/Meta/Class.pm, avg 12µs/call # 36 times (286µs+0s) by Class::MOP::Class::superclasses at line 848 of Class/MOP/Class.pm, avg 8µs/call # 31 times (229µs+0s) by Class::MOP::Method::Constructor::new at line 20 of Class/MOP/Method/Constructor.pm, avg 7µs/call # 28 times (259µs+0s) by Moose::Meta::Attribute::install_delegation at line 738 of Moose/Meta/Attribute.pm, avg 9µs/call # 25 times (314µs+0s) by Moose::init_meta at line 158 of Moose.pm, avg 13µs/call # 24 times (170µs+0s) by Moose::Meta::Method::Destructor::is_needed at line 58 of Moose/Meta/Method/Destructor.pm, avg 7µs/call # 24 times (122µs+0s) by Moose::Meta::Role::Composite::new at line 44 of Moose/Meta/Role/Composite.pm, avg 5µs/call # 23 times (145µs+0s) by Moose::Meta::Method::Delegation::new at line 25 of Moose/Meta/Method/Delegation.pm, avg 6µs/call # 21 times (140µs+0s) by Moose::Meta::Class::add_role at line 179 of Moose/Meta/Class.pm, avg 7µs/call # 21 times (113µs+0s) by Moose::Meta::Class::add_role_application at line 192 of Moose/Meta/Class.pm, avg 5µs/call # 20 times (499µs+0s) by metaclass::import at line 32 of metaclass.pm, avg 25µs/call # 15 times (86µs+0s) by Moose::Meta::Role::Attribute::attach_to_role at line 55 of Moose/Meta/Role/Attribute.pm, avg 6µs/call # 11 times (134µs+0s) by Class::MOP::Class::__ANON__[/home/doy/perl5/perlbrew/perls/perl-5.10.1/lib/site_perl/5.10.1/x86_64-linux/Class/MOP/Class.pm:967] at line 960 of Class/MOP/Class.pm, avg 12µs/call # 10 times (60µ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 # 8 times (50µs+0s) by Moose::Meta::Role::add_attribute at line 232 of Moose/Meta/Role.pm, avg 6µs/call # 7 times (53µs+0s) by Moose::Meta::Class::add_attribute at line 300 of Moose/Meta/Class.pm, avg 8µs/call # 2 times (22µs+0s) by Class::MOP::Method::Inlined::_uninlined_body at line 24 of Class/MOP/Method/Inlined.pm, avg 11µs/call # 2 times (18µs+0s) by Class::MOP::Mixin::HasMethods::_add_meta_method at line 28 of Class/MOP/Mixin/HasMethods.pm, avg 9µs/call # 2 times (15µs+0s) by Moose::Meta::Class::_process_inherited_attribute at line 446 of Moose/Meta/Class.pm, avg 8µs/call # once (7µs+0s) by Moose::Meta::Attribute::_process_accessors at line 651 of Moose/Meta/Attribute.pm # once (5µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 79 of Class/MOP/Method/Inlined.pm # once (5µs+0s) by Moose::Meta::Attribute::_process_accessors at line 663 of Moose/Meta/Attribute.pm
sub UNIVERSAL::isa; # xsub
# spent 8.60s (13.5ms+8.59) within main::BEGIN@0 which was called: # once (13.5ms+8.59s) by main::RUNTIME at line 0
sub main::BEGIN@0; # xsub
# spent 35µs within version::(bool which was called: # once (35µ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