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

Filename/home/doy/perl5/perlbrew/perls/perl-5.10.1/lib/5.10.1/base.pm
StatementsExecuted 1690 statements in 22.9ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
57575633.5ms521msbase::::importbase::import (recurses: max depth 3, inclusive time 48.2ms)
68112.25ms2.25msbase::::has_versionbase::has_version
1111.58ms3.22msbase::::BEGIN@4base::BEGIN@4
68111.44ms1.44msbase::::has_fieldsbase::has_fields
68111.16ms1.16msbase::::has_attrbase::has_attr
11161µs137µsbase::::BEGIN@3base::BEGIN@3
0000s0sbase::::__ANON__[:54]base::__ANON__[:54]
0000s0sbase::::__ANON__[:61]base::__ANON__[:61]
0000s0sbase::::get_attrbase::get_attr
0000s0sbase::::inherit_fieldsbase::inherit_fields
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1package base;
2
33105µs2212µs
# spent 137µs (61+75) within base::BEGIN@3 which was called: # once (61µs+75µs) by Markdent::Types::BEGIN@9 at line 3
use strict 'vars';
# spent 137µs making 1 call to base::BEGIN@3 # spent 75µs making 1 call to strict::import
433.28ms23.35ms
# spent 3.22ms (1.58+1.64) within base::BEGIN@4 which was called: # once (1.58ms+1.64ms) by Markdent::Types::BEGIN@9 at line 4
use vars qw($VERSION);
# spent 3.22ms making 1 call to base::BEGIN@4 # spent 134µs making 1 call to vars::import
515µs$VERSION = '2.14';
6170µs$VERSION = eval $VERSION;
# spent 12µs executing statements in string eval
7
8# constant.pm is slow
9sub SUCCESS () { 1 }
10
11sub PUBLIC () { 2**0 }
12sub PRIVATE () { 2**1 }
13sub INHERITED () { 2**2 }
14sub PROTECTED () { 2**3 }
15
16
1715µsmy $Fattr = \%fields::attr;
18
19
# spent 1.44ms within base::has_fields which was called 68 times, avg 21µs/call: # 68 times (1.44ms+0s) by base::import at line 113, avg 21µs/call
sub has_fields {
2068271µs my($base) = shift;
2168497µs my $fglob = ${"$base\::"}{FIELDS};
2268980µs return( ($fglob && 'GLOB' eq ref($fglob) && *$fglob{HASH}) ? 1 : 0 );
23}
24
25
# spent 2.25ms within base::has_version which was called 68 times, avg 33µs/call: # 68 times (2.25ms+0s) by base::import at line 83, avg 33µs/call
sub has_version {
2668272µs my($base) = shift;
2768851µs my $vglob = ${$base.'::'}{VERSION};
28681.34ms return( ($vglob && *$vglob{SCALAR}) ? 1 : 0 );
29}
30
31
# spent 1.16ms within base::has_attr which was called 68 times, avg 17µs/call: # 68 times (1.16ms+0s) by base::import at line 113, avg 17µs/call
sub has_attr {
3268256µs my($proto) = shift;
3368206µs my($class) = ref $proto || $proto;
3468868µs return exists $Fattr->{$class};
35}
36
37sub get_attr {
38 $Fattr->{$_[0]} = [1] unless $Fattr->{$_[0]};
39 return $Fattr->{$_[0]};
40}
41
42118µsif ($] < 5.009) {
43 *get_fields = sub {
44 # Shut up a possible typo warning.
45 () = \%{$_[0].'::FIELDS'};
46 my $f = \%{$_[0].'::FIELDS'};
47
48 # should be centralized in fields? perhaps
49 # fields::mk_FIELDS_be_OK. Peh. As long as %{ $package . '::FIELDS' }
50 # is used here anyway, it doesn't matter.
51 bless $f, 'pseudohash' if (ref($f) ne 'pseudohash');
52
53 return $f;
54 }
55}
56else {
57 *get_fields = sub {
58 # Shut up a possible typo warning.
59 () = \%{$_[0].'::FIELDS'};
60 return \%{$_[0].'::FIELDS'};
61 }
62113µs}
63
64
# spent 521ms (33.5+487) within base::import which was called 57 times, avg 9.14ms/call: # once (2.06ms+455ms) by Markdent::Types::BEGIN@9 at line 9 of Markdent/Types.pm # once (2.41ms+36.0ms) by Moose::Meta::Attribute::BEGIN@23 at line 23 of Moose/Meta/Attribute.pm # once (1.68ms+12.8ms) by Moose::Meta::Method::Overridden::BEGIN@10 at line 10 of Moose/Meta/Method/Overridden.pm # once (359µs+243µs) by Moose::Meta::Role::BEGIN@25 at line 25 of Moose/Meta/Role.pm # once (408µs+163µs) by Moose::Meta::Method::Destructor::BEGIN@15 at line 15 of Moose/Meta/Method/Destructor.pm # once (279µs+171µs) by Moose::Meta::Method::Delegation::BEGIN@14 at line 14 of Moose/Meta/Method/Delegation.pm # once (275µs+157µs) by Moose::Meta::Method::Meta::BEGIN@11 at line 11 of Moose/Meta/Method/Meta.pm # once (268µs+161µs) by Moose::Meta::Method::Constructor::BEGIN@13 at line 13 of Moose/Meta/Method/Constructor.pm # once (257µs+163µs) by Moose::Meta::Method::Accessor::BEGIN@11 at line 11 of Moose/Meta/Method/Accessor.pm # once (330µs+72µs) by Moose::Meta::TypeCoercion::Union::BEGIN@14 at line 14 of Moose/Meta/TypeCoercion/Union.pm # once (257µs+108µs) by Moose::Meta::Class::Immutable::Trait::BEGIN@13 at line 13 of Moose/Meta/Class/Immutable/Trait.pm # once (211µs+113µs) by Moose::Meta::TypeConstraint::BEGIN@16 at line 16 of Moose/Meta/TypeConstraint.pm # once (173µs+150µs) by Moose::Meta::TypeConstraint::Enum::BEGIN@13 at line 13 of Moose/Meta/TypeConstraint/Enum.pm # once (217µs+86µs) by Moose::Meta::Role::Method::Conflicting::BEGIN@9 at line 9 of Moose/Meta/Role/Method/Conflicting.pm # once (200µs+91µs) by Moose::Meta::Method::Augmented::BEGIN@10 at line 10 of Moose/Meta/Method/Augmented.pm # once (179µs+100µs) by Moose::Meta::Role::Method::BEGIN@11 at line 11 of Moose/Meta/Role/Method.pm # once (192µs+85µs) by Moose::Meta::Class::BEGIN@29 at line 29 of Moose/Meta/Class.pm # once (182µs+93µs) by Moose::Meta::Role::Application::ToInstance::BEGIN@13 at line 13 of Moose/Meta/Role/Application/ToInstance.pm # once (170µs+105µs) by Moose::Meta::TypeConstraint::Parameterizable::BEGIN@11 at line 11 of Moose/Meta/TypeConstraint/Parameterizable.pm # once (185µs+87µs) by Moose::Meta::Instance::BEGIN@13 at line 13 of Moose/Meta/Instance.pm # once (176µs+84µs) by Moose::Meta::Role::Attribute::BEGIN@13 at line 13 of Moose/Meta/Role/Attribute.pm # once (177µs+81µs) by Moose::Meta::TypeConstraint::DuckType::BEGIN@17 at line 17 of Moose/Meta/TypeConstraint/DuckType.pm # once (178µs+79µs) by Moose::Meta::Role::Composite::BEGIN@13 at line 13 of Moose/Meta/Role/Composite.pm # once (168µs+84µs) by Moose::Meta::Role::Application::ToClass::BEGIN@14 at line 14 of Moose/Meta/Role/Application/ToClass.pm # once (166µs+82µs) by Moose::Meta::TypeConstraint::Role::BEGIN@14 at line 14 of Moose/Meta/TypeConstraint/Role.pm # once (171µs+77µs) by Variable::Magic::BEGIN@565 at line 565 of Variable/Magic.pm # once (165µs+82µs) by Moose::Meta::Role::Application::RoleSummation::BEGIN@15 at line 15 of Moose/Meta/Role/Application/RoleSummation.pm # once (165µs+82µs) by Moose::Meta::Role::Application::ToRole::BEGIN@13 at line 13 of Moose/Meta/Role/Application/ToRole.pm # once (164µs+82µs) by Moose::Meta::TypeConstraint::Registry::BEGIN@14 at line 14 of Moose/Meta/TypeConstraint/Registry.pm # once (164µs+81µs) by Moose::Error::Default::BEGIN@13 at line 13 of Moose/Error/Default.pm # once (162µs+81µs) by Moose::Meta::TypeConstraint::Class::BEGIN@14 at line 14 of Moose/Meta/TypeConstraint/Class.pm # once (159µs+81µs) by Moose::Meta::TypeConstraint::Parameterized::BEGIN@15 at line 15 of Moose/Meta/TypeConstraint/Parameterized.pm # once (163µs+76µs) by Devel::Caller::BEGIN@7 at line 7 of Devel/Caller.pm # once (157µs+82µs) by Moose::Meta::Role::Method::Required::BEGIN@11 at line 11 of Moose/Meta/Role/Method/Required.pm # once (163µs+75µs) by List::AllUtils::BEGIN@16 at line 16 of List/AllUtils.pm # once (160µs+76µs) by MooseX::Types::Util::BEGIN@14 at line 14 of MooseX/Types/Util.pm # once (156µs+79µs) by Moose::Meta::TypeConstraint::Union::BEGIN@16 at line 16 of Moose/Meta/TypeConstraint/Union.pm # once (160µs+72µs) by Markdent::Regexes::BEGIN@11 at line 11 of Markdent/Regexes.pm # once (1.24ms+-1.24ms) by Class::MOP::Mixin::AttributeCore::BEGIN@12 at line 12 of Class/MOP/Mixin/AttributeCore.pm # once (259µs+-259µs) by Class::MOP::Attribute::BEGIN@17 at line 17 of Class/MOP/Attribute.pm # once (2.42ms+-2.42ms) by Class::MOP::Method::BEGIN@14 at line 14 of Class/MOP/Method.pm # once (170µs+-170µs) by Class::MOP::Method::Wrapped::BEGIN@14 at line 14 of Class/MOP/Method/Wrapped.pm # once (2.93ms+-2.93ms) by Class::MOP::Method::Accessor::BEGIN@14 at line 14 of Class/MOP/Method/Accessor.pm # once (177µs+-177µs) by Class::MOP::Package::BEGIN@15 at line 15 of Class/MOP/Package.pm # once (179µs+-179µs) by Class::MOP::Mixin::HasMethods::BEGIN@16 at line 16 of Class/MOP/Mixin/HasMethods.pm # once (174µs+-174µs) by Class::MOP::Method::Generated::BEGIN@13 at line 13 of Class/MOP/Method/Generated.pm # once (162µs+-162µs) by Class::MOP::Instance::BEGIN@13 at line 13 of Class/MOP/Instance.pm # once (169µs+-169µs) by Moose::Meta::Method::BEGIN@12 at line 12 of Moose/Meta/Method.pm # once (126µs+-126µs) by Sub::Name::BEGIN@50 at line 50 of Sub/Name.pm # once (159µs+-159µs) by Sub::Name::BEGIN@49 at line 49 of Sub/Name.pm # once (169µs+-169µs) by Class::MOP::Method::Inlined::BEGIN@13 at line 13 of Class/MOP/Method/Inlined.pm # once (3.31ms+-3.31ms) by Class::MOP::Method::Meta::BEGIN@16 at line 16 of Class/MOP/Method/Meta.pm # once (235µs+-235µs) by Class::MOP::Mixin::HasAttributes::BEGIN@13 at line 13 of Class/MOP/Mixin/HasAttributes.pm # once (2.49ms+-2.49ms) by Class::MOP::Class::BEGIN@24 at line 24 of Class/MOP/Class.pm # once (3.03ms+-3.03ms) by Class::MOP::Module::BEGIN@14 at line 14 of Class/MOP/Module.pm # once (194µs+-194µs) by Moose::Meta::Mixin::AttributeCore::BEGIN@9 at line 9 of Moose/Meta/Mixin/AttributeCore.pm # once (2.60ms+-2.60ms) by Class::MOP::Method::Constructor::BEGIN@14 at line 14 of Class/MOP/Method/Constructor.pm
sub import {
6557237µs my $class = shift;
66
6757128µs return SUCCESS unless @_;
68
69 # List of base classes from which we will inherit %FIELDS.
7057101µs my $fields_base;
71
7257217µs my $inheritor = caller(0);
7357112µs my @isa_classes;
74
755798µs my @bases;
7657346µs foreach my $base (@_) {
7768179µs if ( $inheritor eq $base ) {
78 warn "Class '$inheritor' tried to inherit from itself\n";
79 }
80
81683.36ms81871µs next if grep $_->isa($base), ($inheritor, @bases);
# spent 871µs making 81 calls to UNIVERSAL::isa, avg 11µs/call
82
8368898µs682.25ms if (has_version($base)) {
# spent 2.25ms making 68 calls to base::has_version, avg 33µs/call
84 ${$base.'::VERSION'} = '-1, set by base.pm'
8557414µs unless defined ${$base.'::VERSION'};
86 }
87 else {
881120µs my $sigdie;
89 {
9022251µs local $SIG{__DIE__};
91111.06ms eval "require $base";
# spent 402µs executing statements in string eval # spent 401µs executing statements in string eval # spent 367µs executing statements in string eval # spent 347µs executing statements in string eval # spent 342µs executing statements in string eval # spent 340µs executing statements in string eval # spent 336µs executing statements in string eval # spent 335µs executing statements in string eval # spent 335µs executing statements in string eval # spent 327µs executing statements in string eval # spent 12µs executing statements in string eval
92 # Only ignore "Can't locate" errors from our eval require.
93 # Other fatal errors (syntax etc) must be reported.
941125µs die if $@ && $@ !~ /^Can't locate .*? at \(eval /;
9511159µs unless (%{"$base\::"}) {
96 require Carp;
97 local $" = " ";
98 Carp::croak(<<ERROR);
99Base class package "$base" is empty.
100 (Perhaps you need to 'use' the module which defines that package first,
101 or make that module available in \@INC (\@INC contains: @INC).
102ERROR
103 }
10411196µs $sigdie = $SIG{__DIE__} || undef;
105 }
106 # Make sure a global $SIG{__DIE__} makes it out of the localization.
1071126µs $SIG{__DIE__} = $sigdie if defined $sigdie;
108 ${$base.'::VERSION'} = "-1, set by base.pm"
10911100µs unless defined ${$base.'::VERSION'};
110 }
11168311µs push @bases, $base;
112
113681.65ms1362.59ms if ( has_fields($base) || has_attr($base) ) {
# spent 1.44ms making 68 calls to base::has_fields, avg 21µs/call # spent 1.16ms making 68 calls to base::has_attr, avg 17µs/call
114 # No multiple fields inheritance *suck*
115 if ($fields_base) {
116 require Carp;
117 Carp::croak("Can't multiply inherit fields");
118 } else {
119 $fields_base = $base;
120 }
121 }
122 }
123 # Save this until the end so it's all or nothing if the above loop croaks.
12457747µs push @{"$inheritor\::ISA"}, @isa_classes;
125
126571.93ms push @{"$inheritor\::ISA"}, @bases;
127
128571.26ms if( defined $fields_base ) {
129 inherit_fields($inheritor, $fields_base);
130 }
131}
132
133
134sub inherit_fields {
135 my($derived, $base) = @_;
136
137 return SUCCESS unless $base;
138
139 my $battr = get_attr($base);
140 my $dattr = get_attr($derived);
141 my $dfields = get_fields($derived);
142 my $bfields = get_fields($base);
143
144 $dattr->[0] = @$battr;
145
146 if( keys %$dfields ) {
147 warn <<"END";
148$derived is inheriting from $base but already has its own fields!
149This will cause problems. Be sure you use base BEFORE declaring fields.
150END
151
152 }
153
154 # Iterate through the base's fields adding all the non-private
155 # ones to the derived class. Hang on to the original attribute
156 # (Public, Private, etc...) and add Inherited.
157 # This is all too complicated to do efficiently with add_fields().
158 while (my($k,$v) = each %$bfields) {
159 my $fno;
160 if ($fno = $dfields->{$k} and $fno != $v) {
161 require Carp;
162 Carp::croak ("Inherited fields can't override existing fields");
163 }
164
165 if( $battr->[$v] & PRIVATE ) {
166 $dattr->[$v] = PRIVATE | INHERITED;
167 }
168 else {
169 $dattr->[$v] = INHERITED | $battr->[$v];
170 $dfields->{$k} = $v;
171 }
172 }
173
174 foreach my $idx (1..$#{$battr}) {
175 next if defined $dattr->[$idx];
176 $dattr->[$idx] = $battr->[$idx] & INHERITED;
177 }
178}
179
180
181118µs1;
182
183__END__
Report produced by the NYTProf 4.04 Perl profiler, developed by Tim Bunce and Adam Kaplan.