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

Filename/home/doy/perl5/perlbrew/perls/perl-5.10.1/lib/5.10.1/base.pm
StatementsExecuted 1594 statements in 21.2ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
54545232.7ms127msbase::::importbase::import (recurses: max depth 2, inclusive time 22.7ms)
64112.11ms2.11msbase::::has_versionbase::has_version
64111.28ms1.28msbase::::has_fieldsbase::has_fields
64111.13ms1.13msbase::::has_attrbase::has_attr
111131µs202µsbase::::BEGIN@3base::BEGIN@3
11132µs140µsbase::::BEGIN@4base::BEGIN@4
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
33109µs2272µs
# spent 202µs (131+71) within base::BEGIN@3 which was called: # once (131µs+71µs) by Class::MOP::Mixin::AttributeCore::BEGIN@12 at line 3
use strict 'vars';
# spent 202µs making 1 call to base::BEGIN@3 # spent 71µs making 1 call to strict::import
433.06ms2249µs
# spent 140µs (32+108) within base::BEGIN@4 which was called: # once (32µs+108µs) by Class::MOP::Mixin::AttributeCore::BEGIN@12 at line 4
use vars qw($VERSION);
# spent 140µs making 1 call to base::BEGIN@4 # spent 108µs making 1 call to vars::import
514µs$VERSION = '2.14';
6160µs$VERSION = eval $VERSION;
# spent 11µ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.28ms within base::has_fields which was called 64 times, avg 20µs/call: # 64 times (1.28ms+0s) by base::import at line 113, avg 20µs/call
sub has_fields {
2064259µs my($base) = shift;
2164406µs my $fglob = ${"$base\::"}{FIELDS};
2264914µs return( ($fglob && 'GLOB' eq ref($fglob) && *$fglob{HASH}) ? 1 : 0 );
23}
24
25
# spent 2.11ms within base::has_version which was called 64 times, avg 33µs/call: # 64 times (2.11ms+0s) by base::import at line 83, avg 33µs/call
sub has_version {
2664272µs my($base) = shift;
2764796µs my $vglob = ${$base.'::'}{VERSION};
28641.22ms return( ($vglob && *$vglob{SCALAR}) ? 1 : 0 );
29}
30
31
# spent 1.13ms within base::has_attr which was called 64 times, avg 18µs/call: # 64 times (1.13ms+0s) by base::import at line 113, avg 18µs/call
sub has_attr {
3264239µs my($proto) = shift;
3364211µs my($class) = ref $proto || $proto;
3464833µs return exists $Fattr->{$class};
35}
36
37sub get_attr {
38 $Fattr->{$_[0]} = [1] unless $Fattr->{$_[0]};
39 return $Fattr->{$_[0]};
40}
41
4217µ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 }
62112µs}
63
64
# spent 127ms (32.7+94.6) within base::import which was called 54 times, avg 2.36ms/call: # once (2.45ms+35.4ms) by Moose::Meta::Attribute::BEGIN@23 at line 23 of Moose/Meta/Attribute.pm # once (1.96ms+28.9ms) by Class::MOP::Method::Accessor::BEGIN@15 at line 15 of Class/MOP/Method/Accessor.pm # once (2.62ms+19.3ms) by Class::MOP::Class::BEGIN@24 at line 24 of Class/MOP/Class.pm # once (1.70ms+11.2ms) by Moose::Meta::Method::Overridden::BEGIN@10 at line 10 of Moose/Meta/Method/Overridden.pm # once (3.32ms+3.99ms) by Class::MOP::Method::Meta::BEGIN@16 at line 16 of Class/MOP/Method/Meta.pm # once (2.41ms+1.02ms) by Class::MOP::Method::Constructor::BEGIN@15 at line 15 of Class/MOP/Method/Constructor.pm # once (1.15ms+472µs) by Class::MOP::Mixin::AttributeCore::BEGIN@12 at line 12 of Class/MOP/Mixin/AttributeCore.pm # once (355µs+248µs) by Moose::Meta::Role::BEGIN@25 at line 25 of Moose/Meta/Role.pm # once (279µs+160µs) by Moose::Meta::Method::Constructor::BEGIN@14 at line 14 of Moose/Meta/Method/Constructor.pm # once (260µs+179µs) by Moose::Meta::Method::Accessor::BEGIN@13 at line 13 of Moose/Meta/Method/Accessor.pm # once (254µs+172µs) by Class::MOP::Attribute::BEGIN@17 at line 17 of Class/MOP/Attribute.pm # once (251µs+160µs) by Moose::Meta::Method::Delegation::BEGIN@14 at line 14 of Moose/Meta/Method/Delegation.pm # once (253µs+157µs) by Moose::Meta::Method::Destructor::BEGIN@15 at line 15 of Moose/Meta/Method/Destructor.pm # once (248µs+156µs) by Moose::Meta::Method::Meta::BEGIN@11 at line 11 of Moose/Meta/Method/Meta.pm # once (300µs+72µs) by Moose::Meta::TypeCoercion::Union::BEGIN@14 at line 14 of Moose/Meta/TypeCoercion/Union.pm # once (176µs+150µs) by Moose::Meta::Method::Augmented::BEGIN@10 at line 10 of Moose/Meta/Method/Augmented.pm # once (167µs+157µs) by Moose::Meta::Role::Application::ToInstance::BEGIN@13 at line 13 of Moose/Meta/Role/Application/ToInstance.pm # once (200µs+93µs) by Variable::Magic::BEGIN@565 at line 565 of Variable/Magic.pm # once (190µs+96µs) by Moose::Meta::TypeConstraint::DuckType::BEGIN@17 at line 17 of Moose/Meta/TypeConstraint/DuckType.pm # once (190µs+85µs) by Moose::Meta::Instance::BEGIN@13 at line 13 of Moose/Meta/Instance.pm # once (178µs+94µs) by Moose::Meta::TypeConstraint::Registry::BEGIN@14 at line 14 of Moose/Meta/TypeConstraint/Registry.pm # once (184µs+82µs) by Moose::Error::Default::BEGIN@13 at line 13 of Moose/Error/Default.pm # once (181µs+84µs) by Moose::Meta::Class::BEGIN@29 at line 29 of Moose/Meta/Class.pm # once (178µs+86µs) by Moose::Meta::Role::Attribute::BEGIN@13 at line 13 of Moose/Meta/Role/Attribute.pm # once (183µs+80µs) by Set::Object::Weak::BEGIN@28 at line 28 of Set/Object/Weak.pm # once (178µs+84µs) by Moose::Meta::Role::Method::Conflicting::BEGIN@9 at line 9 of Moose/Meta/Role/Method/Conflicting.pm # once (181µs+81µs) by Moose::Meta::Role::Composite::BEGIN@13 at line 13 of Moose/Meta/Role/Composite.pm # once (176µs+82µs) by Moose::Meta::Role::Method::BEGIN@11 at line 11 of Moose/Meta/Role/Method.pm # once (170µs+89µs) by Moose::Meta::Class::Immutable::Trait::BEGIN@13 at line 13 of Moose/Meta/Class/Immutable/Trait.pm # once (177µs+79µs) by Class::MOP::Method::Wrapped::BEGIN@14 at line 14 of Class/MOP/Method/Wrapped.pm # once (166µs+82µs) by Moose::Meta::TypeConstraint::Parameterizable::BEGIN@11 at line 11 of Moose/Meta/TypeConstraint/Parameterizable.pm # once (170µs+77µs) by Sub::Name::BEGIN@49 at line 49 of Sub/Name.pm # once (162µs+83µs) by Moose::Meta::TypeConstraint::Enum::BEGIN@13 at line 13 of Moose/Meta/TypeConstraint/Enum.pm # once (161µs+83µs) by Moose::Meta::Role::Application::ToClass::BEGIN@14 at line 14 of Moose/Meta/Role/Application/ToClass.pm # once (162µs+82µs) by Moose::Meta::Role::Application::ToRole::BEGIN@13 at line 13 of Moose/Meta/Role/Application/ToRole.pm # once (163µs+81µs) by Moose::Meta::TypeConstraint::Role::BEGIN@14 at line 14 of Moose/Meta/TypeConstraint/Role.pm # once (162µs+82µs) by Moose::Meta::Role::Application::RoleSummation::BEGIN@15 at line 15 of Moose/Meta/Role/Application/RoleSummation.pm # once (160µs+83µs) by Moose::Meta::TypeConstraint::Union::BEGIN@16 at line 16 of Moose/Meta/TypeConstraint/Union.pm # once (160µs+81µs) by Moose::Meta::TypeConstraint::Class::BEGIN@14 at line 14 of Moose/Meta/TypeConstraint/Class.pm # once (164µs+78µs) by Class::MOP::Mixin::HasMethods::BEGIN@16 at line 16 of Class/MOP/Mixin/HasMethods.pm # once (159µs+82µs) by Moose::Meta::TypeConstraint::Parameterized::BEGIN@15 at line 15 of Moose/Meta/TypeConstraint/Parameterized.pm # once (159µs+80µs) by Class::MOP::Instance::BEGIN@13 at line 13 of Class/MOP/Instance.pm # once (156µs+83µs) by Moose::Meta::Role::Method::Required::BEGIN@11 at line 11 of Moose/Meta/Role/Method/Required.pm # once (159µs+75µs) by Class::MOP::Mixin::HasAttributes::BEGIN@13 at line 13 of Class/MOP/Mixin/HasAttributes.pm # once (149µs+81µs) by Moose::Meta::TypeConstraint::BEGIN@16 at line 16 of Moose/Meta/TypeConstraint.pm # once (125µs+63µs) by Sub::Name::BEGIN@50 at line 50 of Sub/Name.pm # once (73µs+8µs) by Set::Object::Weak::BEGIN@30 at line 30 of Set/Object/Weak.pm # once (179µs+-179µs) by Class::MOP::Method::Inlined::BEGIN@13 at line 13 of Class/MOP/Method/Inlined.pm # once (171µs+-171µs) by Moose::Meta::Method::BEGIN@12 at line 12 of Moose/Meta/Method.pm # once (174µs+-174µs) by Class::MOP::Method::Generated::BEGIN@14 at line 14 of Class/MOP/Method/Generated.pm # once (278µs+-278µs) by Class::MOP::Package::BEGIN@15 at line 15 of Class/MOP/Package.pm # once (6.24ms+-6.24ms) by Class::MOP::Module::BEGIN@14 at line 14 of Class/MOP/Module.pm # once (2.37ms+-2.37ms) by Class::MOP::Method::BEGIN@14 at line 14 of Class/MOP/Method.pm # once (179µs+-179µs) by Moose::Meta::Mixin::AttributeCore::BEGIN@9 at line 9 of Moose/Meta/Mixin/AttributeCore.pm
sub import {
6554235µs my $class = shift;
66
6754124µs return SUCCESS unless @_;
68
69 # List of base classes from which we will inherit %FIELDS.
705495µs my $fields_base;
71
7254218µs my $inheritor = caller(0);
7354109µs my @isa_classes;
74
755494µs my @bases;
7654336µs foreach my $base (@_) {
7765173µs if ( $inheritor eq $base ) {
78 warn "Class '$inheritor' tried to inherit from itself\n";
79 }
80
81653.23ms78912µs next if grep $_->isa($base), ($inheritor, @bases);
# spent 912µs making 78 calls to UNIVERSAL::isa, avg 12µs/call
82
8364827µs642.11ms if (has_version($base)) {
# spent 2.11ms making 64 calls to base::has_version, avg 33µs/call
84 ${$base.'::VERSION'} = '-1, set by base.pm'
8554399µs unless defined ${$base.'::VERSION'};
86 }
87 else {
881019µs my $sigdie;
89 {
9020209µs local $SIG{__DIE__};
9110884µs eval "require $base";
# spent 377µs executing statements in string eval # spent 370µs executing statements in string eval # spent 334µs executing statements in string eval # spent 317µs executing statements in string eval # spent 316µs executing statements in string eval # spent 313µs executing statements in string eval # spent 313µs executing statements in string eval # spent 304µs executing statements in string eval # spent 277µs executing statements in string eval # spent 11µ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.
941025µs die if $@ && $@ !~ /^Can't locate .*? at \(eval /;
9510143µ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 }
10410181µs $sigdie = $SIG{__DIE__} || undef;
105 }
106 # Make sure a global $SIG{__DIE__} makes it out of the localization.
1071025µs $SIG{__DIE__} = $sigdie if defined $sigdie;
108 ${$base.'::VERSION'} = "-1, set by base.pm"
1091094µs unless defined ${$base.'::VERSION'};
110 }
11164289µs push @bases, $base;
112
113641.53ms1282.41ms if ( has_fields($base) || has_attr($base) ) {
# spent 1.28ms making 64 calls to base::has_fields, avg 20µs/call # spent 1.13ms making 64 calls to base::has_attr, avg 18µ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.
12454706µs push @{"$inheritor\::ISA"}, @isa_classes;
125
126541.65ms push @{"$inheritor\::ISA"}, @bases;
127
128541.17ms 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
181117µs1;
182
183__END__
Report produced by the NYTProf 4.04 Perl profiler, developed by Tim Bunce and Adam Kaplan.