← Index
NYTProf Performance Profile   « line view »
For starman worker -M FindBin --max-requests 50 --workers 2 --user=kohadev-koha --group kohadev-koha --pid /var/run/koha/kohadev/plack.pid --daemonize --access-log /var/log/koha/kohadev/plack.log --error-log /var/log/koha/kohadev/plack-error.log -E deployment --socket /var/run/koha/kohadev/plack.sock /etc/koha/sites/kohadev/plack.psgi
  Run on Fri Jan 8 13:50:58 2016
Reported on Fri Jan 8 13:51:26 2016

Filename/usr/share/perl5/Moo/_Utils.pm
StatementsExecuted 3338 statements in 17.4ms
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
547228.40ms15.3msMoo::_Utils::::_install_coderefMoo::_Utils::_install_coderef
584731.99ms1.99msMoo::_Utils::::_getglobMoo::_Utils::_getglob
547111.69ms4.98msMoo::_Utils::::_name_coderefMoo::_Utils::_name_coderef
111351µs513µsMoo::_Utils::::BEGIN@14Moo::_Utils::BEGIN@14
111182µs234µsMoo::_Utils::::BEGIN@16Moo::_Utils::BEGIN@16
11122µs56µsMoo::_Utils::::BEGIN@11Moo::_Utils::BEGIN@11
11118µs45µsMoo::_Utils::::BEGIN@12Moo::_Utils::BEGIN@12
11113µs56µsMoo::_Utils::::_maybe_load_moduleMoo::_Utils::_maybe_load_module
11112µs20µsMoo::_Utils::::BEGIN@3Moo::_Utils::BEGIN@3
21112µs42µsMoo::_Utils::::_set_loadedMoo::_Utils::_set_loaded
11110µs22µsMoo::_Utils::::BEGIN@17Moo::_Utils::BEGIN@17
11110µs36µsMoo::_Utils::::BEGIN@8Moo::_Utils::BEGIN@8
1119µs18µsMoo::_Utils::::BEGIN@89Moo::_Utils::BEGIN@89
1119µs15µsMoo::_Utils::::BEGIN@15Moo::_Utils::BEGIN@15
1119µs24µsMoo::_Utils::::BEGIN@9Moo::_Utils::BEGIN@9
1118µs18µsMoo::_Utils::::BEGIN@82Moo::_Utils::BEGIN@82
1118µs15µsMoo::_Utils::::BEGIN@112Moo::_Utils::BEGIN@112
2114µs4µsMoo::_Utils::::_getstashMoo::_Utils::_getstash
0000s0sMoo::_Utils::::_get_linear_isaMoo::_Utils::_get_linear_isa
0000s0sMoo::_Utils::::_install_modifierMoo::_Utils::_install_modifier
0000s0sMoo::_Utils::::_load_moduleMoo::_Utils::_load_module
0000s0sMoo::_Utils::::_unimport_coderefsMoo::_Utils::_unimport_coderefs
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments
Time
on line
Calls Time
in subs
Code
1package Moo::_Utils;
2
3296µs228µs
# spent 20µs (12+8) within Moo::_Utils::BEGIN@3 which was called: # once (12µs+8µs) by Sub::Defer::BEGIN@5 at line 3
no warnings 'once'; # guard against -w
# spent 20µs making 1 call to Moo::_Utils::BEGIN@3 # spent 8µs making 1 call to warnings::unimport
4
55847.21ms
# spent 1.99ms within Moo::_Utils::_getglob which was called 584 times, avg 3µs/call: # 547 times (1.91ms+0s) by Moo::_Utils::_install_coderef at line 81, avg 3µs/call # 14 times (42µs+0s) by Sub::Defer::undefer_sub at line 25 of Sub/Defer.pm, avg 3µs/call # 14 times (15µs+0s) by Sub::Defer::undefer_sub at line 30 of Sub/Defer.pm, avg 1µs/call # 6 times (19µs+0s) by Method::Generate::Accessor::generate_method at line 109 of Method/Generate/Accessor.pm, avg 3µs/call # once (5µs+0s) by Method::Generate::Accessor::generate_method at line 126 of Method/Generate/Accessor.pm # once (3µs+0s) by Method::Generate::Accessor::generate_method at line 146 of Method/Generate/Accessor.pm # once (3µs+0s) by Method::Generate::Accessor::generate_method at line 182 of Method/Generate/Accessor.pm
sub _getglob { \*{$_[0]} }
627µs
# spent 4µs within Moo::_Utils::_getstash which was called 2 times, avg 2µs/call: # 2 times (4µs+0s) by Moo::import at line 68 of Moo.pm, avg 2µs/call
sub _getstash { \%{"$_[0]::"} }
7
8269µs262µs
# spent 36µs (10+26) within Moo::_Utils::BEGIN@8 which was called: # once (10µs+26µs) by Sub::Defer::BEGIN@5 at line 8
use constant lt_5_8_3 => ( $] < 5.008003 or $ENV{MOO_TEST_PRE_583} ) ? 1 : 0;
# spent 36µs making 1 call to Moo::_Utils::BEGIN@8 # spent 26µs making 1 call to constant::import
9345µs239µs
# spent 24µs (9+15) within Moo::_Utils::BEGIN@9 which was called: # once (9µs+15µs) by Sub::Defer::BEGIN@5 at line 9
use constant can_haz_subname => eval { require Sub::Name };
# spent 24µs making 1 call to Moo::_Utils::BEGIN@9 # spent 15µs making 1 call to constant::import
10
11332µs389µs
# spent 56µs (22+34) within Moo::_Utils::BEGIN@11 which was called: # once (22µs+34µs) by Sub::Defer::BEGIN@5 at line 11
use strictures 1;
# spent 56µs making 1 call to Moo::_Utils::BEGIN@11 # spent 21µs making 1 call to strictures::import # spent 13µs making 1 call to strictures::VERSION
12263µs272µs
# spent 45µs (18+27) within Moo::_Utils::BEGIN@12 which was called: # once (18µs+27µs) by Sub::Defer::BEGIN@5 at line 12
use Module::Runtime qw(use_package_optimistically module_notional_filename);
# spent 45µs making 1 call to Moo::_Utils::BEGIN@12 # spent 27µs making 1 call to Module::Runtime::import
13
142150µs1513µs
# spent 513µs (351+162) within Moo::_Utils::BEGIN@14 which was called: # once (351µs+162µs) by Sub::Defer::BEGIN@5 at line 14
use Devel::GlobalDestruction ();
# spent 513µs making 1 call to Moo::_Utils::BEGIN@14
15231µs221µs
# spent 15µs (9+6) within Moo::_Utils::BEGIN@15 which was called: # once (9µs+6µs) by Sub::Defer::BEGIN@5 at line 15
use Exporter qw(import);
# spent 15µs making 1 call to Moo::_Utils::BEGIN@15 # spent 6µs making 1 call to Exporter::import
162176µs1234µs
# spent 234µs (182+52) within Moo::_Utils::BEGIN@16 which was called: # once (182µs+52µs) by Sub::Defer::BEGIN@5 at line 16
use Moo::_mro;
# spent 234µs making 1 call to Moo::_Utils::BEGIN@16
172459µs234µs
# spent 22µs (10+12) within Moo::_Utils::BEGIN@17 which was called: # once (10µs+12µs) by Sub::Defer::BEGIN@5 at line 17
use Config;
# spent 22µs making 1 call to Moo::_Utils::BEGIN@17 # spent 12µs making 1 call to Config::import
18
1912µsour @EXPORT = qw(
20 _getglob _install_modifier _load_module _maybe_load_module
21 _get_linear_isa _getstash _install_coderef _name_coderef
22 _unimport_coderefs _in_global_destruction _set_loaded
23);
24
25sub _in_global_destruction ();
2613µs*_in_global_destruction = \&Devel::GlobalDestruction::in_global_destruction;
27
28sub _install_modifier {
29 my ($into, $type, $name, $code) = @_;
30
31 if (my $to_modify = $into->can($name)) { # CMM will throw for us if not
32 require Sub::Defer;
33 Sub::Defer::undefer_sub($to_modify);
34 }
35
36 Class::Method::Modifiers::install_modifier(@_);
37}
38
391200nsour %MAYBE_LOADED;
40
41sub _load_module {
42 my $module = $_[0];
43 my $file = module_notional_filename($module);
44 use_package_optimistically($module);
45 return 1
46 if $INC{$file};
47 my $error = $@ || "Can't locate $file";
48
49 # can't just ->can('can') because a sub-package Foo::Bar::Baz
50 # creates a 'Baz::' key in Foo::Bar's symbol table
51 my $stash = _getstash($module)||{};
52 return 1 if grep +(!ref($_) and *$_{CODE}), values %$stash;
53 return 1
54 if $INC{"Moose.pm"} && Class::MOP::class_of($module)
55 or Mouse::Util->can('find_meta') && Mouse::Util::find_meta($module);
56 die $error;
57}
58
59
# spent 56µs (13+43) within Moo::_Utils::_maybe_load_module which was called: # once (13µs+43µs) by Method::Generate::Accessor::BEGIN@11 at line 17 of Method/Generate/Accessor.pm
sub _maybe_load_module {
601700ns my $module = $_[0];
611700ns return $MAYBE_LOADED{$module}
62 if exists $MAYBE_LOADED{$module};
63257µs243µs if(! eval { use_package_optimistically($module) }) {
# spent 34µs making 1 call to Module::Runtime::use_package_optimistically # spent 9µs making 1 call to Module::Runtime::module_notional_filename
64 warn "$module exists but failed to load with error: $@";
65 }
66 elsif ( $INC{module_notional_filename($module)} ) {
67 return $MAYBE_LOADED{$module} = 1;
68 }
69 return $MAYBE_LOADED{$module} = 0;
70}
71
72
# spent 42µs (12+30) within Moo::_Utils::_set_loaded which was called 2 times, avg 21µs/call: # 2 times (12µs+30µs) by Moo::import at line 23 of Moo.pm, avg 21µs/call
sub _set_loaded {
73243µs230µs $INC{Module::Runtime::module_notional_filename($_[0])} ||= $_[1];
# spent 30µs making 2 calls to Module::Runtime::module_notional_filename, avg 15µs/call
74}
75
76sub _get_linear_isa {
77 return mro::get_linear_isa($_[0]);
78}
79
80
# spent 15.3ms (8.40+6.89) within Moo::_Utils::_install_coderef which was called 547 times, avg 28µs/call: # 535 times (8.34ms+6.80ms) by Sub::Defer::defer_sub at line 61 of Sub/Defer.pm, avg 28µs/call # 12 times (60µs+84µs) by Moo::_install_tracked at line 17 of Moo.pm, avg 12µs/call
sub _install_coderef {
815471.92ms10946.89ms my ($glob, $code) = (_getglob($_[0]), _name_coderef(@_));
# spent 4.98ms making 547 calls to Moo::_Utils::_name_coderef, avg 9µs/call # spent 1.91ms making 547 calls to Moo::_Utils::_getglob, avg 3µs/call
82264µs227µs
# spent 18µs (8+9) within Moo::_Utils::BEGIN@82 which was called: # once (8µs+9µs) by Sub::Defer::BEGIN@5 at line 82
no warnings 'redefine';
# spent 18µs making 1 call to Moo::_Utils::BEGIN@82 # spent 9µs making 1 call to warnings::unimport
835471.11ms if (*{$glob}{CODE}) {
84 *{$glob} = $code;
85 }
86 # perl will sometimes warn about mismatched prototypes coming from the
87 # inheritance cache, so disable them if we aren't redefining a sub
88 else {
892192µs226µs
# spent 18µs (9+8) within Moo::_Utils::BEGIN@89 which was called: # once (9µs+8µs) by Sub::Defer::BEGIN@5 at line 89
no warnings 'prototype';
# spent 18µs making 1 call to Moo::_Utils::BEGIN@89 # spent 8µs making 1 call to warnings::unimport
90527310µs *{$glob} = $code;
91 }
92}
93
94
# spent 4.98ms (1.69+3.28) within Moo::_Utils::_name_coderef which was called 547 times, avg 9µs/call: # 547 times (1.69ms+3.28ms) by Moo::_Utils::_install_coderef at line 81, avg 9µs/call
sub _name_coderef {
95547215µs shift if @_ > 2; # three args is (target, name, sub)
965474.94ms5473.28ms can_haz_subname ? Sub::Name::subname(@_) : $_[1];
# spent 3.28ms making 547 calls to Sub::Name::subname, avg 6µs/call
97}
98
99sub _unimport_coderefs {
100 my ($target, $info) = @_;
101 return unless $info and my $exports = $info->{exports};
102 my %rev = reverse %$exports;
103 my $stash = _getstash($target);
104 foreach my $name (keys %$exports) {
105 if ($stash->{$name} and defined(&{$stash->{$name}})) {
106 if ($rev{$target->can($name)}) {
107 my $old = delete $stash->{$name};
108 my $full_name = join('::',$target,$name);
109 # Copy everything except the code slot back into place (e.g. $has)
110 foreach my $type (qw(SCALAR HASH ARRAY IO)) {
111 next unless defined(*{$old}{$type});
112297µs221µs
# spent 15µs (8+6) within Moo::_Utils::BEGIN@112 which was called: # once (8µs+6µs) by Sub::Defer::BEGIN@5 at line 112
no strict 'refs';
# spent 15µs making 1 call to Moo::_Utils::BEGIN@112 # spent 6µs making 1 call to strict::unimport
113 *$full_name = *{$old}{$type};
114 }
115 }
116 }
117 }
118}
119
1201106µs13µsif ($Config{useithreads}) {
# spent 3µs making 1 call to Config::FETCH
121 require Moo::HandleMoose::_TypeMap;
122}
123
12413µs1;