Performance Profile Index
For /home/chris/git/koha.git/circ/circulation.pl
  Run on Tue Aug 25 19:46:46 2009
Reported on Tue Aug 25 19:47:55 2009

Profile of /home/chris/git/koha.git/circ/circulation.pl for 1.09s, executing 48178 statements and 6378 subroutine calls in 59 source files and 32 string evals.
Top 15 Subroutines — ordered by exclusive time
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
21251.6ms51.8msDBI::db::::do DBI::db::do(xsub)
2021130.5ms50.5msCache::Memcached::GetParser::::parse_from_sockCache::Memcached::GetParser::parse_from_sock
2021129.0ms98.2msCache::Memcached::::_load_multi Cache::Memcached::_load_multi
153641322.9ms23.1msDBI::st::::execute DBI::st::execute(xsub)
2021110.3ms10.3msCache::Memcached::::__ANON__[Cache/Memcached.pm:721] Cache::Memcached::__ANON__[Cache/Memcached.pm:721]
202119.49ms19.9msCache::Memcached::GetParser::::parse_bufferCache::Memcached::GetParser::parse_buffer
2118.92ms17.8msCGI::Session::::_load_pluggables CGI::Session::_load_pluggables
123118.58ms11.1msCGI::Carp::::stamp CGI::Carp::stamp
6117.20ms7.38msCGI::::_compile CGI::_compile
18144127.09ms16.4msDBI::st::::fetchrow_hashref DBI::st::fetchrow_hashref(xsub)
202117.00ms108msCache::Memcached::::get_multi Cache::Memcached::get_multi
107235.69ms5.81msDBI::db::::ping DBI::db::ping(xsub)
210215.51ms5.51msMemoize::Memcached::::_get_key Memoize::Memcached::_get_key
247215.40ms5.40msFile::Spec::Unix::::splitpath File::Spec::Unix::splitpath
194115.10ms10.4msCache::Memcached::::__ANON__[Cache/Memcached.pm:668] Cache::Memcached::__ANON__[Cache/Memcached.pm:668]
See all 1098 subroutines
Source Code Files — ordered by exclusive time then name
StmtsExclusive
Time		 Avg.ReportsSource File
2181190.8ms4µsline • block • subCache/Memcached.pm
4152.5ms1.28msline • block • subCGI/Session/Driver/mysql.pm
449918.0ms4µsline • block • subCGI/Carp.pm
594715.8ms3µsline • block • subCache/Memcached/GetParser.pm
63214.8ms23µsline • block • subCGI.pm (executed 7 string evals)
35313.7ms39µsline • block • sub/home/chris/git/koha.git/C4/Reserves.pm
34013.4ms39µsline • block • sub/home/chris/git/koha.git/C4/Circulation.pm
48110.9ms23µsline • block • sub/home/chris/git/koha.git/C4/Members.pm
150210.1ms7µsline • block • subMemoize/Memcached.pm
9459.37ms10µsline • block • subDBI.pm
4408.03ms18µsline • block • sub/home/chris/git/koha.git/C4/Context.pm
14327.31ms5µsline • block • subMemoize.pm (executed 3 string evals)
5856.76ms12µsline • block • sub/home/chris/git/koha.git/C4/Calendar.pm
1636.73ms41µsline • block • sub/home/chris/git/koha.git/C4/Biblio.pm
5595.33ms10µsline • block • subDBD/mysql.pm
2365.21ms22µsline • block • sub/home/chris/git/koha.git/C4/Auth.pm
4825.17ms11µsline • block • sub/home/chris/git/koha.git/C4/Dates.pm (executed 8 string evals)
25304.18ms2µsline • block • subFile/Spec/Unix.pm
753.90ms52µsline • block • sub/home/chris/git/koha.git/C4/Branch.pm
4013.77ms9µsline • block • subCGI/Session.pm (executed 6 string evals)
8453.75ms4µsline • block • sub/home/chris/git/koha.git/circ/../../lib/Storable.pm (autosplit into ../../lib/auto/Storable/thaw.al)
2333.55ms15µsline • block • sub/home/chris/git/koha.git/circ/circulation.pl
4663.49ms7µsline • block • subCGI/Cookie.pm
11302.93ms3µsline • block • subHTML/Template/Pro.pm
1632.92ms18µsline • block • subYAML/Base.pm (executed 4 string evals)
142.68ms191µsline • block • sub/home/chris/git/koha.git/C4/Overdues.pm
672.49ms37µsline • block • subCGI/Session/Driver/DBI.pm
392.17ms56µsline • block • subYAML.pm
211.78ms85µsline • block • subYAML/Node.pm
25882µs35µsline • block • subCGI/Session/Serialize/yaml.pm (executed 2 string evals)
132871µs7µsline • block • sub/home/chris/git/koha.git/C4/Output.pm
7760µs109µsline • block • subYAML/Syck.pm
486668µs1µsline • block • subCGI/Util.pm
28630µs23µsline • block • subCGI/Session/Driver.pm
151585µs4µsline • block • sub/home/chris/git/koha.git/C4/Languages.pm
23563µs24µsline • block • sub/home/chris/git/koha.git/C4/Koha.pm
16415µs26µsline • block • sub/home/chris/git/koha.git/C4/Items.pm
83406µs5µsline • block • subbase.pm (executed 1 string eval)
152390µs3µsline • block • subExporter.pm
43385µs9µsline • block • subData/Dumper.pm
12226µs19µsline • block • subCGI/Session/ID/md5.pm
9224µs25µsline • block • subYAML/Tag.pm
132202µs2µsline • block • subText/Abbrev.pm
29176µs6µsline • block • subAutoLoader.pm
71110µs2µsline • block • subwarnings.pm
32109µs3µsline • block • suboverload.pm
5105µs21µsline • block • sub/home/chris/git/koha.git/kohaversion.pl
3793µs3µsline • block • substrict.pm
2393µs4µsline • block • subconstant.pm
1533µs2µsline • block • subCGI/Session/ErrorHandler.pm
513µs3µsline • block • subUNIVERSAL.pm
811µs1µsline • block • sub/home/chris/git/koha.git/C4/Boolean.pm
17µs7µsline • block • subURI/Split.pm
1400ns400nsline • block • sub/home/chris/git/koha.git/C4/Print.pm
1300ns300nsline • block • sub/home/chris/git/koha.git/C4/Accounts.pm
1300ns300nsline • block • sub/home/chris/git/koha.git/C4/Search.pm
00s0sline • block • subPOSIX.pm
00s0sline • block • subStorable.pm (executed 1 string eval)
00s0sline • block • subDate/Calc.pm
47960340msTotal (32 string evals account for a further 218 statements)
8125.76msAverage
882µs7µsMedian
0.000886.1e-6Deviation

