← Index
NYTProf Performance Profile   « line view »
For ../prof.pl
  Run on Wed Dec 14 15:33:55 2022
Reported on Wed Dec 14 15:40:03 2022

Filename/Users/ether/.perlbrew/libs/36.0@std/lib/perl5/Module/Implementation.pm
StatementsExecuted 141 statements in 1.44ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
1111.02ms1.24msModule::Implementation::::BEGIN@9Module::Implementation::BEGIN@9
21194µs105µsModule::Implementation::::_copy_symbolsModule::Implementation::_copy_symbols
21148µs8.99msModule::Implementation::::_load_implementationModule::Implementation::_load_implementation
21136µs45µsModule::Implementation::::_build_loaderModule::Implementation::_build_loader
11123µs26µsModule::Implementation::::BEGIN@5Module::Implementation::BEGIN@5
22223µs9.12msModule::Implementation::::__ANON__[:52]Module::Implementation::__ANON__[:52]
142120µs20µsModule::Implementation::::CORE:substModule::Implementation::CORE:subst (opcode)
11116µs36µsModule::Implementation::::BEGIN@8Module::Implementation::BEGIN@8
21112µs8.86msModule::Implementation::::try {...} Module::Implementation::try {...}
11111µs19µsModule::Implementation::::BEGIN@128Module::Implementation::BEGIN@128
2228µs53µsModule::Implementation::::build_loader_subModule::Implementation::build_loader_sub
1117µs26µsModule::Implementation::::BEGIN@129Module::Implementation::BEGIN@129
1115µs24µsModule::Implementation::::BEGIN@6Module::Implementation::BEGIN@6
1112µs2µsModule::Implementation::::implementation_forModule::Implementation::implementation_for
0000s0sModule::Implementation::::__ANON__[:78]Module::Implementation::__ANON__[:78]
0000s0sModule::Implementation::::__ANON__[:82]Module::Implementation::__ANON__[:82]
0000s0sModule::Implementation::::__ANON__[:95]Module::Implementation::__ANON__[:95]
0000s0sModule::Implementation::::__ANON__[:98]Module::Implementation::__ANON__[:98]
0000s0sModule::Implementation::::catch {...} Module::Implementation::catch {...}
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1package Module::Implementation;
2# git description: v0.08-2-gd599347
311µs$Module::Implementation::VERSION = '0.09';
4
5225µs229µs
# spent 26µs (23+3) within Module::Implementation::BEGIN@5 which was called: # once (23µs+3µs) by B::Hooks::EndOfScope::BEGIN@13 at line 5
use strict;
# spent 26µs making 1 call to Module::Implementation::BEGIN@5 # spent 3µs making 1 call to strict::import
6224µs243µs
# spent 24µs (5+19) within Module::Implementation::BEGIN@6 which was called: # once (5µs+19µs) by B::Hooks::EndOfScope::BEGIN@13 at line 6
use warnings;
# spent 24µs making 1 call to Module::Implementation::BEGIN@6 # spent 19µs making 1 call to warnings::import
7
8331µs356µs
# spent 36µs (16+20) within Module::Implementation::BEGIN@8 which was called: # once (16µs+20µs) by B::Hooks::EndOfScope::BEGIN@13 at line 8
use Module::Runtime 0.012 qw( require_module );
# spent 36µs making 1 call to Module::Implementation::BEGIN@8 # spent 14µs making 1 call to Module::Runtime::import # spent 6µs making 1 call to UNIVERSAL::VERSION
92938µs21.29ms
# spent 1.24ms (1.02+216µs) within Module::Implementation::BEGIN@9 which was called: # once (1.02ms+216µs) by B::Hooks::EndOfScope::BEGIN@13 at line 9
use Try::Tiny;
# spent 1.24ms making 1 call to Module::Implementation::BEGIN@9 # spent 54µs making 1 call to Exporter::import
10
11# This is needed for the benefit of Test::CleanNamespaces, which in turn loads
12# Package::Stash, which in turn loads this module and expects a minimum
13# version.
1411µsunless ( exists $Module::Implementation::{VERSION}
15 && ${ $Module::Implementation::{VERSION} } ) {
16
17 $Module::Implementation::{VERSION} = \42;
18}
19
2010smy %Implementation;
21
22
# spent 53µs (8+45) within Module::Implementation::build_loader_sub which was called 2 times, avg 26µs/call: # once (4µs+26µs) by Package::Stash::BEGIN@12 at line 16 of Package/Stash.pm # once (4µs+19µs) by B::Hooks::EndOfScope::BEGIN@12 at line 14 of B/Hooks/EndOfScope.pm
sub build_loader_sub {
2322µs my $caller = caller();
24
25211µs245µs return _build_loader( $caller, @_ );
# spent 45µs making 2 calls to Module::Implementation::_build_loader, avg 22µs/call
26}
27
28
# spent 45µs (36+9) within Module::Implementation::_build_loader which was called 2 times, avg 22µs/call: # 2 times (36µs+9µs) by Module::Implementation::build_loader_sub at line 25, avg 22µs/call
sub _build_loader {
2921µs my $package = shift;
3027µs my %args = @_;
31
3224µs my @implementations = @{ $args{implementations} };
3322µs my @symbols = @{ $args{symbols} || [] };
34
3520s my $implementation;
3623µs my $env_var = uc $package;
37216µs29µs $env_var =~ s/::/_/g;
# spent 9µs making 2 calls to Module::Implementation::CORE:subst, avg 4µs/call
3822µs $env_var .= '_IMPLEMENTATION';
39
40
# spent 9.12ms (23µs+9.10) within Module::Implementation::__ANON__[/Users/ether/.perlbrew/libs/36.0@std/lib/perl5/Module/Implementation.pm:52] which was called 2 times, avg 4.56ms/call: # once (15µs+7.31ms) by B::Hooks::EndOfScope::BEGIN@12 at line 14 of B/Hooks/EndOfScope.pm # once (8µs+1.79ms) by Package::Stash::BEGIN@12 at line 16 of Package/Stash.pm
return sub {
41 my ( $implementation, $loaded ) = _load_implementation(
42 $package,
4327µs28.99ms $ENV{$env_var},
# spent 8.99ms making 2 calls to Module::Implementation::_load_implementation, avg 4.50ms/call
44 \@implementations,
45 );
46
4722µs $Implementation{$package} = $implementation;
48
49238µs2105µs _copy_symbols( $loaded, $package, \@symbols );
# spent 105µs making 2 calls to Module::Implementation::_copy_symbols, avg 52µs/call
50
5127µs return $loaded;
52211µs };
53}
54
55
# spent 2µs within Module::Implementation::implementation_for which was called: # once (2µs+0s) by Package::Stash::BEGIN@12 at line 32 of Package/Stash.pm
sub implementation_for {
5610s my $package = shift;
57
5813µs return $Implementation{$package};
59}
60
61
# spent 8.99ms (48µs+8.95) within Module::Implementation::_load_implementation which was called 2 times, avg 4.50ms/call: # 2 times (48µs+8.95ms) by Module::Implementation::__ANON__[/Users/ether/.perlbrew/libs/36.0@std/lib/perl5/Module/Implementation.pm:52] at line 43, avg 4.50ms/call
sub _load_implementation {
6220s my $package = shift;
6322µs my $env_value = shift;
6422µs my $implementations = shift;
65
6621µs if ($env_value) {
67 die "$env_value is not a valid implementation for $package"
68 unless grep { $_ eq $env_value } @{$implementations};
69
70 my $requested = "${package}::$env_value";
71
72 # Values from the %ENV hash are tainted. We know it's safe to untaint
73 # this value because the value was one of our known implementations.
74 ($requested) = $requested =~ /^(.+)$/;
75
76 try {
77 require_module($requested);
78 }
79 catch {
80 require Carp;
81 Carp::croak("Could not load $requested: $_");
82 };
83
84 return ( $env_value, $requested );
85 }
86 else {
8720s my $err;
8822µs for my $possible ( @{$implementations} ) {
8922µs my $try = "${package}::$possible";
90
9121µs my $ok;
92
# spent 8.86ms (12µs+8.85) within Module::Implementation::try {...} which was called 2 times, avg 4.43ms/call: # 2 times (12µs+8.85ms) by Try::Tiny::try at line 102 of Try/Tiny.pm, avg 4.43ms/call
try {
9328µs28.85ms require_module($try);
# spent 8.85ms making 2 calls to Module::Runtime::require_module, avg 4.42ms/call
9428µs $ok = 1;
95 }
96 catch {
97 $err .= $_ if defined $_;
98228µs48.95ms };
# spent 8.91ms making 2 calls to Try::Tiny::try, avg 4.46ms/call # spent 34µs making 2 calls to Try::Tiny::catch, avg 17µs/call
99
10028µs return ( $possible, $try ) if $ok;
101 }
102
103 require Carp;
104 if ( defined $err && length $err ) {
105 Carp::croak(
106 "Could not find a suitable $package implementation: $err");
107 }
108 else {
109 Carp::croak(
110 'Module::Runtime failed to load a module but did not throw a real error. This should never happen. Something is very broken'
111 );
112 }
113 }
114}
115
116
# spent 105µs (94+11) within Module::Implementation::_copy_symbols which was called 2 times, avg 52µs/call: # 2 times (94µs+11µs) by Module::Implementation::__ANON__[/Users/ether/.perlbrew/libs/36.0@std/lib/perl5/Module/Implementation.pm:52] at line 49, avg 52µs/call
sub _copy_symbols {
11721µs my $from_package = shift;
11820s my $to_package = shift;
11921µs my $symbols = shift;
120
121210µs for my $sym ( @{$symbols} ) {
1221225µs1211µs my $type = $sym =~ s/^([\$\@\%\&\*])// ? $1 : '&';
# spent 11µs making 12 calls to Module::Implementation::CORE:subst, avg 917ns/call
123
124126µs my $from = "${from_package}::$sym";
125123µs my $to = "${to_package}::$sym";
126
127 {
1281442µs227µs
# spent 19µs (11+8) within Module::Implementation::BEGIN@128 which was called: # once (11µs+8µs) by B::Hooks::EndOfScope::BEGIN@13 at line 128
no strict 'refs';
# spent 19µs making 1 call to Module::Implementation::BEGIN@128 # spent 8µs making 1 call to strict::unimport
1292123µs245µs
# spent 26µs (7+19) within Module::Implementation::BEGIN@129 which was called: # once (7µs+19µs) by B::Hooks::EndOfScope::BEGIN@13 at line 129
no warnings 'once';
# spent 26µs making 1 call to Module::Implementation::BEGIN@129 # spent 19µs making 1 call to warnings::unimport
130
131 # Copied from Exporter
132 *{$to}
133 = $type eq '&' ? \&{$from}
134 : $type eq '$' ? \${$from}
135 : $type eq '@' ? \@{$from}
136 : $type eq '%' ? \%{$from}
1371225µs : $type eq '*' ? *{$from}
138 : die
139 "Can't copy symbol from $from_package to $to_package: $type$sym";
140 }
141 }
142}
143
14414µs1;
145
146# ABSTRACT: Loads one of several alternate underlying implementations for a module
147
148__END__
 
# spent 20µs within Module::Implementation::CORE:subst which was called 14 times, avg 1µs/call: # 12 times (11µs+0s) by Module::Implementation::_copy_symbols at line 122, avg 917ns/call # 2 times (9µs+0s) by Module::Implementation::_build_loader at line 37, avg 4µs/call
sub Module::Implementation::CORE:subst; # opcode
Report produced by the NYTProf 6.12 Perl profiler, developed by Tim Bunce based on work by Adam Kaplan and Salvador Fandiño García.