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:59:53 2016
Reported on Fri Jan 8 14:00:22 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 16.4s (of 19.5s), executing 5081201 statements and 1597647 subroutine calls in 280 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
3000112.17s2.17sDBI::st::::execute DBI::st::execute (xsub)
3000111.41s1.80sDBIx::Class::Storage::DBIHacks::::_resolve_column_infoDBIx::Class::Storage::DBIHacks::_resolve_column_info
9000311.20s1.42sDBIx::Class::ResultSet::::_resolved_attrs DBIx::Class::ResultSet::_resolved_attrs
6900042786ms1.65sDBIx::Class::SQLMaker::::_quote DBIx::Class::SQLMaker::_quote
6300021577ms1.99sDBIx::Class::SQLMaker::::_recurse_fields DBIx::Class::SQLMaker::_recurse_fields (recurses: max depth 1, inclusive time 1.69s)
7500031470ms706msSQL::Abstract::::_assert_pass_injection_guard SQL::Abstract::_assert_pass_injection_guard
92711380ms434msClass::Accessor::Grouped::::__ANON__[Class/Accessor/Grouped.pm:774] Class::Accessor::Grouped::__ANON__[Class/Accessor/Grouped.pm:774]
2416785375ms412msList::Util::::first List::Util::first (xsub)
654486284ms1.29sClass::C3::Componentised::::ensure_class_loaded Class::C3::Componentised::ensure_class_loaded (recurses: max depth 3, inclusive time 116ms)
81645107255ms255msnext::::method next::method
13500041242ms242msDBIx::Class::Storage::DBIHacks::::CORE:matchDBIx::Class::Storage::DBIHacks::CORE:match (opcode)
300011231ms1.10sSQL::Abstract::::_where_hashpair_HASHREF SQL::Abstract::_where_hashpair_HASHREF
300011222ms486msDBIx::Class::ResultSet::::search_rs DBIx::Class::ResultSet::search_rs
652885218ms3.09sTry::Tiny::::try Try::Tiny::try
2143722202ms261msClass::Accessor::Grouped::::get_inherited Class::Accessor::Grouped::get_inherited

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
3122863.50slineDBIx/Class/Storage/DBI.pm
8730442.58slineSQL/Abstract.pm
12300332.58slineDBIx/Class/ResultSet.pm
10200212.01slineDBIx/Class/Storage/DBIHacks.pm
6060221.56slineDBIx/Class/SQLMaker.pm
323370731mslineDBIx/Class/ResultSource.pm
1908571.17slineClass/Accessor/Grouped.pm (including 1 string eval)
75064371mslineDBIx/Class/Storage/BlockRunner.pm
21651209mslineClass/C3/Componentised.pm
36016169mslineDBIx/Class/Storage/DBI/mysql.pm
4018143mslineplack.pl
33156128mslineDBIx/Class/Schema.pm
286.9mslineTry/Tiny.pm
3002171.3mslineContext/Preserve.pm
3051669.8mslineDBIx/Class/Row.pm
2594369.2mslineDBIx/Class/AccessorGroup.pm
2704954.9mslineHash/Merge.pm
389942.6mslineModule/Find.pm
521340.3mslineDBIx/Class/ResultSourceProxy.pm
301038.1mslineDBIx/Class/ResultSource/Table.pm
3027031.9mslineDBIx/Class/_Util.pm
173758369mslineSub/Quote.pm (including 15 string evals)
226.1mslineFile/Find.pm
158120.7mslineDBIx/Class/ResultSourceProxy/Table.pm
240519.2mslineDBIx/Class/Relationship/Accessor.pm
333818.9mslineMoo/_Utils.pm
312.0mslineKoha/Database.pm
23659.18mslineDBIx/Class/Relationship/BelongsTo.pm
1349.07mslineDBD/mysql.pm
45058.11mslineSub/Defer.pm (including 1 string eval)
427.84mslineTemplate/Provider.pm
7695.30mslineDBIx/Class.pm
6345.29mslineMethod/Generate/Accessor.pm
874.96mslineTemplate/Constants.pm
234.51mslineC4/Templates.pm
194.01mslineC4/Output.pm
153.89mslineDBI/Const/GetInfoType.pm
11093.68mslineDBIx/Class/InflateColumn.pm
20533.58mslineClass/XSAccessor.pm
203.52mslineTemplate/Service.pm
9693.47mslineDBIx/Class/Relationship/ProxyMethods.pm
93.42mslineDBIx/Class/SQLMaker/LimitDialects.pm
13.25mslineApache/LogFormat/Compiler.pm
573.24mslineB.pm
3083.21mslineDBIx/Class/Relationship/ManyToMany.pm
293.13mslineTemplate.pm
363.07mslineDBIx/Class/Storage.pm
153.06mslineDBIx/Class/ResultSource/RowParser.pm
423.03mslineConfig_heavy.pl
5862.66mslineDBIx/Class/Relationship/HasMany.pm
2652.52mslineMoo.pm (including 1 string eval)
3342.17mslineDBIx/Class/Relationship/Base.pm
352.14mslineDBIx/Class/Optional/Dependencies.pm
152.10mslineDBIx/Class/ResultSetColumn.pm
542.08mslineKoha/Schema/Result/Borrower.pm
161.99mslineTemplate/Document.pm
3491.97mslineMethod/Generate/Constructor.pm
231.84mslineTemplate/Config.pm
151.84mslineDBIx/Class/ResultSource/RowParser/Util.pm
1991.79mslineDBIx/Class/Relationship/HasOne.pm
1551.57mslineDBIx/Class/Componentised.pm
291.53mslineKoha/Schema/Result/Item.pm
161.52mslineTemplate/Base.pm
1471.50mslineDBIx/Class/Carp.pm
291.46mslineMRO/Compat.pm
171.41mslineDBIx/Class/Storage/TxnScopeGuard.pm
1241.39mslinestrictures.pm
121.21mslineKoha/Schema/Result/BorrowerModification.pm
131.15mslineKoha/Schema/Result/SubscriptionFrequency.pm
141.14mslineTemplate/Exception.pm
161.12mslineKoha/Schema/Result/HoldFillTarget.pm
1991µslinebase.pm
14975µslineKoha/Schema/Result/Branchtransfer.pm
15958µslineKoha/Schema/Result/MessageAttribute.pm
11950µslineKoha/Schema/Result/Deletedborrower.pm
18949µslineKoha/Schema/Result/Issue.pm
34943µslineDBD/mysql/GetInfo.pm
16906µslineKoha/Schema/Result/Subscription.pm
12881µslineKoha/Schema/Result/Deleteditem.pm
15864µslineKoha/Schema/Result/Biblioitem.pm
34862µslineKoha/Schema/Result/Branch.pm
19834µslineKoha/Schema/Result/Aqbookseller.pm
18813µslineKoha/Schema/Result/Reserve.pm
15808µslineDBIx/Class/ResultSourceHandle.pm
28806µslineMoo/Object.pm
10801µslineDBIx/Class/SQLMaker/MySQL.pm
17800µslineKoha/Schema/Result/Category.pm
13793µslineKoha/Schema/Result/Suggestion.pm
14789µslineKoha/Schema/Result/MessageQueue.pm
23779µslineKoha/Schema/Result/Biblio.pm
10778µslineDBIx/Class/Relationship/CascadeActions.pm
13768µslineKoha/Schema/Result/BorrowerDebarment.pm
17762µslineKoha/Schema/Result/BorrowerMessagePreference.pm
17761µslineKoha/Schema/Result/ImportRecord.pm
16748µslineKoha/Schema/Result/CourseItem.pm
18738µslineKoha/Schema/Result/Aqbudget.pm
19730µslineKoha/Schema/Result/Aqbasket.pm
13729µslineKoha/Schema/Result/UserPermission.pm
12717µslineKoha/Schema/Result/Deletedbiblioitem.pm
12715µslineKoha/Schema/Result/Issuingrule.pm
12692µslineKoha/Schema/Result/ExportFormat.pm
15687µslineKoha/Schema/Result/AqordersTransfer.pm
22682µslineKoha/Schema/Result/Aqorder.pm
13676µslineKoha/Schema/Result/ImportBatch.pm
15670µslineKoha/Schema/Result/OldReserve.pm
12664µslineKoha/Schema/Result/CollectionsTracking.pm
14650µslineKoha/Schema/Result/Overduerule.pm
18650µslineMoo/HandleMoose/_TypeMap.pm
14649µslineKoha/Schema/Result/Accountline.pm
12643µslineKoha/Schema/Result/Subscriptionhistory.pm
12642µslineKoha/Schema/Result/MarcSubfieldStructure.pm
13639µslineKoha/Schema/Result/MarcModificationTemplateAction.pm
13638µslineKoha/Schema/Result/AuthSubfieldStructure.pm
15635µslineKoha/Schema/Result/Course.pm
13633µslineKoha/Schema/Result/SubscriptionNumberpattern.pm
171.21mslineCGI/Compile.pm (including 1 string eval)
15629µslineKoha/Schema/Result/Virtualshelfcontent.pm
14627µslineKoha/Schema/Result/OldIssue.pm
13621µslineKoha/Schema/Result/Serial.pm
12621µslineKoha/Schema/Result/CreatorTemplate.pm
15619µslineKoha/Schema/Result/CreatorBatch.pm
12619µslineKoha/Schema/Result/ImportBiblio.pm
14616µslineKoha/Schema/Result/PatronListPatron.pm
14614µslineKoha/Schema/Result/Rating.pm
15613µslineKoha/Schema/Result/Virtualshelve.pm
14598µslineKoha/Schema/Result/TransportCost.pm
12593µslineKoha/Schema/Result/Z3950server.pm
12593µslineKoha/Schema/Result/CreatorLayout.pm
14593µslineKoha/Schema/Result/OpacNews.pm
14587µslineKoha/Schema/Result/MatchpointComponent.pm
12587µslineKoha/Schema/Result/SavedSql.pm
17584µslineKoha/Schema/Result/Matchpoint.pm
7583µslineDBIx/Class/PK.pm
14575µslineKoha/Schema/Result/Review.pm
14574µslineKoha/Schema/Result/BorrowerAttributeType.pm
13571µslineKoha/Schema/Result/Biblioimage.pm
10570µslineKoha/Schema.pm
13570µslineKoha/Schema/Result/BorrowerAttributeTypesBranch.pm
13567µslineKoha/Schema/Result/PrintersProfile.pm
131567µslineDBI/Const/GetInfo/ODBC.pm
18564µslineKoha/Schema/Result/MessageTransportType.pm
13564µslineKoha/Schema/Result/Localization.pm
13564µslineKoha/Schema/Result/Aqcontact.pm
36564µslineImport/Into.pm
14564µslineKoha/Schema/Result/Aqcontract.pm
15562µslineKoha/Schema/Result/Aqinvoice.pm
12559µslineKoha/Schema/Result/ImportAuth.pm
15550µslineKoha/Schema/Result/MarcMatcher.pm
13547µslineKoha/Schema/Result/DefaultBranchCircRule.pm
14542µslineKoha/Schema/Result/Letter.pm
15538µslineKoha/Schema/Result/ItemsLastBorrower.pm
13537µslineKoha/Schema/Result/AqordersItem.pm
13535µslineKoha/Schema/Result/AqbudgetsPlanning.pm
12533µslineKoha/Schema/Result/MarcTagStructure.pm
13531µslineKoha/Schema/Result/ClassSource.pm
12529µslineKoha/Schema/Result/Aqbudgetperiod.pm
15529µslineKoha/Schema/Result/Subscriptionroutinglist.pm
14529µslineKoha/Schema/Result/Virtualshelfshare.pm
15528µslineKoha/Schema/Result/MessageTransport.pm
12527µslineKoha/Schema/Result/NeedMergeAuthority.pm
14525µslineKoha/Schema/Result/AuthType.pm
14525µslineKoha/Schema/Result/AuthorisedValue.pm
14524µslineKoha/Schema/Result/TagAll.pm
15522µslineKoha/Schema/Result/CourseReserve.pm
12522µslineKoha/Schema/Result/PendingOfflineOperation.pm
14522µslineKoha/Schema/Result/Aqbasketuser.pm
14520µslineKoha/Schema/Result/AdditionalField.pm
14518µslineKoha/Schema/Result/Currency.pm
14517µslineKoha/Schema/Result/BranchBorrowerCircRule.pm
11515µslineKoha/Schema/Result/TmpHoldsqueue.pm
12513µslineKoha/Schema/Result/ReportsDictionary.pm
15512µslineKoha/Schema/Result/Matchcheck.pm
12510µslineKoha/Schema/Result/AuthHeader.pm
12508µslineKoha/Schema/Result/ColumnsSetting.pm
12508µslineKoha/Schema/Result/Printer.pm
14508µslineKoha/Schema/Result/Aqbasketgroup.pm
13507µslineKoha/Schema/Result/BorrowerSync.pm
12504µslineKoha/Schema/Result/Deletedbiblio.pm
13503µslineKoha/Schema/Result/ItemsSearchField.pm
14500µslineKoha/Schema/Result/TagsIndex.pm
13500µslineKoha/Schema/Result/Userflag.pm
13500µslineKoha/Schema/Result/Collection.pm
14500µslineKoha/Schema/Result/Branchcategory.pm
14497µslineKoha/Schema/Result/AdditionalFieldValue.pm
11497µslineKoha/Schema/Result/Statistic.pm
12495µslineKoha/Schema/Result/SocialData.pm
14493µslineKoha/Schema/Result/AuthorisedValuesBranch.pm
14489µslineKoha/Schema/Result/PatronList.pm
12488µslineKoha/Schema/Result/SavedReport.pm
12487µslineKoha/Schema/Result/Message.pm
14487µslineKoha/Schema/Result/AqorderUser.pm
12487µslineKoha/Schema/Result/Zebraqueue.pm
14484µslineKoha/Schema/Result/TagsApproval.pm
15484µslineClass/XSAccessor/Heavy.pm
12483µslineKoha/Schema/Result/UploadedFile.pm
14480µslineKoha/Schema/Result/OverduerulesTransportType.pm
12479µslineKoha/Schema/Result/OaiSetsMapping.pm
13478µslineKoha/Schema/Result/ImportItem.pm
13476µslineKoha/Schema/Result/CreatorImage.pm
12474µslineKoha/Schema/Result/Accountoffset.pm
13472µslineKoha/Schema/Result/CategoriesBranch.pm
13471µslineKoha/Schema/Result/Discharge.pm
13471µslineKoha/Schema/Result/DefaultBranchItemRule.pm
14470µslineKoha/Schema/Result/BorrowerMessageTransportPreference.pm
13468µslineKoha/Schema/Result/AuthTagStructure.pm
16468µslineKoha/Schema/Result/OaiSet.pm
14466µslineKoha/Schema/Result/Aqbudgetborrower.pm
12461µslineKoha/Schema/Result/LanguageRfc4646ToIso639.pm
13459µslineKoha/Schema/Result/MarcModificationTemplate.pm
12458µslineKoha/Schema/Result/SpecialHoliday.pm
13458µslineKoha/Schema/Result/Patronimage.pm
12456µslineKoha/Schema/Result/MiscFile.pm
13456µslineKoha/Schema/Result/BorrowerFile.pm
13455µslineKoha/Schema/Result/BorrowerAttribute.pm
14455µslineKoha/Schema/Result/Permission.pm
12450µslineKoha/Schema/Result/Fieldmapping.pm
12450µslineKoha/Schema/Result/SearchHistory.pm
12448µslineKoha/Schema/Result/Alert.pm
14448µslineKoha/Schema/Result/Serialitem.pm
12445µslineKoha/Schema/Result/ActionLog.pm
13443µslineKoha/Schema/Result/ClassSortRule.pm
14441µslineKoha/Schema/Result/CourseInstructor.pm
12439µslineKoha/Schema/Result/Systempreference.pm
12434µslineKoha/Schema/Result/PluginData.pm
12431µslineKoha/Schema/Result/RepeatableHoliday.pm
12429µslineKoha/Schema/Result/OaiSetsDescription.pm
12424µslineKoha/Schema/Result/ServicesThrottle.pm
12419µslineKoha/Schema/Result/MatchpointComponentNorm.pm
12418µslineKoha/Schema/Result/BiblioFramework.pm
13413µslineKoha/Schema/Result/MatcherMatchpoint.pm
12412µslineKoha/Schema/Result/ImportRecordMatch.pm
12411µslineKoha/Schema/Result/City.pm
13409µslineKoha/Schema/Result/DefaultBorrowerCircRule.pm
12404µslineKoha/Schema/Result/Tag.pm
12402µslineKoha/Schema/Result/Quote.pm
12402µslineKoha/Schema/Result/Linktracker.pm
11401µslineKoha/Schema/Result/Notify.pm
14398µslineKoha/Schema/Result/Itemtype.pm
12398µslineKoha/Schema/Result/ItemCirculationAlertPreference.pm
13397µslineKoha/Schema/Result/OaiSetsBiblio.pm
12396µslineKoha/Schema/Result/BranchTransferLimit.pm
12395µslineKoha/Schema/Result/LanguageDescription.pm
10395µslineKoha/Schema/Result/ActionLogs.pm
11391µslineKoha/Schema/Result/Browser.pm
12389µslineKoha/Schema/Result/Session.pm
187827µslineDevel/GlobalDestruction.pm (including 1 string eval)
12373µslineKoha/Schema/Result/AudioAlert.pm
12370µslineDBIx/Class/Exception.pm
14367µslineKoha/Schema/Result/BranchItemRule.pm
12364µslineKoha/Schema/Result/DefaultCircRule.pm
11363µslineKoha/Schema/Result/LanguageScriptBidi.pm
11348µslineKoha/Schema/Result/Stopword.pm
11346µslineKoha/Schema/Result/LanguageScriptMapping.pm
12326µslineKoha/Schema/Result/LanguageSubtagRegistry.pm
14300µslineKoha/Schema/Result/Branchrelation.pm
8243µslineDBIx/Class/Core.pm
5386µslineDBI.pm (including 1 string eval)
85932µslineCGI.pm (including 5 string evals)
8177µslineDBIx/Class/Relationship.pm
8168µslineDBIx/Class/Relationship/Helpers.pm
8163µslineMoo/sification.pm
7159µslineDBIx/Class/PK/Auto.pm
15159µslineDBI/Const/GetInfo/ANSI.pm
2123µslineConfig.pm
567µslineDBIx/Class/StartupCheck.pm
549µslineMoo/_mro.pm
17µslineModule/Runtime.pm
14µslineConfig_git.pl
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
1142µsline/unknown-eval-invoker (including 1 string eval)
508124116.4sTotal (-40 statements are unaccounted for)
1814758.5msAverage
575µsMedian
0.00016Deviation