← Index
NYTProf Performance Profile   « block view • line view • sub view »
For -e
  Run on Wed Nov 17 21:45:08 2010
Reported on Wed Nov 17 22:13:00 2010

Filename-e
StatementsExecuted 4 statements in 4.77ms
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
8561503480.7ms80.7msUNIVERSAL::::isa UNIVERSAL::isa (xsub)
8351331855.5ms55.5msUNIVERSAL::::can UNIVERSAL::can (xsub)
17961153.3ms332msClass::MOP::Class:::::around Class::MOP::Class:::around (recurses: max depth 4, inclusive time 11.7ms)
1112.43ms13.9smain::::BEGIN@0 main::BEGIN@0 (xsub)
36211.88ms810msClass::MOP::Class:::::before Class::MOP::Class:::before
3030221.64ms10.1ms__TYPE__::::Str __TYPE__::Str
2516111.47ms1.47msUNIVERSAL::::VERSION UNIVERSAL::VERSION (xsub)
664462µs21.2ms__TYPE__::::ArrayRef __TYPE__::ArrayRef
665416µs16.1ms__TYPE__::::HashRef __TYPE__::HashRef
611383µs541µsMoose::Meta::TypeConstraint::::Bool Moose::Meta::TypeConstraint::Bool
611320µs748µsMoose::Meta::TypeConstraint::::ArrayRef[Str] Moose::Meta::TypeConstraint::ArrayRef[Str]
881283µs647µsMooseX::Types::CheckedUtilExports::::as MooseX::Types::CheckedUtilExports::as
881267µs15.1msMooseX::Types::CheckedUtilExports::::subtype MooseX::Types::CheckedUtilExports::subtype
333195µs1.25ms__TYPE__::Markdent::Types::Internal::::HeaderLevel__TYPE__::Markdent::Types::Internal::HeaderLevel
611187µs278µsClass::MOP::Class:::::after Class::MOP::Class:::after
222166µs711µs__TYPE__::Markdent::Types::Internal::::OutputStream__TYPE__::Markdent::Types::Internal::OutputStream
332164µs929µs__TYPE__::::Int __TYPE__::Int
222133µs679µs__TYPE__::Markdent::Types::Internal::::BlockParserClass__TYPE__::Markdent::Types::Internal::BlockParserClass
222131µs677µs__TYPE__::Markdent::Types::Internal::::SpanParserClass__TYPE__::Markdent::Types::Internal::SpanParserClass
222131µs671µs__TYPE__::Markdent::Types::Internal::::EventObject__TYPE__::Markdent::Types::Internal::EventObject
222122µs672µs__TYPE__::::Bool __TYPE__::Bool
22195µs550µs__TYPE__::::Object __TYPE__::Object
22192µs546µs__TYPE__::::ClassName __TYPE__::ClassName
11192µs385µs__TYPE__::Markdent::Types::Internal::::PosInt__TYPE__::Markdent::Types::Internal::PosInt
11182µs346µs__TYPE__::Markdent::Types::Internal::::HandlerObject__TYPE__::Markdent::Types::Internal::HandlerObject
11180µs363µs__TYPE__::Markdent::Types::Internal::::TableCellAlignment__TYPE__::Markdent::Types::Internal::TableCellAlignment
11176µs343µs__TYPE__::Markdent::Types::Internal::::NonEmptyArrayRef__TYPE__::Markdent::Types::Internal::NonEmptyArrayRef
11163µs358µs__TYPE__::::RegexpRef __TYPE__::RegexpRef
11150µs278µs__TYPE__::::Any __TYPE__::Any
11143µs2.05msMooseX::Types::CheckedUtilExports::::enum MooseX::Types::CheckedUtilExports::enum
11131µs31µsversion::::(cmp version::(cmp (xsub)
11126µs26µsversion::::(bool version::(bool (xsub)
52120µs20µsRegexp::::DESTROY Regexp::DESTROY (xsub)
31117µs17µsInternals::::SvREADONLY Internals::SvREADONLY (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
0113.9sProfile data that couldn't be associated with a specific line:
# spent 13.9s making 1 call to main::BEGIN@0
144.77ms1
 
# spent 17µs within Internals::SvREADONLY which was called 3 times, avg 6µs/call: # 3 times (17µs+0s) by constant::import at line 111 of constant.pm, avg 6µs/call
sub Internals::SvREADONLY; # xsub
# spent 20µs within Regexp::DESTROY which was called 5 times, avg 4µs/call: # 4 times (14µ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 1.47ms within UNIVERSAL::VERSION which was called 25 times, avg 59µs/call: # 10 times (471µs+0s) by namespace::autoclean::__ANON__[/home/doy/perl5/perlbrew/perls/perl-5.10.1/lib/site_perl/5.10.1/namespace/autoclean.pm:57] at line 46 of namespace/autoclean.pm, avg 47µs/call # once (124µs+0s) by namespace::clean::BEGIN@14 at line 14 of namespace/clean.pm # once (84µs+0s) by Moose::BEGIN@17 at line 17 of Moose.pm # once (72µs+0s) by Moose::Deprecated::BEGIN@10 at line 22 of Moose/Deprecated.pm # once (70µs+0s) by B::Hooks::EndOfScope::BEGIN@14 at line 14 of B/Hooks/EndOfScope.pm # once (65µs+0s) by MooseX::SemiAffordanceAccessor::BEGIN@9 at line 9 of MooseX/SemiAffordanceAccessor.pm # once (65µs+0s) by Moose::Exporter::BEGIN@15 at line 15 of Moose/Exporter.pm # once (64µs+0s) by MooseX::StrictConstructor::BEGIN@9 at line 9 of MooseX/StrictConstructor.pm # once (62µs+0s) by namespace::autoclean::BEGIN@13 at line 13 of namespace/autoclean.pm # once (61µs+0s) by Moose::Util::BEGIN@13 at line 13 of Moose/Util.pm # once (60µs+0s) by namespace::autoclean::BEGIN@16 at line 16 of namespace/autoclean.pm # once (56µs+0s) by namespace::clean::BEGIN@17 at line 17 of namespace/clean.pm # once (56µs+0s) by Data::OptList::BEGIN@8 at line 8 of Data/OptList.pm # once (55µs+0s) by namespace::clean::BEGIN@15 at line 15 of namespace/clean.pm # once (52µs+0s) by namespace::clean::BEGIN@16 at line 16 of namespace/clean.pm # once (50µs+0s) by Sub::Exporter::BEGIN@9 at line 9 of Sub/Exporter.pm
sub UNIVERSAL::VERSION; # xsub
# spent 55.5ms within UNIVERSAL::can which was called 8351 times, avg 7µs/call: # 2018 times (12.8ms+0s) by Class::MOP::Mixin::HasMethods::add_method at line 67 of Class/MOP/Mixin/HasMethods.pm, avg 6µs/call # 1706 times (9.16ms+0s) by Moose::Meta::Class::new_object at line 260 of Moose/Meta/Class.pm, avg 5µs/call # 1392 times (9.08ms+0s) by Class::MOP::Class::_single_metaclass_can_be_made_compatible at line 364 of Class/MOP/Class.pm, avg 7µs/call # 1134 times (7.28ms+0s) by Class::MOP::Class::_single_metaclass_is_compatible at line 297 of Class/MOP/Class.pm, avg 6µs/call # 427 times (2.83ms+0s) by Moose::Meta::Class::excludes_role at line 245 of Moose/Meta/Class.pm, avg 7µs/call # 216 times (1.76ms+0s) by Class::MOP::Mixin::HasMethods::_add_meta_method at line 25 of Class/MOP/Mixin/HasMethods.pm, avg 8µs/call # 211 times (1.55ms+0s) by Class::MOP::Mixin::HasAttributes::add_attribute at line 45 of Class/MOP/Mixin/HasAttributes.pm, avg 7µs/call # 180 times (2.19ms+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 # 178 times (1.19ms+0s) by Moose::Meta::Role::Composite::add_method at line 83 of Moose/Meta/Role/Composite.pm, avg 7µs/call # 130 times (872µs+0s) by Moose::Meta::Method::Constructor::_generate_triggers at line 196 of Moose/Meta/Method/Constructor.pm, avg 7µs/call # 130 times (859µs+0s) by Moose::Meta::Class::does_role at line 225 of Moose/Meta/Class.pm, avg 7µs/call # 130 times (786µs+0s) by Moose::Meta::Method::Constructor::_initialize_body at line 91 of Moose/Meta/Method/Constructor.pm, avg 6µs/call # 110 times (940µs+0s) by Moose::Meta::Class::new_object at line 278 of Moose/Meta/Class.pm, avg 9µs/call # 80 times (650µs+0s) by Moose::Util::MetaRole::_make_new_class at line 161 of Moose/Util/MetaRole.pm, avg 8µs/call # 72 times (859µs+0s) by Moose::Meta::Class::add_attribute at line 307 of Moose/Meta/Class.pm, avg 12µs/call # 42 times (253µs+0s) by Moose::Object::BUILDALL at line 54 of Moose/Object.pm, avg 6µs/call # 41 times (658µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 70 of Class/MOP/Method/Inlined.pm, avg 16µs/call # 41 times (428µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 58 of Class/MOP/Method/Inlined.pm, avg 10µs/call # 20 times (149µs+0s) by Moose::Util::add_method_modifier at line 216 of Moose/Util.pm, avg 7µs/call # 14 times (279µs+0s) by Moose::Meta::Attribute::install_delegation at line 738 of Moose/Meta/Attribute.pm, avg 20µs/call # 14 times (215µs+0s) by Class::MOP::Class:::around at line 146 of Moose/Meta/Attribute/Native/Trait.pm, avg 15µs/call # 14 times (189µs+0s) by Moose::Meta::Attribute::Native::Trait::_check_handles_values at line 113 of Moose/Meta/Attribute/Native/Trait.pm, avg 14µs/call # 13 times (154µs+0s) by Sub::Exporter::default_generator at line 848 of Sub/Exporter.pm, avg 12µs/call # 10 times (130µs+0s) by Moose::Util::resolve_metaclass_alias at line 207 of Moose/Util.pm, avg 13µs/call # 6 times (49µs+0s) by Moose::Meta::TypeConstraint::Parameterizable::parameterize at line 59 of Moose/Meta/TypeConstraint/Parameterizable.pm, avg 8µs/call # 6 times (46µs+0s) by Class::MOP::Class::__ANON__::SERIAL::11::_native_type or Class::MOP::Class::__ANON__::SERIAL::15::_native_type or Class::MOP::Class::__ANON__::SERIAL::19::_native_type or Class::MOP::Class::__ANON__::SERIAL::5::_native_type or Class::MOP::Class::__ANON__::SERIAL::8::_native_type at line 192 of accessor _native_type defined at Moose/Meta/Attribute/Native/Trait.pm, avg 8µs/call # 6 times (45µs+0s) by Moose::Object::does at line 125 of Moose/Object.pm, avg 8µs/call # 4 times (33µs+0s) by MooseX::Types::TypeDecorator::can at line 141 of MooseX/Types/TypeDecorator.pm, avg 8µs/call # 2 times (23µs+0s) by if::work at line 14 of if.pm, avg 12µs/call # once (24µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 168 of Moose/Meta/Attribute.pm # once (18µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 208 of Moose/Meta/Attribute.pm # once (17µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 218 of Moose/Meta/Attribute.pm # once (8µs+0s) by Moose::Object::BEGIN@109 at line 110 of Moose/Object.pm
sub UNIVERSAL::can; # xsub
# spent 80.7ms within UNIVERSAL::isa which was called 8561 times, avg 9µs/call: # 2776 times (26.6ms+0s) by Class::MOP::Object::_is_compatible_with at line 50 of Class/MOP/Object.pm, avg 10µs/call # 1366 times (14.6ms+0s) by Moose::Meta::Role::Application::ToRole::apply_methods at line 84 of Moose/Meta/Role/Application/ToRole.pm, avg 11µs/call # 730 times (7.45ms+0s) by Moose::Meta::Role::Application::ToClass::apply_methods at line 155 of Moose/Meta/Role/Application/ToClass.pm, avg 10µs/call # 330 times (5.02ms+0s) by Class::MOP::Class::new_object at line 574 of Class/MOP/Class.pm, avg 15µs/call # 270 times (1.85ms+0s) by Class::MOP::Method::Accessor::new at line 26 of Class/MOP/Method/Accessor.pm, avg 7µs/call # 257 times (1.80ms+0s) by Moose::Meta::Role::apply at line 440 of Moose/Meta/Role.pm, avg 7µs/call # 256 times (2.03ms+0s) by Moose::Meta::Role::Application::RoleSummation::apply_methods at line 167 of Moose/Meta/Role/Application/RoleSummation.pm, avg 8µs/call # 211 times (1.46ms+0s) by Class::MOP::Mixin::HasAttributes::add_attribute at line 24 of Class/MOP/Mixin/HasAttributes.pm, avg 7µs/call # 187 times (972µs+0s) by Class::MOP::Attribute::attach_to_class at line 232 of Class/MOP/Attribute.pm, avg 5µs/call # 159 times (1.10ms+0s) by Moose::Meta::TypeConstraint::Registry::add_type_constraint at line 47 of Moose/Meta/TypeConstraint/Registry.pm, avg 7µs/call # 153 times (1.17ms+0s) by Moose::Util::_apply_all_roles at line 117 of Moose/Util.pm, avg 8µs/call # 130 times (1.19ms+0s) by Moose::Meta::Method::Constructor::_generate_slot_initializer at line 226 of Moose/Meta/Method/Constructor.pm, avg 9µs/call # 119 times (1.55ms+0s) by Moose::Meta::Class::superclasses at line 290 of Moose/Meta/Class.pm, avg 13µs/call # 111 times (749µs+0s) by Moose::Meta::Role::add_role at line 397 of Moose/Meta/Role.pm, avg 7µs/call # 110 times (1.00ms+0s) by Class::MOP::Class::superclasses at line 848 of Class/MOP/Class.pm, avg 9µs/call # 110 times (729µs+0s) by Class::MOP::Method::Wrapped::wrap at line 74 of Class/MOP/Method/Wrapped.pm, avg 7µs/call # 106 times (1.54ms+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 43 of Class/MOP/Method/Inlined.pm, avg 15µs/call # 86 times (644µs+0s) by Moose::Meta::TypeConstraint::Class::__ANON__[/home/doy/perl5/perlbrew/perls/perl-5.10.1/lib/site_perl/5.10.1/x86_64-linux/Moose/Meta/TypeConstraint/Class.pm:38] at line 37 of Moose/Meta/TypeConstraint/Class.pm, avg 7µs/call # 83 times (908µs+0s) by Moose::Role::init_meta at line 113 of Moose/Role.pm, avg 11µs/call # 83 times (632µs+0s) by MooseX::Types::TypeDecorator::isa at line 121 of MooseX/Types/TypeDecorator.pm, avg 8µs/call # 81 times (871µs+0s) by base::import at line 81 of base.pm, avg 11µs/call # 77 times (542µs+0s) by MooseX::Types::TypeDecorator::new at line 77 of MooseX/Types/TypeDecorator.pm, avg 7µs/call # 73 times (460µs+0s) by Moose::Meta::Class::add_role at line 179 of Moose/Meta/Class.pm, avg 6µs/call # 73 times (406µs+0s) by Moose::Meta::Class::add_role_application at line 192 of Moose/Meta/Class.pm, avg 6µs/call # 60 times (393µs+0s) by Moose::Util::TypeConstraints::find_type_constraint at line 256 of Moose/Util/TypeConstraints.pm, avg 7µs/call # 49 times (744µs+0s) by namespace::autoclean::__ANON__[/home/doy/perl5/perlbrew/perls/perl-5.10.1/lib/site_perl/5.10.1/namespace/autoclean.pm:57] at line 46 of namespace/autoclean.pm, avg 15µs/call # 45 times (504µs+0s) by Moose::init_meta at line 158 of Moose.pm, avg 11µs/call # 42 times (613µs+0s) by Moose::Util::MetaRole::apply_metaroles at line 35 of Moose/Util/MetaRole.pm, avg 15µs/call # 42 times (306µs+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:643] at line 636 of Moose/Exporter.pm, avg 7µs/call # 42 times (301µs+0s) by Moose::Meta::Method::Destructor::is_needed at line 58 of Moose/Meta/Method/Destructor.pm, avg 7µs/call # 42 times (299µs+0s) by Class::MOP::Class::reinitialize at line 63 of Class/MOP/Class.pm, avg 7µs/call # 42 times (290µs+0s) by Class::MOP::Class::reinitialize at line 56 of Class/MOP/Class.pm, avg 7µs/call # 42 times (254µs+0s) by Class::MOP::Class::reinitialize at line 60 of Class/MOP/Class.pm, avg 6µs/call # 42 times (238µs+0s) by Class::MOP::Package::reinitialize at line 51 of Class/MOP/Package.pm, avg 6µs/call # 31 times (223µs+0s) by Class::MOP::Method::Constructor::new at line 20 of Class/MOP/Method/Constructor.pm, avg 7µs/call # 24 times (120µs+0s) by Moose::Meta::Role::Attribute::attach_to_role at line 55 of Moose/Meta/Role/Attribute.pm, avg 5µs/call # 21 times (148µs+0s) by Moose::Meta::Class::add_attribute at line 300 of Moose/Meta/Class.pm, avg 7µs/call # 20 times (250µs+0s) by metaclass::import at line 32 of metaclass.pm, avg 13µs/call # 20 times (104µs+0s) by Moose::Meta::Role::Composite::new at line 44 of Moose/Meta/Role/Composite.pm, avg 5µs/call # 19 times (216µ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 11µs/call # 15 times (172µs+0s) by Moose::Meta::Attribute::install_delegation at line 738 of Moose/Meta/Attribute.pm, avg 11µs/call # 15 times (89µs+0s) by Moose::Meta::Role::add_attribute at line 232 of Moose/Meta/Role.pm, avg 6µs/call # 3 times (39µs+0s) by Moose::Meta::TypeConstraint::Parameterized::equals at line 27 of Moose/Meta/TypeConstraint/Parameterized.pm, avg 13µs/call # 2 times (17µs+0s) by Class::MOP::Mixin::HasMethods::_add_meta_method at line 28 of Class/MOP/Mixin/HasMethods.pm, avg 9µs/call # once (15µs+0s) by Moose::Meta::Class::_process_inherited_attribute at line 446 of Moose/Meta/Class.pm # once (8µs+0s) by Moose::Meta::Attribute::_process_accessors at line 651 of Moose/Meta/Attribute.pm # once (7µs+0s) by Class::MOP::Class::clone_object at line 671 of Class/MOP/Class.pm # once (6µs+0s) by Moose::Meta::Method::Delegation::new at line 25 of Moose/Meta/Method/Delegation.pm # once (6µs+0s) by Class::MOP::Class::clone_object at line 664 of Class/MOP/Class.pm # once (5µs+0s) by Moose::Meta::Attribute::_process_accessors at line 663 of Moose/Meta/Attribute.pm
sub UNIVERSAL::isa; # xsub
# spent 13.9s (2.43ms+13.9) within main::BEGIN@0 which was called: # once (2.43ms+13.9s) by main::RUNTIME at line 0
sub main::BEGIN@0; # xsub
# spent 26µs within version::(bool which was called: # once (26µs+0s) by DynaLoader::BEGIN@25 at line 47 of Config.pm
sub version::(bool; # xsub
# spent 31µs within version::(cmp which was called: # once (31µs+0s) by DynaLoader::BEGIN@25 at line 50 of Config.pm
sub version::(cmp; # xsub