← 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/lib/x86_64-linux-gnu/perl5/5.20/Class/XSAccessor.pm
StatementsExecuted 2053 statements in 4.38ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
30221.38ms2.82msClass::XSAccessor::::importClass::XSAccessor::import
24081938µs938µsClass::XSAccessor::::_make_hashClass::XSAccessor::_make_hash
111389µs494µsClass::XSAccessor::::BEGIN@6Class::XSAccessor::BEGIN@6
3011260µs506µsClass::XSAccessor::::_generate_methodClass::XSAccessor::_generate_method
2611192µs192µsClass::XSAccessor::::newxs_accessorClass::XSAccessor::newxs_accessor (xsub)
301132µs32µsClass::XSAccessor::::CORE:matchClass::XSAccessor::CORE:match (opcode)
41122µs22µsClass::XSAccessor::::newxs_getterClass::XSAccessor::newxs_getter (xsub)
11119µs19µsClass::XSAccessor::::BEGIN@2Class::XSAccessor::BEGIN@2
11110µs17µsClass::XSAccessor::::BEGIN@3Class::XSAccessor::BEGIN@3
1119µs37µsClass::XSAccessor::::BEGIN@5Class::XSAccessor::BEGIN@5
1119µs18µsClass::XSAccessor::::BEGIN@84Class::XSAccessor::BEGIN@84
1118µs12µsClass::XSAccessor::::BEGIN@4Class::XSAccessor::BEGIN@4
1117µs7µsClass::XSAccessor::::BEGIN@7Class::XSAccessor::BEGIN@7
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1package Class::XSAccessor;
2248µs119µs
# spent 19µs within Class::XSAccessor::BEGIN@2 which was called: # once (19µs+0s) by Module::Runtime::require_module at line 2
use 5.008;
# spent 19µs making 1 call to Class::XSAccessor::BEGIN@2
3235µs224µs
# spent 17µs (10+7) within Class::XSAccessor::BEGIN@3 which was called: # once (10µs+7µs) by Module::Runtime::require_module at line 3
use strict;
# spent 17µs making 1 call to Class::XSAccessor::BEGIN@3 # spent 7µs making 1 call to strict::import
4229µs216µs
# spent 12µs (8+4) within Class::XSAccessor::BEGIN@4 which was called: # once (8µs+4µs) by Module::Runtime::require_module at line 4
use warnings;
# spent 12µs making 1 call to Class::XSAccessor::BEGIN@4 # spent 4µs making 1 call to warnings::import
5259µs265µs
# spent 37µs (9+28) within Class::XSAccessor::BEGIN@5 which was called: # once (9µs+28µs) by Module::Runtime::require_module at line 5
use Carp qw/croak/;
# spent 37µs making 1 call to Class::XSAccessor::BEGIN@5 # spent 28µs making 1 call to Exporter::import
62126µs1494µs
# spent 494µs (389+104) within Class::XSAccessor::BEGIN@6 which was called: # once (389µs+104µs) by Module::Runtime::require_module at line 6
use Class::XSAccessor::Heavy;
# spent 494µs making 1 call to Class::XSAccessor::BEGIN@6
72436µs17µs
# spent 7µs within Class::XSAccessor::BEGIN@7 which was called: # once (7µs+0s) by Module::Runtime::require_module at line 7
use XSLoader;
# spent 7µs making 1 call to Class::XSAccessor::BEGIN@7
8
91400nsour $VERSION = '1.19';
10
111575µs1563µsXSLoader::load('Class::XSAccessor', $VERSION);
# spent 563µs making 1 call to XSLoader::load
12
13
# spent 938µs within Class::XSAccessor::_make_hash which was called 240 times, avg 4µs/call: # 30 times (721µs+0s) by Class::XSAccessor::import at line 37, avg 24µs/call # 30 times (57µs+0s) by Class::XSAccessor::import at line 38, avg 2µs/call # 30 times (33µs+0s) by Class::XSAccessor::import at line 39, avg 1µs/call # 30 times (27µs+0s) by Class::XSAccessor::import at line 40, avg 910ns/call # 30 times (27µs+0s) by Class::XSAccessor::import at line 41, avg 903ns/call # 30 times (25µs+0s) by Class::XSAccessor::import at line 42, avg 847ns/call # 30 times (25µs+0s) by Class::XSAccessor::import at line 44, avg 820ns/call # 30 times (23µs+0s) by Class::XSAccessor::import at line 43, avg 757ns/call
sub _make_hash {
1424060µs my $ref = shift;
15
1624089µs if (ref ($ref)) {
17 if (ref($ref) eq 'ARRAY') {
18 $ref = { map { $_ => $_ } @$ref }
19 }
20 } else {
21 $ref = { $ref, $ref };
22 }
23
24240379µs return $ref;
25}
26
27
# spent 2.82ms (1.38+1.44) within Class::XSAccessor::import which was called 30 times, avg 94µs/call: # 26 times (1.16ms+1.35ms) by DBIx::Class::ResultSet::_result_class or DBIx::Class::ResultSet::result_source or DBIx::Class::ResultSource::_columns or DBIx::Class::ResultSource::_ordered_columns or DBIx::Class::ResultSource::_primaries or DBIx::Class::ResultSource::_relationships or DBIx::Class::ResultSource::_unique_constraints or DBIx::Class::ResultSource::name or DBIx::Class::ResultSource::source_name or DBIx::Class::Row::in_storage or DBIx::Class::Storage::DBI::_conn_pid or DBIx::Class::Storage::DBI::_connect_info or DBIx::Class::Storage::DBI::_dbh or DBIx::Class::Storage::DBI::_dbh_autocommit or DBIx::Class::Storage::DBI::_dbh_details or DBIx::Class::Storage::DBI::_dbic_connect_attributes or DBIx::Class::Storage::DBI::_driver_determined or DBIx::Class::Storage::DBI::_sql_maker or DBIx::Class::Storage::DBI::_sql_maker_opts or DBIx::Class::Storage::DBI::disable_sth_caching or DBIx::Class::Storage::DBI::on_connect_call or DBIx::Class::Storage::DBI::on_connect_do or DBIx::Class::Storage::DBI::unsafe or DBIx::Class::Storage::debug or DBIx::Class::Storage::schema or DBIx::Class::Storage::transaction_depth at line 830 of Class/Accessor/Grouped.pm, avg 96µs/call # 4 times (221µs+94µs) by Method::Generate::Accessor::_generate_xs at line 641 of Method/Generate/Accessor.pm, avg 79µs/call
sub import {
283012µs my $own_class = shift;
293037µs my ($caller_pkg) = caller();
30
31 # Support both { getters => ... } and plain getters => ...
323078µs my %opts = ref($_[0]) eq 'HASH' ? %{$_[0]} : @_;
33
343021µs $caller_pkg = $opts{class} if defined $opts{class};
35
36 # TODO: Refactor. Move more duplicated code to ::Heavy
3730673µs30721µs my $read_subs = _make_hash($opts{getters} || {});
# spent 721µs making 30 calls to Class::XSAccessor::_make_hash, avg 24µs/call
383060µs3057µs my $set_subs = _make_hash($opts{setters} || {});
# spent 57µs making 30 calls to Class::XSAccessor::_make_hash, avg 2µs/call
393035µs3033µs my $acc_subs = _make_hash($opts{accessors} || {});
# spent 33µs making 30 calls to Class::XSAccessor::_make_hash, avg 1µs/call
403032µs3027µs my $lvacc_subs = _make_hash($opts{lvalue_accessors} || {});
# spent 27µs making 30 calls to Class::XSAccessor::_make_hash, avg 910ns/call
413031µs3027µs my $pred_subs = _make_hash($opts{predicates} || {});
# spent 27µs making 30 calls to Class::XSAccessor::_make_hash, avg 903ns/call
423028µs3025µs my $ex_pred_subs = _make_hash($opts{exists_predicates} || {});
# spent 25µs making 30 calls to Class::XSAccessor::_make_hash, avg 847ns/call
433028µs3023µs my $def_pred_subs = _make_hash($opts{defined_predicates} || {});
# spent 23µs making 30 calls to Class::XSAccessor::_make_hash, avg 757ns/call
443026µs3025µs my $test_subs = _make_hash($opts{__tests__} || {});
# spent 25µs making 30 calls to Class::XSAccessor::_make_hash, avg 820ns/call
453024µs my $construct_subs = $opts{constructors} || [defined($opts{constructor}) ? $opts{constructor} : ()];
463010µs my $true_subs = $opts{true} || [];
473015µs my $false_subs = $opts{false} || [];
48
4930115µs foreach my $subtype ( ["getter", $read_subs],
50 ["setter", $set_subs],
51 ["accessor", $acc_subs],
52 ["lvalue_accessor", $lvacc_subs],
53 ["test", $test_subs],
54 ["ex_predicate", $ex_pred_subs],
55 ["def_predicate", $def_pred_subs],
56 ["def_predicate", $pred_subs] )
57 {
5824045µs my $subs = $subtype->[1];
59240224µs foreach my $subname (keys %$subs) {
603010µs my $hashkey = $subs->{$subname};
613068µs30506µs _generate_method($caller_pkg, $subname, $hashkey, \%opts, $subtype->[0]);
# spent 506µs making 30 calls to Class::XSAccessor::_generate_method, avg 17µs/call
62 }
63 }
64
6530208µs foreach my $subtype ( ["constructor", $construct_subs],
66 ["true", $true_subs],
67 ["false", $false_subs] )
68 {
699051µs foreach my $subname (@{$subtype->[1]}) {
70 _generate_method($caller_pkg, $subname, "", \%opts, $subtype->[0]);
71 }
72 }
73}
74
75
# spent 506µs (260+245) within Class::XSAccessor::_generate_method which was called 30 times, avg 17µs/call: # 30 times (260µs+245µs) by Class::XSAccessor::import at line 61, avg 17µs/call
sub _generate_method {
763018µs my ($caller_pkg, $subname, $hashkey, $opts, $type) = @_;
77
78305µs croak("Cannot use undef as a hash key for generating an XS $type accessor. (Sub: $subname)")
79 if not defined $hashkey;
80
8130119µs3032µs $subname = "${caller_pkg}::$subname" if $subname !~ /::/;
# spent 32µs making 30 calls to Class::XSAccessor::CORE:match, avg 1µs/call
82
833012µs Class::XSAccessor::Heavy::check_sub_existence($subname) if not $opts->{replace};
842192µs228µs
# spent 18µs (9+10) within Class::XSAccessor::BEGIN@84 which was called: # once (9µs+10µs) by Module::Runtime::require_module at line 84
no warnings 'redefine'; # don't warn about an explicitly requested redefine
# spent 18µs making 1 call to Class::XSAccessor::BEGIN@84 # spent 10µs making 1 call to warnings::unimport
85
8630112µs422µs if ($type eq 'getter') {
# spent 22µs making 4 calls to Class::XSAccessor::newxs_getter, avg 5µs/call
87 newxs_getter($subname, $hashkey);
88 }
89 elsif ($type eq 'lvalue_accessor') {
90 newxs_lvalue_accessor($subname, $hashkey);
91 }
92 elsif ($type eq 'setter') {
93 newxs_setter($subname, $hashkey, $opts->{chained}||0);
94 }
95 elsif ($type eq 'def_predicate') {
96 newxs_defined_predicate($subname, $hashkey);
97 }
98 elsif ($type eq 'ex_predicate') {
99 newxs_exists_predicate($subname, $hashkey);
100 }
101 elsif ($type eq 'constructor') {
102 newxs_constructor($subname);
103 }
104 elsif ($type eq 'true') {
105 newxs_boolean($subname, 1);
106 }
107 elsif ($type eq 'false') {
108 newxs_boolean($subname, 0);
109 }
110 elsif ($type eq 'test') {
111 newxs_test($subname, $hashkey);
112 }
113 else {
11426252µs26192µs newxs_accessor($subname, $hashkey, $opts->{chained}||0);
# spent 192µs making 26 calls to Class::XSAccessor::newxs_accessor, avg 7µs/call
115 }
116}
117
11815µs1;
119
120__END__
 
# spent 32µs within Class::XSAccessor::CORE:match which was called 30 times, avg 1µs/call: # 30 times (32µs+0s) by Class::XSAccessor::_generate_method at line 81, avg 1µs/call
sub Class::XSAccessor::CORE:match; # opcode
# spent 192µs within Class::XSAccessor::newxs_accessor which was called 26 times, avg 7µs/call: # 26 times (192µs+0s) by Class::XSAccessor::_generate_method at line 114, avg 7µs/call
sub Class::XSAccessor::newxs_accessor; # xsub
# spent 22µs within Class::XSAccessor::newxs_getter which was called 4 times, avg 5µs/call: # 4 times (22µs+0s) by Class::XSAccessor::_generate_method at line 86, avg 5µs/call
sub Class::XSAccessor::newxs_getter; # xsub
Report produced by the NYTProf 6.02 Perl profiler, developed by Tim Bunce and Adam Kaplan.