Performance Profile Index
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:30 2016

Profile of 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 for 13.7s (of 16.7s), executing 5093201 statements and 1585646 subroutine calls in 282 source files and 27 string evals.
SVG not supported

The Flame Graph above is a visualization of the time spent in distinct call stacks. The colors and x-axis position are not meaningful.

Top 15 Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
3000111.31s1.69sDBIx::Class::Storage::DBIHacks::::_resolve_column_infoDBIx::Class::Storage::DBIHacks::_resolve_column_info
9000311.17s1.37sDBIx::Class::ResultSet::::_resolved_attrs DBIx::Class::ResultSet::_resolved_attrs
6900042663ms1.48sDBIx::Class::SQLMaker::::_quote DBIx::Class::SQLMaker::_quote
6300021565ms1.86sDBIx::Class::SQLMaker::::_recurse_fields DBIx::Class::SQLMaker::_recurse_fields (recurses: max depth 1, inclusive time 1.57s)
7500031459ms685msSQL::Abstract::::_assert_pass_injection_guard SQL::Abstract::_assert_pass_injection_guard
300011371ms13.4sKoha::Objects::::find Koha::Objects::find
92711370ms420msClass::Accessor::Grouped::::__ANON__[Class/Accessor/Grouped.pm:774] Class::Accessor::Grouped::__ANON__[Class/Accessor/Grouped.pm:774]
300011313ms313msDBI::st::::execute DBI::st::execute (xsub)
654486297ms1.32sClass::C3::Componentised::::ensure_class_loaded Class::C3::Componentised::ensure_class_loaded (recurses: max depth 3, inclusive time 117ms)
2416785281ms318msList::Util::::first List::Util::first (xsub)
81645107244ms244msnext::::method next::method
13500041231ms231msDBIx::Class::Storage::DBIHacks::::CORE:matchDBIx::Class::Storage::DBIHacks::CORE:match (opcode)
300011225ms975msSQL::Abstract::::_where_hashpair_HASHREF SQL::Abstract::_where_hashpair_HASHREF
2143722206ms261msClass::Accessor::Grouped::::get_inherited Class::Accessor::Grouped::get_inherited
300011205ms445msDBIx::Class::ResultSet::::search_rs DBIx::Class::ResultSet::search_rs
See all 9699 subroutines

You can view a treemap of subroutine exclusive time, grouped by package.
NYTProf also generates call-graph files in Graphviz format: inter-package calls, all inter-subroutine calls (probably too complex to render easily).