Package treemap
Packages - subroutine times rolled up to level 1 package name
Exclusive
Time		 Package Name Prefix
115msDBI:: (includes 5 packages)
108msCache:: (includes 2 packages)
37.5msCGI:: (includes 10 packages)
20.8msC4:: (includes 15 packages)
16.8msMemoize:: (includes 2 packages)
7.29msFile::
6.45msDate::
4.89msStorable::
3.39msHTML::
2.87msDBD:: (includes 3 packages)
1.88msYAML:: (includes 2 packages)
540µsExporter::
481µsData::
422µsbase::
368µsPOSIX::
305µsstrict::
293µsUNIVERSAL::
240µswarnings::
176µsoverload::
151µsText::
123µsconstant::
80µsAutoLoader::
64µsURI::
35µsmro::
34µsInternals::
Packages - subroutine times rolled up to level 2 package name
Exclusive
Time		 Package Name Prefix
108msCache::Memcached:: (includes 2 packages)
63.2msDBI::db::
39.3msDBI::st::
13.9msCGI::Carp::
11.8msCGI::Session:: (includes 6 packages)
10.9msMemoize::Memcached::
9.38msCGI::
8.02msDBI::
7.29msFile::Spec::
6.45msDate::Calc::
5.89msMemoize::
4.89msStorable::
4.45msC4::Dates::
4.17msDBI::common::
4.10msC4::Context::
3.39msHTML::Template::
2.52msDBD::mysql::
2.13msC4::Circulation::
1.96msC4::Members::
1.75msC4::Calendar::
1.66msC4::Reserves::
1.33msCGI::Cookie::
1.16msC4::Auth::
1.13msCGI::Util::
1.03msC4::Biblio::
1.03msYAML::Base::
862µsC4::Output::
857µsYAML::Syck::
651µsC4::Languages::
540µsExporter::
481µsData::Dumper::
422µsbase::
415µsC4::Branch::
368µsPOSIX::
352µsDBD::_:: (includes 2 packages)
305µsstrict::
293µsUNIVERSAL::
240µswarnings::
230µsC4::Koha::
213µsC4::Overdues::
176µsoverload::
151µsText::Abbrev::
123µsconstant::
121µsC4::Items::
80µsAutoLoader::
66µsC4::Boolean::
64µsURI::Split::
43µsDBI::dr::
35µsmro::
34µsInternals::
0sYAML::
Packages - subroutine times rolled up to level 3 package name
Exclusive
Time		 Package Name Prefix
63.5msCache::Memcached::
63.2msDBI::db::
44.1msCache::Memcached::GetParser::
39.3msDBI::st::
13.9msCGI::Carp::
11.1msCGI::Session::
10.9msMemoize::Memcached::
9.38msCGI::
8.02msDBI::
7.29msFile::Spec::Unix::
6.45msDate::Calc::
5.89msMemoize::
4.89msStorable::
4.45msC4::Dates::
4.17msDBI::common::
4.10msC4::Context::
3.39msHTML::Template::Pro::
2.52msDBD::mysql::st::
2.13msC4::Circulation::
1.96msC4::Members::
1.75msC4::Calendar::
1.66msC4::Reserves::
1.33msCGI::Cookie::
1.16msC4::Auth::
1.13msCGI::Util::
1.03msC4::Biblio::
1.03msYAML::Base::
862µsC4::Output::
857µsYAML::Syck::
651µsC4::Languages::
540µsExporter::
481µsData::Dumper::
427µsCGI::Session::Driver:: (includes 3 packages)
422µsbase::
415µsC4::Branch::
368µsPOSIX::
319µsDBD::_::st::
305µsstrict::
293µsUNIVERSAL::
240µswarnings::
230µsC4::Koha::
213µsC4::Overdues::
176µsoverload::
151µsText::Abbrev::
123µsconstant::
121µsC4::Items::
120µsCGI::Session::ErrorHandler::
85µsCGI::Session::Serialize::
80µsAutoLoader::
66µsC4::Boolean::
64µsURI::Split::
43µsDBI::dr::
35µsmro::
34µsInternals::
32µsDBD::_::common::
0sDBD::mysql::
0sYAML::
Packages - subroutine times rolled up to level 4 package name
Exclusive
Time		 Package Name Prefix
63.5msCache::Memcached::
63.2msDBI::db::
44.1msCache::Memcached::GetParser::
39.3msDBI::st::
13.9msCGI::Carp::
11.1msCGI::Session::
10.9msMemoize::Memcached::
9.38msCGI::
8.02msDBI::
7.29msFile::Spec::Unix::
6.45msDate::Calc::
5.89msMemoize::
4.89msStorable::
4.45msC4::Dates::
4.17msDBI::common::
4.10msC4::Context::
3.39msHTML::Template::Pro::
2.52msDBD::mysql::st::
2.13msC4::Circulation::
1.96msC4::Members::
1.75msC4::Calendar::
1.66msC4::Reserves::
1.33msCGI::Cookie::
1.16msC4::Auth::
1.13msCGI::Util::
1.03msC4::Biblio::
1.03msYAML::Base::
862µsC4::Output::
857µsYAML::Syck::
651µsC4::Languages::
540µsExporter::
481µsData::Dumper::
422µsbase::
415µsC4::Branch::
368µsPOSIX::
319µsDBD::_::st::
305µsstrict::
293µsUNIVERSAL::
240µswarnings::
230µsC4::Koha::
213µsC4::Overdues::
190µsCGI::Session::Driver::mysql::
176µsoverload::
168µsCGI::Session::Driver::DBI::
151µsText::Abbrev::
123µsconstant::
121µsC4::Items::
120µsCGI::Session::ErrorHandler::
85µsCGI::Session::Serialize::yaml::
80µsAutoLoader::
69µsCGI::Session::Driver::
66µsC4::Boolean::
64µsURI::Split::
43µsDBI::dr::
35µsmro::
34µsInternals::
32µsDBD::_::common::
0sDBD::mysql::
0sYAML::
Report produced by the NYTProf 2.10 Perl profiler, developed by Tim Bunce and Adam Kaplan.