← 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:14 2010

Filename-e
StatementsExecuted 4 statements in 4.23ms
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
7178493366.1ms66.1msUNIVERSAL::::isa UNIVERSAL::isa (xsub)
6349341943.1ms43.1msUNIVERSAL::::can UNIVERSAL::can (xsub)
17521137.8ms304msClass::MOP::Class:::::around Class::MOP::Class:::around (recurses: max depth 4, inclusive time 8.75ms)
1112.53ms10.6smain::::BEGIN@0 main::BEGIN@0 (xsub)
3030221.69ms10.2ms__TYPE__::::Str __TYPE__::Str
27211.49ms1.48sClass::MOP::Class:::::before Class::MOP::Class:::before
2516111.42ms1.42msUNIVERSAL::::VERSION UNIVERSAL::VERSION (xsub)
665465µs16.1ms__TYPE__::::HashRef __TYPE__::HashRef
664461µs21.6ms__TYPE__::::ArrayRef __TYPE__::ArrayRef
611387µs552µsMoose::Meta::TypeConstraint::::Bool Moose::Meta::TypeConstraint::Bool
611287µs695µsMoose::Meta::TypeConstraint::::ArrayRef[Str] Moose::Meta::TypeConstraint::ArrayRef[Str]
881281µs701µsMooseX::Types::CheckedUtilExports::::as MooseX::Types::CheckedUtilExports::as
881263µs15.0msMooseX::Types::CheckedUtilExports::::subtype MooseX::Types::CheckedUtilExports::subtype
333193µs1.27ms__TYPE__::Markdent::Types::Internal::::HeaderLevel__TYPE__::Markdent::Types::Internal::HeaderLevel
611189µs280µsClass::MOP::Class:::::after Class::MOP::Class:::after
222167µs764µs__TYPE__::Markdent::Types::Internal::::BlockParserClass__TYPE__::Markdent::Types::Internal::BlockParserClass
332154µs942µs__TYPE__::::Int __TYPE__::Int
222133µs746µs__TYPE__::Markdent::Types::Internal::::EventObject__TYPE__::Markdent::Types::Internal::EventObject
222133µs699µs__TYPE__::Markdent::Types::Internal::::SpanParserClass__TYPE__::Markdent::Types::Internal::SpanParserClass
222129µs676µs__TYPE__::Markdent::Types::Internal::::OutputStream__TYPE__::Markdent::Types::Internal::OutputStream
222124µs702µs__TYPE__::::Bool __TYPE__::Bool
22199µs607µs__TYPE__::::ClassName __TYPE__::ClassName
22194µs555µs__TYPE__::::Object __TYPE__::Object
11181µs352µs__TYPE__::Markdent::Types::Internal::::NonEmptyArrayRef__TYPE__::Markdent::Types::Internal::NonEmptyArrayRef
11179µs337µs__TYPE__::Markdent::Types::Internal::::PosInt__TYPE__::Markdent::Types::Internal::PosInt
11177µs369µs__TYPE__::Markdent::Types::Internal::::TableCellAlignment__TYPE__::Markdent::Types::Internal::TableCellAlignment
11174µs344µs__TYPE__::Markdent::Types::Internal::::HandlerObject__TYPE__::Markdent::Types::Internal::HandlerObject
11165µs378µs__TYPE__::::RegexpRef __TYPE__::RegexpRef
11150µs279µs__TYPE__::::Any __TYPE__::Any
11143µs1.98msMooseX::Types::CheckedUtilExports::::enum MooseX::Types::CheckedUtilExports::enum
11132µs32µsversion::::(cmp version::(cmp (xsub)
11127µs27µsversion::::(bool version::(bool (xsub)
52120µs20µsRegexp::::DESTROY Regexp::DESTROY (xsub)
31119µs19µ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
0110.6sProfile data that couldn't be associated with a specific line:
# spent 10.6s making 1 call to main::BEGIN@0
144.23ms1
 
# spent 19µs within Internals::SvREADONLY which was called 3 times, avg 6µs/call: # 3 times (19µ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.42ms within UNIVERSAL::VERSION which was called 25 times, avg 57µs/call: # 10 times (430µ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 43µs/call # once (127µs+0s) by namespace::clean::BEGIN@14 at line 14 of namespace/clean.pm # once (89µs+0s) by MooseX::SemiAffordanceAccessor::BEGIN@9 at line 9 of MooseX/SemiAffordanceAccessor.pm # once (73µs+0s) by namespace::clean::BEGIN@15 at line 15 of namespace/clean.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 (60µs+0s) by Moose::Util::BEGIN@13 at line 13 of Moose/Util.pm # once (59µs+0s) by B::Hooks::EndOfScope::BEGIN@14 at line 14 of B/Hooks/EndOfScope.pm # once (59µs+0s) by namespace::autoclean::BEGIN@16 at line 16 of namespace/autoclean.pm # once (59µs+0s) by Sub::Exporter::BEGIN@9 at line 9 of Sub/Exporter.pm # once (58µs+0s) by Moose::Deprecated::BEGIN@10 at line 22 of Moose/Deprecated.pm # once (57µs+0s) by Data::OptList::BEGIN@8 at line 8 of Data/OptList.pm # once (57µs+0s) by Moose::Exporter::BEGIN@15 at line 15 of Moose/Exporter.pm # once (56µs+0s) by Moose::BEGIN@17 at line 17 of Moose.pm # once (56µs+0s) by namespace::clean::BEGIN@17 at line 17 of namespace/clean.pm # once (50µs+0s) by namespace::clean::BEGIN@16 at line 16 of namespace/clean.pm
sub UNIVERSAL::VERSION; # xsub
# spent 43.1ms within UNIVERSAL::can which was called 6349 times, avg 7µs/call: # 1498 times (7.86ms+0s) by Moose::Meta::Class::new_object at line 260 of Moose/Meta/Class.pm, avg 5µs/call # 1399 times (9.29ms+0s) by Class::MOP::Class::_single_metaclass_can_be_made_compatible at line 352 of Class/MOP/Class.pm, avg 7µs/call # 1141 times (7.31ms+0s) by Class::MOP::Class::_single_metaclass_is_compatible at line 285 of Class/MOP/Class.pm, avg 6µs/call # 427 times (2.73ms+0s) by Moose::Meta::Class::excludes_role at line 245 of Moose/Meta/Class.pm, avg 6µs/call # 296 times (2.35ms+0s) by Class::MOP::Method::Generated::_compile_code at line 47 of Class/MOP/Method/Generated.pm, avg 8µs/call # 212 times (1.60ms+0s) by Class::MOP::Mixin::HasAttributes::add_attribute at line 42 of Class/MOP/Mixin/HasAttributes.pm, avg 8µs/call # 174 times (1.34ms+0s) by Class::MOP::Mixin::HasMethods::_add_meta_method at line 23 of Class/MOP/Mixin/HasMethods.pm, avg 8µs/call # 137 times (1.65ms+0s) by Moose::Exporter::__ANON__[/home/doy/coding/src/Moose/blib/lib//Moose/Exporter.pm:456] at line 417 of Moose/Exporter.pm, avg 12µs/call # 132 times (841µs+0s) by Moose::Meta::Class::_inline_triggers at line 436 of Moose/Meta/Class.pm, avg 6µs/call # 132 times (760µs+0s) by Moose::Meta::Method::Constructor::_eval_environment at line 71 of Moose/Meta/Method/Constructor.pm, avg 6µs/call # 130 times (841µs+0s) by Moose::Meta::Class::does_role at line 225 of Moose/Meta/Class.pm, avg 6µs/call # 114 times (934µs+0s) by Moose::Meta::Class::_inline_check_required_attr at line 355 of Moose/Meta/Class.pm, avg 8µs/call # 101 times (894µs+0s) by Moose::Meta::Class::new_object at line 278 of Moose/Meta/Class.pm, avg 9µs/call # 80 times (606µs+0s) by Moose::Util::MetaRole::_make_new_class at line 161 of Moose/Util/MetaRole.pm, avg 8µs/call # 72 times (929µs+0s) by Moose::Meta::Class::add_attribute at line 491 of Moose/Meta/Class.pm, avg 13µs/call # 72 times (421µs+0s) by Moose::Meta::Role::Composite::add_method at line 83 of Moose/Meta/Role/Composite.pm, avg 6µs/call # 42 times (253µs+0s) by Moose::Object::BUILDALL at line 54 of Moose/Object.pm, avg 6µs/call # 41 times (753µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 68 of Class/MOP/Method/Inlined.pm, avg 18µs/call # 41 times (338µs+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 56 of Class/MOP/Method/Inlined.pm, avg 8µs/call # 14 times (326µs+0s) by Moose::Meta::Attribute::install_delegation at line 983 of Moose/Meta/Attribute.pm, avg 23µs/call # 14 times (234µs+0s) by Class::MOP::Class:::around at line 146 of Moose/Meta/Attribute/Native/Trait.pm, avg 17µ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 # 14 times (171µs+0s) by Sub::Exporter::default_generator at line 848 of Sub/Exporter.pm, avg 12µs/call # 14 times (104µs+0s) by Moose::Util::add_method_modifier at line 216 of Moose/Util.pm, avg 7µs/call # 10 times (126µs+0s) by Moose::Util::resolve_metaclass_alias at line 207 of Moose/Util.pm, avg 13µs/call # 6 times (53µs+0s) by Moose::Meta::TypeConstraint::Parameterizable::parameterize at line 59 of Moose/Meta/TypeConstraint/Parameterizable.pm, avg 9µs/call # 6 times (47µs+0s) by Moose::Object::does at line 125 of Moose/Object.pm, avg 8µs/call # 6 times (42µ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 7 of accessor _native_type (defined at Moose/Meta/Attribute/Native/Trait.pm line 191), avg 7µs/call # 4 times (32µs+0s) by MooseX::Types::TypeDecorator::can at line 141 of MooseX/Types/TypeDecorator.pm, avg 8µs/call # 2 times (19µs+0s) by if::work at line 14 of if.pm, avg 10µs/call # once (24µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 173 of Moose/Meta/Attribute.pm # once (18µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 213 of Moose/Meta/Attribute.pm # once (17µs+0s) by Moose::Meta::Attribute::clone_and_inherit_options at line 223 of Moose/Meta/Attribute.pm # once (7µs+0s) by Moose::Object::BEGIN@109 at line 110 of Moose/Object.pm
sub UNIVERSAL::can; # xsub
# spent 66.1ms within UNIVERSAL::isa which was called 7178 times, avg 9µs/call: # 2792 times (25.6ms+0s) by Class::MOP::Object::_is_compatible_with at line 50 of Class/MOP/Object.pm, avg 9µs/call # 745 times (7.71ms+0s) by Moose::Meta::Role::Application::ToClass::apply_methods at line 155 of Moose/Meta/Role/Application/ToClass.pm, avg 10µs/call # 529 times (5.62ms+0s) by Moose::Meta::Role::Application::ToRole::apply_methods at line 84 of Moose/Meta/Role/Application/ToRole.pm, avg 11µs/call # 279 times (4.32ms+0s) by Class::MOP::Class::new_object at line 550 of Class/MOP/Class.pm, avg 15µs/call # 269 times (1.86ms+0s) by Class::MOP::Method::Accessor::new at line 27 of Class/MOP/Method/Accessor.pm, avg 7µs/call # 214 times (1.67ms+0s) by Moose::Meta::Role::apply at line 440 of Moose/Meta/Role.pm, avg 8µs/call # 212 times (1.61ms+0s) by Class::MOP::Mixin::HasAttributes::add_attribute at line 21 of Class/MOP/Mixin/HasAttributes.pm, avg 8µs/call # 188 times (971µs+0s) by Class::MOP::Attribute::attach_to_class at line 238 of Class/MOP/Attribute.pm, avg 5µs/call # 126 times (891µs+0s) by Class::MOP::Method::Wrapped::wrap at line 74 of Class/MOP/Method/Wrapped.pm, avg 7µs/call # 120 times (1.60ms+0s) by Moose::Meta::Class::superclasses at line 474 of Moose/Meta/Class.pm, avg 13µs/call # 116 times (765µ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 (1.04ms+0s) by Class::MOP::Class::superclasses at line 989 of Class/MOP/Class.pm, avg 9µs/call # 106 times (1.50ms+0s) by Class::MOP::Method::Inlined::can_be_inlined at line 41 of Class/MOP/Method/Inlined.pm, avg 14µs/call # 104 times (831µs+0s) by Moose::Util::_apply_all_roles at line 117 of Moose/Util.pm, avg 8µs/call # 88 times (651µs+0s) by Moose::Meta::Role::Application::RoleSummation::apply_methods at line 167 of Moose/Meta/Role/Application/RoleSummation.pm, avg 7µs/call # 86 times (681µs+0s) by Moose::Meta::TypeConstraint::Class::__ANON__[/home/doy/coding/src/Moose/blib/lib//Moose/Meta/TypeConstraint/Class.pm:38] at line 37 of Moose/Meta/TypeConstraint/Class.pm, avg 8µs/call # 81 times (900µs+0s) by base::import at line 81 of base.pm, avg 11µs/call # 77 times (539µs+0s) by MooseX::Types::TypeDecorator::new at line 77 of MooseX/Types/TypeDecorator.pm, avg 7µs/call # 75 times (571µs+0s) by MooseX::Types::TypeDecorator::isa at line 121 of MooseX/Types/TypeDecorator.pm, avg 8µs/call # 73 times (474µs+0s) by Moose::Meta::Class::add_role at line 179 of Moose/Meta/Class.pm, avg 6µs/call # 73 times (426µs+0s) by Moose::Meta::Class::add_role_application at line 192 of Moose/Meta/Class.pm, avg 6µs/call # 68 times (416µs+0s) by Moose::Meta::Role::add_role at line 397 of Moose/Meta/Role.pm, avg 6µs/call # 60 times (312µs+0s) by Moose::Util::TypeConstraints::find_type_constraint at line 256 of Moose/Util/TypeConstraints.pm, avg 5µs/call # 49 times (669µ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 14µs/call # 45 times (552µs+0s) by Moose::init_meta at line 158 of Moose.pm, avg 12µs/call # 43 times (300µs+0s) by Moose::Meta::Method::Destructor::is_needed at line 58 of Moose/Meta/Method/Destructor.pm, avg 7µs/call # 42 times (609µs+0s) by Moose::Util::MetaRole::apply_metaroles at line 35 of Moose/Util/MetaRole.pm, avg 15µs/call # 42 times (319µs+0s) by Moose::Exporter::__ANON__[/home/doy/coding/src/Moose/blib/lib//Moose/Exporter.pm:643] at line 636 of Moose/Exporter.pm, avg 8µs/call # 42 times (297µs+0s) by Class::MOP::Class::reinitialize at line 63 of Class/MOP/Class.pm, avg 7µs/call # 42 times (250µs+0s) by Class::MOP::Class::reinitialize at line 56 of Class/MOP/Class.pm, avg 6µs/call # 42 times (220µs+0s) by Class::MOP::Package::reinitialize at line 51 of Class/MOP/Package.pm, avg 5µs/call # 42 times (220µs+0s) by Class::MOP::Class::reinitialize at line 60 of Class/MOP/Class.pm, avg 5µs/call # 40 times (414µs+0s) by Moose::Role::init_meta at line 113 of Moose/Role.pm, avg 10µs/call # 31 times (226µs+0s) by Class::MOP::Method::Constructor::new at line 21 of Class/MOP/Method/Constructor.pm, avg 7µs/call # 24 times (122µ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 (153µs+0s) by Moose::Meta::Class::add_attribute at line 484 of Moose/Meta/Class.pm, avg 7µs/call # 20 times (241µs+0s) by metaclass::import at line 32 of metaclass.pm, avg 12µs/call # 15 times (179µs+0s) by Moose::Meta::Attribute::install_delegation at line 983 of Moose/Meta/Attribute.pm, avg 12µs/call # 15 times (92µs+0s) by Moose::Meta::Role::add_attribute at line 239 of Moose/Meta/Role.pm, avg 6µs/call # 12 times (124µ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 10µs/call # 8 times (41µs+0s) by Moose::Meta::Role::Composite::new at line 44 of Moose/Meta/Role/Composite.pm, avg 5µs/call # 3 times (38µ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 26 of Class/MOP/Mixin/HasMethods.pm, avg 9µs/call # once (14µs+0s) by Moose::Meta::Class::_process_inherited_attribute at line 630 of Moose/Meta/Class.pm # once (9µs+0s) by Moose::Meta::Method::Delegation::new at line 25 of Moose/Meta/Method/Delegation.pm # once (8µs+0s) by Moose::Meta::Attribute::_process_accessors at line 912 of Moose/Meta/Attribute.pm # once (8µs+0s) by Class::MOP::Class::clone_object at line 813 of Class/MOP/Class.pm # once (5µs+0s) by Class::MOP::Class::clone_object at line 806 of Class/MOP/Class.pm # once (5µs+0s) by Moose::Meta::Attribute::_process_accessors at line 924 of Moose/Meta/Attribute.pm
sub UNIVERSAL::isa; # xsub
# spent 10.6s (2.53ms+10.6) within main::BEGIN@0 which was called: # once (2.53ms+10.6s) by main::RUNTIME at line 0
sub main::BEGIN@0; # xsub
# spent 27µs within version::(bool which was called: # once (27µs+0s) by DynaLoader::BEGIN@25 at line 47 of Config.pm
sub version::(bool; # xsub
# spent 32µs within version::(cmp which was called: # once (32µs+0s) by DynaLoader::BEGIN@25 at line 50 of Config.pm
sub version::(cmp; # xsub