You can hover over some table cells and headings to view extra information.
Some table column headings can be clicked on to sort the table by that column.
Source Code Files — ordered by exclusive time then name
StmtsExclusive
Time		 ReportsSource File
12300332.51slineDBIx/Class/ResultSet.pm
8730442.33slineSQL/Abstract.pm
10200211.86slineDBIx/Class/Storage/DBIHacks.pm
3122861.50slineDBIx/Class/Storage/DBI.pm
6060221.48slineDBIx/Class/SQLMaker.pm
332370715mslineDBIx/Class/ResultSource.pm
1878571.13slineClass/Accessor/Grouped.pm (including 1 string eval)
75064275mslineDBIx/Class/Storage/BlockRunner.pm
21651212mslineClass/C3/Componentised.pm
36016167mslineDBIx/Class/Storage/DBI/mysql.pm
2163mslineKoha/Objects.pm
3315694.8mslineDBIx/Class/Schema.pm
293.1mslineKoha/Object.pm
284.5mslineTry/Tiny.pm
2594371.9mslineDBIx/Class/AccessorGroup.pm
401871.7mslineplack.pl
3002162.2mslineContext/Preserve.pm
2704960.8mslineHash/Merge.pm
3651660.7mslineDBIx/Class/Row.pm
389939.3mslineModule/Find.pm
521336.2mslineDBIx/Class/ResultSourceProxy.pm
173758277mslineSub/Quote.pm (including 15 string evals)
3027029.1mslineDBIx/Class/_Util.pm
301019.0mslineDBIx/Class/ResultSource/Table.pm
333817.4mslineMoo/_Utils.pm
158117.1mslineDBIx/Class/ResultSourceProxy/Table.pm
240515.0mslineDBIx/Class/Relationship/Accessor.pm
13411.9mslineDBD/mysql.pm
311.3mslineKoha/Database.pm
236510.3mslineDBIx/Class/Relationship/BelongsTo.pm
5868.53mslineDBIx/Class/Relationship/HasMany.pm
9698.22mslineDBIx/Class/Relationship/ProxyMethods.pm
45057.84mslineSub/Defer.pm (including 1 string eval)
27.26mslineFile/Find.pm
425.24mslineTemplate/Provider.pm
7695.20mslineDBIx/Class.pm
6345.02mslineMethod/Generate/Accessor.pm
11094.45mslineDBIx/Class/InflateColumn.pm
20534.38mslineClass/XSAccessor.pm
154.34mslineKoha/Schema/Result/Aqinvoice.pm
14.16mslineApache/LogFormat/Compiler.pm
153.41mslineDBI/Const/GetInfoType.pm
93.39mslineDBIx/Class/SQLMaker/LimitDialects.pm
573.38mslineB.pm
3083.22mslineDBIx/Class/Relationship/ManyToMany.pm
363.11mslineDBIx/Class/Storage.pm
153.05mslineDBIx/Class/ResultSource/RowParser.pm
232.84mslineC4/Templates.pm
872.84mslineTemplate/Constants.pm
192.79mslineC4/Output.pm
422.79mslineConfig_heavy.pl
2652.30mslineMoo.pm (including 1 string eval)
1472.19mslineDBIx/Class/Carp.pm
3342.17mslineDBIx/Class/Relationship/Base.pm
352.15mslineDBIx/Class/Optional/Dependencies.pm
542.09mslineKoha/Schema/Result/Borrower.pm
152.06mslineDBIx/Class/ResultSource/RowParser/Util.pm
292.05mslineTemplate.pm
3491.97mslineMethod/Generate/Constructor.pm
231.89mslineTemplate/Config.pm
151.78mslineDBIx/Class/ResultSetColumn.pm
291.77mslineMRO/Compat.pm
1991.68mslineDBIx/Class/Relationship/HasOne.pm
161.55mslineTemplate/Document.pm
201.54mslineTemplate/Service.pm
341.50mslineDBD/mysql/GetInfo.pm
121.47mslineKoha/Schema/Result/BorrowerModification.pm
1551.44mslineDBIx/Class/Componentised.pm
191.38mslineKoha/Schema/Result/Aqbookseller.pm
141.31mslineKoha/Schema/Result/Aqbasketuser.pm
171.29mslineDBIx/Class/Storage/TxnScopeGuard.pm
341.26mslineKoha/Schema/Result/Branch.pm
121.18mslineKoha/Schema/Result/Subscriptionhistory.pm
121.18mslineKoha/Schema/Result/LanguageDescription.pm
151.16mslineDBIx/Class/ResultSourceHandle.pm
291.16mslineKoha/Schema/Result/Item.pm
1241.14mslinestrictures.pm
221.14mslineKoha/Schema/Result/Aqorder.pm
121.11mslineKoha/Schema/Result/DefaultCircRule.pm
111.08mslineKoha/Schema/Result/Deletedborrower.pm
161.03mslineKoha/Schema/Result/Subscription.pm
121.03mslineKoha/Schema/Result/ServicesThrottle.pm
11.01mslinebase.pm
161.00mslineTemplate/Base.pm
15946µslineKoha/Schema/Result/MarcMatcher.pm
15871µslineKoha/Schema/Result/Biblioitem.pm
10860µslineDBIx/Class/SQLMaker/MySQL.pm
7851µslineDBIx/Class/PK.pm
18849µslineKoha/Schema/Result/Reserve.pm
14846µslineTemplate/Exception.pm
18824µslineKoha/Schema/Result/Issue.pm
131819µslineDBI/Const/GetInfo/ODBC.pm
12802µslineKoha/Schema/Result/Deleteditem.pm
14795µslineKoha/Schema/Result/BorrowerAttributeType.pm
23794µslineKoha/Schema/Result/Biblio.pm
17793µslineKoha/Schema/Result/ImportRecord.pm
19771µslineKoha/Schema/Result/Aqbasket.pm
13767µslineKoha/Schema/Result/Suggestion.pm
18763µslineKoha/Schema/Result/Aqbudget.pm
12761µslineKoha/Schema/Result/Deletedbiblioitem.pm
17729µslineKoha/Schema/Result/Category.pm
15728µslineKoha/Schema/Result/CreatorBatch.pm
13720µslineKoha/Schema/Result/ImportBatch.pm
14720µslineKoha/Schema/Result/Aqcontract.pm
10714µslineDBIx/Class/Relationship/CascadeActions.pm
15706µslineKoha/Schema/Result/OldReserve.pm
12703µslineKoha/Schema/Result/Issuingrule.pm
14679µslineKoha/Schema/Result/Accountline.pm
13678µslineKoha/Schema/Result/MarcModificationTemplateAction.pm
18672µslineMoo/HandleMoose/_TypeMap.pm
13670µslineKoha/Schema/Result/BorrowerDebarment.pm
15664µslineKoha/Schema/Result/Course.pm
14660µslineKoha/Schema/Result/AqorderUser.pm
13658µslineKoha/Schema/Result/AuthSubfieldStructure.pm
14642µslineKoha/Schema/Result/MessageQueue.pm
13640µslineKoha/Schema/Result/AqbudgetsPlanning.pm
17636µslineKoha/Schema/Result/Matchpoint.pm
171.10mslineCGI/Compile.pm (including 1 string eval)
12635µslineKoha/Schema/Result/Z3950server.pm
16634µslineKoha/Schema/Result/CourseItem.pm
13632µslineKoha/Schema/Result/Serial.pm
14626µslineKoha/Schema/Result/OldIssue.pm
15625µslineKoha/Schema/Result/Virtualshelve.pm
18618µslineKoha/Schema/Result/MessageTransportType.pm
12617µslineKoha/Schema/Result/Accountoffset.pm
12617µslineKoha/Schema/Result/MarcSubfieldStructure.pm
14610µslineKoha/Schema/Result/Letter.pm
17606µslineKoha/Schema/Result/BorrowerMessagePreference.pm
13602µslineKoha/Schema/Result/Aqcontact.pm
16595µslineKoha/Schema/Result/HoldFillTarget.pm
36595µslineImport/Into.pm
12594µslineKoha/Schema/Result/Session.pm
12593µslineKoha/Schema/Result/CreatorLayout.pm
15591µslineKoha/Schema/Result/MessageTransport.pm
12573µslineKoha/Schema/Result/BranchTransferLimit.pm
28571µslineMoo/Object.pm
13568µslineKoha/Schema/Result/SubscriptionFrequency.pm
14567µslineKoha/Schema/Result/BorrowerMessageTransportPreference.pm
14566µslineKoha/Schema/Result/Branchtransfer.pm
13566µslineKoha/Schema/Result/DefaultBranchCircRule.pm
15563µslineKoha/Schema/Result/CourseReserve.pm
11563µslineKoha/Schema/Result/TmpHoldsqueue.pm
14560µslineKoha/Schema/Result/AuthorisedValuesBranch.pm
14559µslineKoha/Schema/Result/OpacNews.pm
13557µslineKoha/Schema/Result/PrintersProfile.pm
14557µslineKoha/Schema/Result/Overduerule.pm
15554µslineKoha/Schema/Result/ItemsLastBorrower.pm
13552µslineKoha/Schema/Result/ImportItem.pm
12552µslineKoha/Schema/Result/CreatorTemplate.pm
14551µslineKoha/Schema/Result/Aqbasketgroup.pm
14551µslineKoha/Schema/Result/Branchrelation.pm
15550µslineKoha/Schema/Result/Virtualshelfcontent.pm
14544µslineKoha/Schema/Result/AdditionalField.pm
14544µslineKoha/Schema/Result/Aqbudgetborrower.pm
14539µslineKoha/Schema/Result/Itemtype.pm
16539µslineKoha/Schema/Result/OaiSet.pm
12538µslineKoha/Schema/Result/SearchHistory.pm
14536µslineKoha/Schema/Result/CourseInstructor.pm
13534µslineKoha/Schema/Result/Biblioimage.pm
15533µslineKoha/Schema/Result/AqordersTransfer.pm
14529µslineKoha/Schema/Result/Currency.pm
12528µslineKoha/Schema/Result/AuthHeader.pm
15527µslineKoha/Schema/Result/MessageAttribute.pm
13527µslineKoha/Schema/Result/UserPermission.pm
12524µslineKoha/Schema/Result/Zebraqueue.pm
14521µslineKoha/Schema/Result/Review.pm
13520µslineKoha/Schema/Result/CategoriesBranch.pm
14520µslineKoha/Schema/Result/MatchpointComponent.pm
13520µslineKoha/Schema/Result/Collection.pm
14520µslineKoha/Schema/Result/Rating.pm
15520µslineKoha/Schema/Result/Subscriptionroutinglist.pm
14518µslineKoha/Schema/Result/BranchItemRule.pm
12518µslineKoha/Schema/Result/SpecialHoliday.pm
12517µslineKoha/Schema/Result/Printer.pm
12517µslineKoha/Schema/Result/Message.pm
12517µslineKoha/Schema/Result/PendingOfflineOperation.pm
14516µslineKoha/Schema/Result/AuthorisedValue.pm
14516µslineKoha/Schema/Result/TransportCost.pm
13514µslineKoha/Schema/Result/MatcherMatchpoint.pm
12511µslineKoha/Schema/Result/UploadedFile.pm
13509µslineKoha/Schema/Result/AuthTagStructure.pm
15508µslineKoha/Schema/Result/Matchcheck.pm
13504µslineKoha/Schema/Result/BorrowerFile.pm
13503µslineKoha/Schema/Result/ClassSource.pm
14502µslineKoha/Schema/Result/Permission.pm
12498µslineKoha/Schema/Result/Deletedbiblio.pm
12497µslineKoha/Schema/Result/ImportBiblio.pm
14497µslineKoha/Schema/Result/Virtualshelfshare.pm
12496µslineKoha/Schema/Result/ExportFormat.pm
12492µslineKoha/Schema/Result/Fieldmapping.pm
14489µslineKoha/Schema/Result/BranchBorrowerCircRule.pm
13489µslineKoha/Schema/Result/Localization.pm
14488µslineKoha/Schema/Result/PatronList.pm
14487µslineKoha/Schema/Result/OverduerulesTransportType.pm
11486µslineKoha/Schema/Result/Statistic.pm
14485µslineKoha/Schema/Result/TagAll.pm
14484µslineKoha/Schema/Result/TagsIndex.pm
13484µslineKoha/Schema/Result/Userflag.pm
12481µslineKoha/Schema/Result/MiscFile.pm
12476µslineKoha/Schema/Result/Aqbudgetperiod.pm
14472µslineKoha/Schema/Result/Branchcategory.pm
13472µslineKoha/Schema/Result/DefaultBorrowerCircRule.pm
14471µslineKoha/Schema/Result/Serialitem.pm
13470µslineKoha/Schema/Result/Patronimage.pm
12468µslineKoha/Schema/Result/ActionLog.pm
14468µslineKoha/Schema/Result/AdditionalFieldValue.pm
12467µslineKoha/Schema/Result/City.pm
12466µslineKoha/Schema/Result/MarcTagStructure.pm
14465µslineKoha/Schema/Result/PatronListPatron.pm
12464µslineKoha/Schema/Result/ReportsDictionary.pm
11458µslineKoha/Schema/Result/Notify.pm
13458µslineKoha/Schema/Result/OaiSetsBiblio.pm
13457µslineKoha/Schema/Result/ItemsSearchField.pm
13457µslineKoha/Schema/Result/BorrowerAttribute.pm
13456µslineKoha/Schema/Result/MarcModificationTemplate.pm
12453µslineKoha/Schema/Result/ColumnsSetting.pm
12448µslineKoha/Schema/Result/MatchpointComponentNorm.pm
13448µslineKoha/Schema/Result/BorrowerAttributeTypesBranch.pm
12448µslineKoha/Schema/Result/SavedReport.pm
13447µslineKoha/Schema/Result/BorrowerSync.pm
851.83mslineCGI.pm (including 5 string evals)
13442µslineKoha/Schema/Result/AqordersItem.pm
13440µslineKoha/Schema/Result/DefaultBranchItemRule.pm
13438µslineKoha/Schema/Result/ClassSortRule.pm
12438µslineKoha/Schema/Result/OaiSetsMapping.pm
12438µslineKoha/Schema/Result/RepeatableHoliday.pm
11437µslineKoha/Schema/Result/Browser.pm
13436µslineKoha/Schema/Result/Discharge.pm
12433µslineKoha/Schema/Result/Quote.pm
13431µslineKoha/Schema/Result/CreatorImage.pm
12429µslineKoha/Schema/Result/PluginData.pm
12424µslineKoha/Schema/Result/SocialData.pm
12424µslineKoha/Schema/Result/ImportRecordMatch.pm
12423µslineKoha/Schema/Result/Linktracker.pm
12421µslineKoha/Schema/Result/ItemCirculationAlertPreference.pm
12419µslineKoha/Schema/Result/AudioAlert.pm
12417µslineKoha/Schema/Result/LanguageSubtagRegistry.pm
14416µslineKoha/Schema/Result/TagsApproval.pm
10416µslineKoha/Schema.pm
11413µslineKoha/Schema/Result/Stopword.pm
12410µslineKoha/Schema/Result/Tag.pm
12404µslineKoha/Schema/Result/CollectionsTracking.pm
12402µslineKoha/Schema/Result/LanguageRfc4646ToIso639.pm
15400µslineClass/XSAccessor/Heavy.pm
12397µslineKoha/Schema/Result/OaiSetsDescription.pm
187830µslineDevel/GlobalDestruction.pm (including 1 string eval)
12395µslineDBIx/Class/Exception.pm
13395µslineKoha/Schema/Result/SubscriptionNumberpattern.pm
12394µslineKoha/Schema/Result/NeedMergeAuthority.pm
10382µslineKoha/Schema/Result/ActionLogs.pm
12379µslineKoha/Schema/Result/BiblioFramework.pm
11373µslineKoha/Schema/Result/LanguageScriptMapping.pm
11357µslineKoha/Schema/Result/LanguageScriptBidi.pm
14354µslineKoha/Schema/Result/AuthType.pm
12350µslineKoha/Schema/Result/ImportAuth.pm
12330µslineKoha/Schema/Result/Systempreference.pm
12270µslineKoha/Schema/Result/SavedSql.pm
5417µslineDBI.pm (including 1 string eval)
8189µslineDBIx/Class/Core.pm
8185µslineDBIx/Class/Relationship.pm
2177µslineConfig.pm
8172µslineDBIx/Class/Relationship/Helpers.pm
7157µslineDBIx/Class/PK/Auto.pm
8156µslineMoo/sification.pm
12102µslineKoha/Schema/Result/Alert.pm
1574µslineDBI/Const/GetInfo/ANSI.pm
568µslineDBIx/Class/StartupCheck.pm
550µslineMoo/_mro.pm
15µslineConfig_git.pl
15µslineModule/Runtime.pm
00sline(eval 161)[Text/MicroTemplate.pm:389]
00slineText/MicroTemplate.pm
00sline(eval 162)[Apache/LogFormat/Compiler.pm:151]
00slineIO/Socket.pm
00slinePlack/App/URLMap.pm
00slinenamespace/clean.pm
00slineB/Hooks/EndOfScope/XS.pm
00slineFile/Spec/Unix.pm
00slineXML/Parser/Expat.pm
00slineXML/Parser.pm
00slineCGI/Emulate/PSGI.pm
1158µsline/unknown-eval-invoker (including 1 string eval)
509324513.7sTotal (-44 statements are unaccounted for)
1806148.5msAverage
573µsMedian
0.00016Deviation
Report produced by the NYTProf 6.02 Perl profiler, developed by Tim Bunce and Adam Kaplan.