← Index
NYTProf Performance Profile   « block view • line view • sub view »
For /usr/share/koha/opac/cgi-bin/opac/opac-search.pl
  Run on Tue Oct 15 11:58:52 2013
Reported on Tue Oct 15 12:01:48 2013

Filename/usr/share/perl/5.10/AutoLoader.pm
StatementsExecuted 121 statements in 4.11ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
4421.61ms1.70msAutoLoader::::importAutoLoader::import
222917µs1.16msAutoLoader::::AUTOLOADAutoLoader::AUTOLOAD
211113µs245µsAutoLoader::::find_filenameAutoLoader::find_filename
42177µs77µsAutoLoader::::CORE:regcompAutoLoader::CORE:regcomp (opcode)
115161µs61µsAutoLoader::::CORE:substAutoLoader::CORE:subst (opcode)
21139µs39µsAutoLoader::::CORE:ftereadAutoLoader::CORE:fteread (opcode)
53139µs39µsAutoLoader::::CORE:matchAutoLoader::CORE:match (opcode)
11125µs25µsAutoLoader::::BEGIN@4AutoLoader::BEGIN@4
11124µs32µsAutoLoader::::BEGIN@3AutoLoader::BEGIN@3
82112µs12µsAutoLoader::::CORE:substcontAutoLoader::CORE:substcont (opcode)
11111µs31µsAutoLoader::::BEGIN@138AutoLoader::BEGIN@138
11110µs24µsAutoLoader::::BEGIN@186AutoLoader::BEGIN@186
1119µs29µsAutoLoader::::BEGIN@30AutoLoader::BEGIN@30
1119µs9µsAutoLoader::::BEGIN@13AutoLoader::BEGIN@13
1112µs2µsAutoLoader::::__ANON__[:31]AutoLoader::__ANON__[:31]
0000s0sAutoLoader::::unimportAutoLoader::unimport
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1package AutoLoader;
2
3332µs241µs
# spent 32µs (24+8) within AutoLoader::BEGIN@3 which was called: # once (24µs+8µs) by DBI::BEGIN@157 at line 3
use strict;
# spent 32µs making 1 call to AutoLoader::BEGIN@3 # spent 8µs making 1 call to strict::import
43112µs125µs
# spent 25µs within AutoLoader::BEGIN@4 which was called: # once (25µs+0s) by DBI::BEGIN@157 at line 4
use 5.006_001;
# spent 25µs making 1 call to AutoLoader::BEGIN@4
5
61500nsour($VERSION, $AUTOLOAD);
7
81200nsmy $is_dosish;
91200nsmy $is_epoc;
101100nsmy $is_vms;
111100nsmy $is_macos;
12
13
# spent 9µs within AutoLoader::BEGIN@13 which was called: # once (9µs+0s) by DBI::BEGIN@157 at line 19
BEGIN {
1413µs $is_dosish = $^O eq 'dos' || $^O eq 'os2' || $^O eq 'MSWin32' || $^O eq 'NetWare';
151400ns $is_epoc = $^O eq 'epoc';
161400ns $is_vms = $^O eq 'VMS';
171500ns $is_macos = $^O eq 'MacOS';
1815µs $VERSION = '5.68';
19175µs19µs}
# spent 9µs making 1 call to AutoLoader::BEGIN@13
20
21
# spent 1.16ms (917µs+245µs) within AutoLoader::AUTOLOAD which was called 2 times, avg 581µs/call: # once (798µs+100µs) by POSIX::import at line 44 of POSIX.pm # once (119µs+146µs) by C4::XSLT::XSLTParse4Display at line 228 of /usr/share/koha/lib/C4/XSLT.pm
AUTOLOAD {
2224µs my $sub = $AUTOLOAD;
2329µs2245µs my $filename = AutoLoader::find_filename( $sub );
# spent 245µs making 2 calls to AutoLoader::find_filename, avg 123µs/call
24
2521µs my $save = $@;
262118µs local $!; # Do not munge the value.
276744µs eval { local $SIG{__DIE__}; require $filename };
2822µs if ($@) {
2914µs if (substr($sub,-9) eq '::DESTROY') {
303680µs249µs
# spent 29µs (9+20) within AutoLoader::BEGIN@30 which was called: # once (9µs+20µs) by DBI::BEGIN@157 at line 30
no strict 'refs';
# spent 29µs making 1 call to AutoLoader::BEGIN@30 # spent 20µs making 1 call to strict::unimport
31214µs
# spent 2µs within AutoLoader::__ANON__[/usr/share/perl/5.10/AutoLoader.pm:31] which was called: # once (2µs+0s) by C4::XSLT::XSLTParse4Display at line 51
*$sub = sub {};
321500ns $@ = undef;
33 } elsif ($@ =~ /^Can't locate/) {
34 # The load might just have failed because the filename was too
35 # long for some old SVR3 systems which treat long names as errors.
36 # If we can successfully truncate a long name then it's worth a go.
37 # There is a slight risk that we could pick up the wrong file here
38 # but autosplit should have warned about that when splitting.
39 if ($filename =~ s/(\w{12,})\.al$/substr($1,0,11).".al"/e){
40 eval { local $SIG{__DIE__}; require $filename };
41 }
42 }
431500ns if ($@){
44 $@ =~ s/ at .*\n//;
45 my $error = $@;
46 require Carp;
47 Carp::croak($error);
48 }
49 }
5021µs $@ = $save;
51227µs2662µs goto &$sub;
# spent 660µs making 1 call to POSIX::load_imports # spent 2µs making 1 call to AutoLoader::__ANON__[AutoLoader.pm:31]
52}
53
54
# spent 245µs (113+133) within AutoLoader::find_filename which was called 2 times, avg 123µs/call: # 2 times (113µs+133µs) by AutoLoader::AUTOLOAD at line 23, avg 123µs/call
sub find_filename {
5523µs my $sub = shift;
5622µs my $filename;
57 # Braces used to preserve $1 et al.
58 {
59 # Try to find the autoloaded file from the package-qualified
60 # name of the sub. e.g., if the sub needed is
61 # Getopt::Long::GetOptions(), then $INC{Getopt/Long.pm} is
62 # something like '/usr/lib/perl5/Getopt/Long.pm', and the
63 # autoload file is '/usr/lib/perl5/auto/Getopt/Long/GetOptions.al'.
64 #
65 # However, if @INC is a relative path, this might not work. If,
66 # for example, @INC = ('lib'), then $INC{Getopt/Long.pm} is
67 # 'lib/Getopt/Long.pm', and we want to require
68 # 'auto/Getopt/Long/GetOptions.al' (without the leading 'lib').
69 # In this case, we simple prepend the 'auto/' and let the
70 # C<require> take care of the searching for us.
71
72442µs223µs my ($pkg,$func) = ($sub =~ /(.*)::([^:]+)$/);
# spent 23µs making 2 calls to AutoLoader::CORE:match, avg 11µs/call
73218µs28µs $pkg =~ s#::#/#g;
# spent 8µs making 2 calls to AutoLoader::CORE:subst, avg 4µs/call
7428µs if (defined($filename = $INC{"$pkg.pm"})) {
7523µs if ($is_macos) {
76 $pkg =~ tr#/#:#;
77 $filename = undef
78 unless $filename =~ s#^(.*)$pkg\.pm\z#$1auto:$pkg:$func.al#s;
79 } else {
80293µs858µs $filename = undef
# spent 44µs making 2 calls to AutoLoader::CORE:regcomp, avg 22µs/call # spent 8µs making 2 calls to AutoLoader::CORE:subst, avg 4µs/call # spent 5µs making 4 calls to AutoLoader::CORE:substcont, avg 1µs/call
81 unless $filename =~ s#^(.*)$pkg\.pm\z#$1auto/$pkg/$func.al#s;
82 }
83
84 # if the file exists, then make sure that it is a
85 # a fully anchored path (i.e either '/usr/lib/auto/foo/bar.al',
86 # or './lib/auto/foo/bar.al'. This avoids C<require> searching
87 # (and failing) to find the 'lib/auto/foo/bar.al' because it
88 # looked for 'lib/lib/auto/foo/bar.al', given @INC = ('lib').
89
90251µs239µs if (defined $filename and -r $filename) {
# spent 39µs making 2 calls to AutoLoader::CORE:fteread, avg 20µs/call
9115µs12µs unless ($filename =~ m|^/|s) {
# spent 2µs making 1 call to AutoLoader::CORE:match
92 if ($is_dosish) {
93 unless ($filename =~ m{^([a-z]:)?[\\/]}is) {
94 if ($^O ne 'NetWare') {
95 $filename = "./$filename";
96 } else {
97 $filename = "$filename";
98 }
99 }
100 }
101 elsif ($is_epoc) {
102 unless ($filename =~ m{^([a-z?]:)?[\\/]}is) {
103 $filename = "./$filename";
104 }
105 }
106 elsif ($is_vms) {
107 # XXX todo by VMSmiths
108 $filename = "./$filename";
109 }
110 elsif (!$is_macos) {
111 $filename = "./$filename";
112 }
113 }
114 }
115 else {
11611µs $filename = undef;
117 }
118 }
11922µs unless (defined $filename) {
120 # let C<require> do the searching
12112µs $filename = "auto/$sub.al";
12218µs13µs $filename =~ s#::#/#g;
# spent 3µs making 1 call to AutoLoader::CORE:subst
123 }
124 }
125210µs return $filename;
126}
127
128
# spent 1.70ms (1.61+95µs) within AutoLoader::import which was called 4 times, avg 426µs/call: # once (1.26ms+32µs) by POSIX::BEGIN@9 at line 9 of POSIX.pm # once (268µs+34µs) by Storable::BEGIN@22 at line 22 of Storable.pm # once (52µs+21µs) by POSIX::SigAction::BEGIN@58 at line 58 of POSIX.pm # once (30µs+8µs) by POSIX::SigRt::BEGIN@62 at line 62 of POSIX.pm
sub import {
12947µs my $pkg = shift;
13046µs my $callpkg = caller;
131
132 #
133 # Export symbols, but not by accident of inheritance.
134 #
135
136410µs if ($pkg eq 'AutoLoader') {
137445µs214µs if ( @_ and $_[0] =~ /^&?AUTOLOAD$/ ) {
# spent 14µs making 2 calls to AutoLoader::CORE:match, avg 7µs/call
1383229µs251µs
# spent 31µs (11+20) within AutoLoader::BEGIN@138 which was called: # once (11µs+20µs) by DBI::BEGIN@157 at line 138
no strict 'refs';
# spent 31µs making 1 call to AutoLoader::BEGIN@138 # spent 20µs making 1 call to strict::unimport
139217µs *{ $callpkg . '::AUTOLOAD' } = \&AUTOLOAD;
140 }
141 }
142
143 #
144 # Try to find the autosplit index file. Eg., if the call package
145 # is POSIX, then $INC{POSIX.pm} is something like
146 # '/usr/local/lib/perl5/POSIX.pm', and the autosplit index file is in
147 # '/usr/local/lib/perl5/auto/POSIX/autosplit.ix', so we require that.
148 #
149 # However, if @INC is a relative path, this might not work. If,
150 # for example, @INC = ('lib'), then
151 # $INC{POSIX.pm} is 'lib/POSIX.pm', and we want to require
152 # 'auto/POSIX/autosplit.ix' (without the leading 'lib').
153 #
154
155456µs421µs (my $calldir = $callpkg) =~ s#::#/#g;
# spent 21µs making 4 calls to AutoLoader::CORE:subst, avg 5µs/call
15649µs my $path = $INC{$calldir . '.pm'};
157443µs if (defined($path)) {
158 # Try absolute path name, but only eval it if the
159 # transformation from module path to autosplit.ix path
160 # succeeded!
16121µs my $replaced_okay;
16223µs if ($is_macos) {
163 (my $malldir = $calldir) =~ tr#/#:#;
164 $replaced_okay = ($path =~ s#^(.*)$malldir\.pm\z#$1auto:$malldir:autosplit.ix#s);
165 } else {
1662102µs860µs $replaced_okay = ($path =~ s#^(.*)$calldir\.pm\z#$1auto/$calldir/autosplit.ix#);
# spent 33µs making 2 calls to AutoLoader::CORE:regcomp, avg 16µs/call # spent 21µs making 2 calls to AutoLoader::CORE:subst, avg 11µs/call # spent 6µs making 4 calls to AutoLoader::CORE:substcont, avg 2µs/call
167 }
168
16941.41ms eval { require $path; } if $replaced_okay;
170 # If that failed, try relative path with normal @INC searching.
17122µs if (!$replaced_okay or $@) {
172 $path ="auto/$calldir/autosplit.ix";
173 eval { require $path; };
174 }
17522µs if ($@) {
176 my $error = $@;
177 require Carp;
178 Carp::carp($error);
179 }
180 }
181}
182
183sub unimport {
184 my $callpkg = caller;
185
186380µs238µs
# spent 24µs (10+14) within AutoLoader::BEGIN@186 which was called: # once (10µs+14µs) by DBI::BEGIN@157 at line 186
no strict 'refs';
# spent 24µs making 1 call to AutoLoader::BEGIN@186 # spent 14µs making 1 call to strict::unimport
187
188 for my $exported (qw( AUTOLOAD )) {
189 my $symname = $callpkg . '::' . $exported;
190 undef *{ $symname } if \&{ $symname } == \&{ $exported };
191 *{ $symname } = \&{ $symname };
192 }
193}
194
19514µs1;
196
197__END__
 
# spent 39µs within AutoLoader::CORE:fteread which was called 2 times, avg 20µs/call: # 2 times (39µs+0s) by AutoLoader::find_filename at line 90, avg 20µs/call
sub AutoLoader::CORE:fteread; # opcode
# spent 39µs within AutoLoader::CORE:match which was called 5 times, avg 8µs/call: # 2 times (23µs+0s) by AutoLoader::find_filename at line 72, avg 11µs/call # 2 times (14µs+0s) by AutoLoader::import at line 137, avg 7µs/call # once (2µs+0s) by AutoLoader::find_filename at line 91
sub AutoLoader::CORE:match; # opcode
# spent 77µs within AutoLoader::CORE:regcomp which was called 4 times, avg 19µs/call: # 2 times (44µs+0s) by AutoLoader::find_filename at line 80, avg 22µs/call # 2 times (33µs+0s) by AutoLoader::import at line 166, avg 16µs/call
sub AutoLoader::CORE:regcomp; # opcode
# spent 61µs within AutoLoader::CORE:subst which was called 11 times, avg 6µs/call: # 4 times (21µs+0s) by AutoLoader::import at line 155, avg 5µs/call # 2 times (21µs+0s) by AutoLoader::import at line 166, avg 11µs/call # 2 times (8µs+0s) by AutoLoader::find_filename at line 80, avg 4µs/call # 2 times (8µs+0s) by AutoLoader::find_filename at line 73, avg 4µs/call # once (3µs+0s) by AutoLoader::find_filename at line 122
sub AutoLoader::CORE:subst; # opcode
# spent 12µs within AutoLoader::CORE:substcont which was called 8 times, avg 1µs/call: # 4 times (6µs+0s) by AutoLoader::import at line 166, avg 2µs/call # 4 times (5µs+0s) by AutoLoader::find_filename at line 80, avg 1µs/call
sub AutoLoader::CORE:substcont; # opcode
Report produced by the NYTProf 4.04 Perl profiler, developed by Tim Bunce and Adam Kaplan.