← 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 14:31:06 2016
Reported on Fri Jan 8 14:33:27 2016

Filename/usr/lib/x86_64-linux-gnu/perl5/5.20/Class/XSAccessor.pm
StatementsExecuted 68 statements in 157µs
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
33113.92ms4.23msClass::XSAccessor::::_generate_methodClass::XSAccessor::_generate_method
33221.53ms6.06msClass::XSAccessor::::importClass::XSAccessor::import
111410µs518µsClass::XSAccessor::::BEGIN@6Class::XSAccessor::BEGIN@6
26481297µs297µsClass::XSAccessor::::_make_hashClass::XSAccessor::_make_hash
2911245µs245µsClass::XSAccessor::::newxs_accessorClass::XSAccessor::newxs_accessor (xsub)
331138µs38µsClass::XSAccessor::::CORE:matchClass::XSAccessor::CORE:match (opcode)
41124µs24µsClass::XSAccessor::::newxs_getterClass::XSAccessor::newxs_getter (xsub)
11119µs19µsClass::XSAccessor::::BEGIN@2Class::XSAccessor::BEGIN@2
11112µs24µsClass::XSAccessor::::BEGIN@84Class::XSAccessor::BEGIN@84
11111µs15µsClass::XSAccessor::::BEGIN@4Class::XSAccessor::BEGIN@4
1119µs43µsClass::XSAccessor::::BEGIN@5Class::XSAccessor::BEGIN@5
1118µs15µsClass::XSAccessor::::BEGIN@3Class::XSAccessor::BEGIN@3
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;
2119µ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
3222µs
# spent 15µs (8+7) within Class::XSAccessor::BEGIN@3 which was called: # once (8µs+7µs) by Module::Runtime::require_module at line 3
use strict;
# spent 15µs making 1 call to Class::XSAccessor::BEGIN@3 # spent 7µs making 1 call to strict::import
4218µs
# spent 15µs (11+4) within Class::XSAccessor::BEGIN@4 which was called: # once (11µs+4µs) by Module::Runtime::require_module at line 4
use warnings;
# spent 15µs making 1 call to Class::XSAccessor::BEGIN@4 # spent 4µs making 1 call to warnings::import
5276µs
# spent 43µs (9+33) within Class::XSAccessor::BEGIN@5 which was called: # once (9µs+33µs) by Module::Runtime::require_module at line 5
use Carp qw/croak/;
# spent 43µs making 1 call to Class::XSAccessor::BEGIN@5 # spent 33µs making 1 call to Exporter::import
61518µs
# spent 518µs (410+109) within Class::XSAccessor::BEGIN@6 which was called: # once (410µs+109µs) by Module::Runtime::require_module at line 6
use Class::XSAccessor::Heavy;
# spent 518µs making 1 call to Class::XSAccessor::BEGIN@6
717µ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
9our $VERSION = '1.19';
10
111332µsXSLoader::load('Class::XSAccessor', $VERSION);
# spent 332µs making 1 call to XSLoader::load
12
13
# spent 297µs within Class::XSAccessor::_make_hash which was called 264 times, avg 1µs/call: # 33 times (102µs+0s) by Class::XSAccessor::import at line 37, avg 3µs/call # 33 times (37µs+0s) by Class::XSAccessor::import at line 43, avg 1µs/call # 33 times (29µs+0s) by Class::XSAccessor::import at line 38, avg 870ns/call # 33 times (27µs+0s) by Class::XSAccessor::import at line 39, avg 818ns/call # 33 times (27µs+0s) by Class::XSAccessor::import at line 42, avg 806ns/call # 33 times (25µs+0s) by Class::XSAccessor::import at line 40, avg 767ns/call # 33 times (25µs+0s) by Class::XSAccessor::import at line 41, avg 764ns/call # 33 times (25µs+0s) by Class::XSAccessor::import at line 44, avg 755ns/call
sub _make_hash {
1481µs my $ref = shift;
15
16834µs if (ref ($ref)) {
17 if (ref($ref) eq 'ARRAY') {
18 $ref = { map { $_ => $_ } @$ref }
19 }
20 } else {
21 $ref = { $ref, $ref };
22 }
23
24825µs return $ref;
25}
26
27
# spent 6.06ms (1.53+4.52) within Class::XSAccessor::import which was called 33 times, avg 184µs/call: # 29 times (1.36ms+800µs) 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::SQLMaker::limit_dialect or DBIx::Class::SQLMaker::name_sep or DBIx::Class::Storage::DBI::Cursor::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 75µs/call # 4 times (170µs+3.72ms) by Method::Generate::Accessor::_generate_xs at line 641 of Method/Generate/Accessor.pm, avg 974µs/call
sub import {
2812µs my $own_class = shift;
2912µs my ($caller_pkg) = caller();
30
31 # Support both { getters => ... } and plain getters => ...
3217µs my %opts = ref($_[0]) eq 'HASH' ? %{$_[0]} : @_;
33
3412µs $caller_pkg = $opts{class} if defined $opts{class};
35
36 # TODO: Refactor. Move more duplicated code to ::Heavy
3714µs33102µs my $read_subs = _make_hash($opts{getters} || {});
# spent 102µs making 33 calls to Class::XSAccessor::_make_hash, avg 3µs/call
3813µs3329µs my $set_subs = _make_hash($opts{setters} || {});
# spent 29µs making 33 calls to Class::XSAccessor::_make_hash, avg 870ns/call
3911µs3327µs my $acc_subs = _make_hash($opts{accessors} || {});
# spent 27µs making 33 calls to Class::XSAccessor::_make_hash, avg 818ns/call
4011µs3325µs my $lvacc_subs = _make_hash($opts{lvalue_accessors} || {});
# spent 25µs making 33 calls to Class::XSAccessor::_make_hash, avg 767ns/call
4112µs3325µs my $pred_subs = _make_hash($opts{predicates} || {});
# spent 25µs making 33 calls to Class::XSAccessor::_make_hash, avg 764ns/call
4211µs3327µs my $ex_pred_subs = _make_hash($opts{exists_predicates} || {});
# spent 27µs making 33 calls to Class::XSAccessor::_make_hash, avg 806ns/call
431900ns3337µs my $def_pred_subs = _make_hash($opts{defined_predicates} || {});
# spent 37µs making 33 calls to Class::XSAccessor::_make_hash, avg 1µs/call
4411µs3325µs my $test_subs = _make_hash($opts{__tests__} || {});
# spent 25µs making 33 calls to Class::XSAccessor::_make_hash, avg 755ns/call
451900ns my $construct_subs = $opts{constructors} || [defined($opts{constructor}) ? $opts{constructor} : ()];
461500ns my $true_subs = $opts{true} || [];
471500ns my $false_subs = $opts{false} || [];
48
4919µ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 {
5882µs my $subs = $subtype->[1];
5989µs foreach my $subname (keys %$subs) {
6012µs my $hashkey = $subs->{$subname};
6114µs334.23ms _generate_method($caller_pkg, $subname, $hashkey, \%opts, $subtype->[0]);
# spent 4.23ms making 33 calls to Class::XSAccessor::_generate_method, avg 128µs/call
62 }
63 }
64
6519µs foreach my $subtype ( ["constructor", $construct_subs],
66 ["true", $true_subs],
67 ["false", $false_subs] )
68 {
6932µs foreach my $subname (@{$subtype->[1]}) {
70 _generate_method($caller_pkg, $subname, "", \%opts, $subtype->[0]);
71 }
72 }
73}
74
75
# spent 4.23ms (3.92+307µs) within Class::XSAccessor::_generate_method which was called 33 times, avg 128µs/call: # 33 times (3.92ms+307µs) by Class::XSAccessor::import at line 61, avg 128µs/call
sub _generate_method {
761900ns my ($caller_pkg, $subname, $hashkey, $opts, $type) = @_;
77
781300ns croak("Cannot use undef as a hash key for generating an XS $type accessor. (Sub: $subname)")
79 if not defined $hashkey;
80
8117µs3338µs $subname = "${caller_pkg}::$subname" if $subname !~ /::/;
# spent 38µs making 33 calls to Class::XSAccessor::CORE:match, avg 1µs/call
82
831500ns Class::XSAccessor::Heavy::check_sub_existence($subname) if not $opts->{replace};
84236µs
# spent 24µs (12+12) within Class::XSAccessor::BEGIN@84 which was called: # once (12µs+12µs) by Module::Runtime::require_module at line 84
no warnings 'redefine'; # don't warn about an explicitly requested redefine
# spent 24µs making 1 call to Class::XSAccessor::BEGIN@84 # spent 12µs making 1 call to warnings::unimport
85
8614µs424µs if ($type eq 'getter') {
# spent 24µs making 4 calls to Class::XSAccessor::newxs_getter, avg 6µ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 {
114120µs29245µs newxs_accessor($subname, $hashkey, $opts->{chained}||0);
# spent 245µs making 29 calls to Class::XSAccessor::newxs_accessor, avg 8µs/call
115 }
116}
117
1181;
119
120__END__
 
# spent 38µs within Class::XSAccessor::CORE:match which was called 33 times, avg 1µs/call: # 33 times (38µs+0s) by Class::XSAccessor::_generate_method at line 81, avg 1µs/call
sub Class::XSAccessor::CORE:match; # opcode
# spent 245µs within Class::XSAccessor::newxs_accessor which was called 29 times, avg 8µs/call: # 29 times (245µs+0s) by Class::XSAccessor::_generate_method at line 114, avg 8µs/call
sub Class::XSAccessor::newxs_accessor; # xsub
# spent 24µs within Class::XSAccessor::newxs_getter which was called 4 times, avg 6µs/call: # 4 times (24µs+0s) by Class::XSAccessor::_generate_method at line 86, avg 6µs/call
sub Class::XSAccessor::newxs_getter; # xsub
Report produced by the NYTProf 6.02 Perl profiler, developed by Tim Bunce and Adam Kaplan.