Show stats about call types and block calls by Ruby #168

k0kubun · 2023-01-11T19:54:57Z

Following up #158 and #163, this PR adds the following features to the harness-stats.

Show block calls by Ruby methods as well
- Kernel#tap is in Ruby from 3.0, and Kernel#loop is also in Ruby from 3.3.
Show a breakdown of each call type like this (railsbench example):

The overall ratio of each call type:
 9540942 (55.4%) C method calls
 6470859 (37.6%) Ruby method calls
  805035 ( 4.7%) C method's block calls
  393000 ( 2.3%) Ruby method's block calls

Example

I used Ruby master (ruby/ruby@3642006).

activerecord

$ ruby -Iharness-stats benchmarks/activerecord/benchmark.rb
ruby 3.3.0dev (2023-01-11T19:12:15Z master 3642006872) [x86_64-linux]
Command: bundle check 2> /dev/null || bundle install
The Gemfile's dependencies are satisfied
Calling `DidYouMean::SPELL_CHECKERS.merge!(error_name => spell_checker)' has been deprecated. Please call `DidYouMean.correct_error(error_name, spell_checker)' instead.
-- create_table(:posts, {:force=>true})
   -> 0.0014s
itr #1: 1506ms
The overall breakdown of each call type:
  944021 (55.6%) C method calls
  670005 (39.5%) Ruby method calls
   50000 ( 2.9%) block calls from C
   34002 ( 2.0%) block calls from Ruby

Top 100 C method calls (92.4% of all 944021 calls):
   96000 (10.2%) Hash#[]
   46000 ( 4.9%) Module#===
   43000 ( 4.6%) Hash#fetch
   31000 ( 3.3%) Class#new
   25000 ( 2.6%) #<Class:Thread>#current
   25000 ( 2.6%) BasicObject#!
   24000 ( 2.5%) Hash#[]=
   22000 ( 2.3%) Arel::Collectors::Composite#left
   22000 ( 2.3%) Arel::Collectors::Composite#right
   21000 ( 2.2%) Kernel#hash
   21000 ( 2.2%) String#<<
   20000 ( 2.1%) Kernel#is_a?
   19000 ( 2.0%) Array#empty?
   18001 ( 1.9%) Array#[]
   17000 ( 1.8%) ActiveRecord::Relation#klass
   17000 ( 1.8%) Array#each
   14003 ( 1.5%) Integer#+
   14000 ( 1.5%) Thread#[]
   12000 ( 1.3%) String#to_s
   12000 ( 1.3%) Arel::Visitors::Visitor#dispatch
   12000 ( 1.3%) Hash#delete
   11000 ( 1.2%) Kernel#nil?
   10000 ( 1.1%) Array#include?
   10000 ( 1.1%) ActiveModel::Attribute#value_before_type_cast
   10000 ( 1.1%) Integer#<
   10000 ( 1.1%) ActiveModel::LazyAttributeHash#delegate_hash
    9000 ( 1.0%) Module#name
    9000 ( 1.0%) Array#any?
    9000 ( 1.0%) BasicObject#initialize
    9000 ( 1.0%) String#-@
    8000 ( 0.8%) Kernel#respond_to?
    8000 ( 0.8%) ActiveModel::Attribute#type
    7000 ( 0.7%) Class#superclass
    7000 ( 0.7%) ActiveRecord::TableMetadata#klass
    7000 ( 0.7%) String#to_i
    6000 ( 0.6%) Kernel#dup
    6000 ( 0.6%) Kernel#initialize_dup
    6000 ( 0.6%) ActiveRecord::PredicateBuilder#table
    6000 ( 0.6%) Array#map
    6000 ( 0.6%) Integer#==
    6000 ( 0.6%) Kernel#block_given?
    5000 ( 0.5%) Module#==
    5000 ( 0.5%) Hash#initialize_copy
    5000 ( 0.5%) ActiveRecord::Relation::WhereClause#predicates
    5000 ( 0.5%) Kernel#object_id
    5000 ( 0.5%) Arel::Nodes::BindParam#value
    5000 ( 0.5%) ActiveModel::AttributeSet#attributes
    5000 ( 0.5%) ActiveModel::LazyAttributeHash#additional_types
    5000 ( 0.5%) ActiveModel::LazyAttributeHash#types
    5000 ( 0.5%) ActiveModel::LazyAttributeHash#values
    4000 ( 0.4%) Kernel#freeze
    4000 ( 0.4%) Array#map!
    4000 ( 0.4%) Thread#thread_variable_get
    4000 ( 0.4%) #<Class:Process>#pid
    4000 ( 0.4%) Arel::Nodes::SelectCore#source
    4000 ( 0.4%) String#initialize
    4000 ( 0.4%) Enumerable#each_with_index
    4000 ( 0.4%) Arel::Table#table_alias
    4000 ( 0.4%) Arel::Table#name
    4000 ( 0.4%) Arel::Nodes::Binary#left
    3001 ( 0.3%) Array#<<
    3000 ( 0.3%) Kernel#initialize_clone
    3000 ( 0.3%) Symbol#to_s
    3000 ( 0.3%) Array#flatten!
    3000 ( 0.3%) Hash#each_key
    3000 ( 0.3%) #<Class:#<Class:0x00007f9e5efbb040>>#new
    3000 ( 0.3%) Struct#initialize
    3000 ( 0.3%) Array#+
    3000 ( 0.3%) ActiveRecord::Relation#table
    3000 ( 0.3%) BasicObject#!=
    3000 ( 0.3%) BasicObject#==
    3000 ( 0.3%) Arel::Nodes::SelectStatement#orders
    3000 ( 0.3%) TrueClass#===
    3000 ( 0.3%) FalseClass#===
    3000 ( 0.3%) Array#length
    3000 ( 0.3%) Kernel#public_send
    2001 ( 0.2%) Integer#>
    2000 ( 0.2%) Module#<=
    2000 ( 0.2%) Symbol#==
    2000 ( 0.2%) Hash#empty?
    2000 ( 0.2%) Enumerable#map
    2000 ( 0.2%) Hash#each
    2000 ( 0.2%) String#include?
    2000 ( 0.2%) ActiveRecord::Relation::WhereClauseFactory#predicate_builder
    2000 ( 0.2%) Enumerable#flat_map
    2000 ( 0.2%) ActiveRecord::TableMetadata#types
    2000 ( 0.2%) Array#last
    2000 ( 0.2%) ActiveRecord::Relation#loaded
    2000 ( 0.2%) Array#reject!
    2000 ( 0.2%) Arel::Nodes::SelectStatement#cores
    2000 ( 0.2%) Arel::Nodes::SelectCore#wheres
    2000 ( 0.2%) Array#concat
    2000 ( 0.2%) Arel::Nodes::SelectCore#projections
    2000 ( 0.2%) NilClass#nil?
    2000 ( 0.2%) ActiveRecord::ConnectionAdapters::AbstractAdapter#visitor
    2000 ( 0.2%) Arel::Nodes::SelectStatement#offset
    2000 ( 0.2%) Arel::Nodes::Binary#right
    2000 ( 0.2%) Integer#<=>
    2000 ( 0.2%) Arel::Nodes::Unary#expr
    2000 ( 0.2%) Array#first

Top 100 Ruby method calls (62.2% of all 670005 calls):
   26000 ( 3.9%) Kernel#class
   21000 ( 3.1%) Arel::Collectors::PlainString#<<
   19000 ( 2.8%) Arel::Collectors::Composite#<<
   19000 ( 2.8%) Arel::Collectors::Bind#<<
   12000 ( 1.8%) Arel::Visitors::Visitor#visit
    9000 ( 1.3%) ActiveSupport::PerThreadRegistry#instance
    8000 ( 1.2%) Concurrent::Map#[]
    8000 ( 1.2%) Concurrent::Collection::NonConcurrentMapBackend#[]
    7000 ( 1.0%) ActiveRecord::Scoping::ScopeRegistry#raise_invalid_scope_type!
    7000 ( 1.0%) ActiveModel::Attribute#initialize
    7000 ( 1.0%) ActiveModel::Attribute#value
    6000 ( 0.9%) ActiveRecord::ConnectionHandling#connection_specification_name
    6000 ( 0.9%) Arel::Visitors::ToSql#maybe_visit
    5000 ( 0.7%) ActiveRecord::Relation#already_in_scope?
    5000 ( 0.7%) Arel::Visitors::ToSql#collect_nodes_for
    5000 ( 0.7%) ActiveModel::Type::Value#cast
    5000 ( 0.7%) ActiveRecord::AttributeMethods::Read#_read_attribute
    5000 ( 0.7%) ActiveModel::AttributeSet#fetch_value
    5000 ( 0.7%) ActiveModel::AttributeSet#[]
    5000 ( 0.7%) ActiveModel::LazyAttributeHash#[]
    5000 ( 0.7%) ActiveModel::LazyAttributeHash#assign_default_value
    5000 ( 0.7%) #<Class:ActiveModel::Attribute>#from_database
    5000 ( 0.7%) ActiveModel::Attribute::FromDatabase#type_cast
    4000 ( 0.6%) ActiveRecord::QueryMethods#assert_mutability!
    4000 ( 0.6%) ActiveRecord::Scoping::ClassMethods#current_scope=
    4000 ( 0.6%) #<Class:ActiveRecord::Scoping::ScopeRegistry>#set_value_for
    4000 ( 0.6%) ActiveRecord::Scoping::ScopeRegistry#set_value_for
    4000 ( 0.6%) #<Class:ActiveRecord::Base>#connection_handler
    4000 ( 0.6%) #<Class:ActiveRecord::Base>#default_connection_handler
    4000 ( 0.6%) ActiveRecord::ConnectionAdapters::AbstractAdapter#prepared_statements
    4000 ( 0.6%) ActiveRecord::ConnectionAdapters::AbstractAdapter#prepared_statements_disabled_cache
    4000 ( 0.6%) Set#include?
    4000 ( 0.6%) Arel::Visitors::ToSql#quote_table_name
    4000 ( 0.6%) ActiveRecord::ConnectionAdapters::SQLite3::Quoting#quote_table_name
    4000 ( 0.6%) #<Class:ActiveRecord::ConnectionAdapters::AbstractAdapter>#quoted_table_names
    4000 ( 0.6%) ActiveModel::Type::Value#deserialize
    3000 ( 0.4%) ActiveRecord::Scoping::ClassMethods#current_scope
    3000 ( 0.4%) #<Class:ActiveRecord::Scoping::ScopeRegistry>#value_for
    3000 ( 0.4%) ActiveRecord::Scoping::ScopeRegistry#value_for
    3000 ( 0.4%) ActiveRecord::SpawnMethods#spawn
    3000 ( 0.4%) Kernel#clone
    3000 ( 0.4%) ActiveRecord::Relation#initialize_copy
    3000 ( 0.4%) ActiveRecord::Relation#reset
    3000 ( 0.4%) ActiveRecord::QueryMethods#where_clause
    3000 ( 0.4%) #<Class:ActiveRecord::Base>#attribute_aliases
    3000 ( 0.4%) Arel::Table#[]
    3000 ( 0.4%) ActiveRecord::ModelSchema::ClassMethods#attribute_types
    3000 ( 0.4%) ActiveRecord::ModelSchema::ClassMethods#load_schema
    3000 ( 0.4%) ActiveRecord::ModelSchema::ClassMethods#schema_loaded?
    3000 ( 0.4%) ActiveRecord::QueryMethods#order_values
    3000 ( 0.4%) ActiveRecord::AttributeMethods::PrimaryKey::ClassMethods#primary_key
    3000 ( 0.4%) Object#blank?
    3000 ( 0.4%) ActiveRecord::ConnectionHandling#connection
    3000 ( 0.4%) ActiveRecord::ConnectionHandling#retrieve_connection
    3000 ( 0.4%) ActiveRecord::ConnectionAdapters::ConnectionHandler#retrieve_connection
    3000 ( 0.4%) ActiveRecord::ConnectionAdapters::ConnectionHandler#retrieve_connection_pool
    3000 ( 0.4%) ActiveRecord::ConnectionAdapters::ConnectionHandler#owner_to_pool
    3000 ( 0.4%) Concurrent::Map#fetch
    3000 ( 0.4%) Concurrent::Collection::NonConcurrentMapBackend#get_or_default
    3000 ( 0.4%) ActiveRecord::ConnectionAdapters::ConnectionPool#connection
    3000 ( 0.4%) ActiveRecord::ConnectionAdapters::ConnectionPool#current_thread
    3000 ( 0.4%) ActiveRecord::ConnectionAdapters::ConnectionPool#connection_cache_key
    3000 ( 0.4%) ActiveRecord::QueryMethods#limit_value
    3000 ( 0.4%) ActiveRecord::QueryMethods#includes_values
    3000 ( 0.4%) Arel::Visitors::ToSql#inject_join
    3000 ( 0.4%) Arel::Visitors::ToSql#visit_Arel_Attributes_Attribute
    3000 ( 0.4%) Arel::Visitors::ToSql#quote_column_name
    3000 ( 0.4%) ActiveModel::Type::String#cast_value
    2000 ( 0.3%) #<Class:Post(id: integer, title: string, body: string, type_name: string, key: string, upvotes: integer, author_id: integer, created_at: datetime, updated_at: datetime)>#default_scope_override
    2000 ( 0.3%) ActiveRecord::Core::ClassMethods#arel_attribute
    2000 ( 0.3%) ActiveRecord::TableMetadata#type
    2000 ( 0.3%) ActiveRecord::ModelSchema::ClassMethods#type_for_attribute
    2000 ( 0.3%) Arel::Nodes::BindParam#initialize
    2000 ( 0.3%) Arel::Nodes::Binary#initialize
    2000 ( 0.3%) ActiveRecord::Relation::WhereClause#initialize
    2000 ( 0.3%) ActiveRecord::QueryMethods#offset_value
    2000 ( 0.3%) Post::GeneratedRelationMethods#implicit_order_column
    2000 ( 0.3%) ActiveRecord::Relation#scoping
    2000 ( 0.3%) ActiveRecord::Relation#_scoping
    2000 ( 0.3%) #<Class:ActiveRecord::Base>#implicit_order_column
    2000 ( 0.3%) ActiveRecord::Delegation#primary_key
    2000 ( 0.3%) Arel::Nodes::Unary#initialize
    2000 ( 0.3%) ActiveRecord::Delegation#connection
    2000 ( 0.3%) ActiveRecord::Relation#eager_loading?
    2000 ( 0.3%) ActiveRecord::QueryMethods#eager_load_values
    2000 ( 0.3%) ActiveRecord::Relation::WhereClause#empty?
    2000 ( 0.3%) ActiveRecord::ModelSchema::ClassMethods#ignored_columns
    2000 ( 0.3%) ActiveRecord::Relation::QueryAttribute#value_for_database
    2000 ( 0.3%) ActiveModel::Attribute#value_for_database
    2000 ( 0.3%) Integer#to_i
    2000 ( 0.3%) ActiveRecord::ConnectionAdapters::SQLite3::Quoting#quote_column_name
    2000 ( 0.3%) #<Class:ActiveRecord::ConnectionAdapters::AbstractAdapter>#quoted_column_names
    2000 ( 0.3%) Arel::Visitors::ToSql#visit_Arel_Nodes_BindParam
    2000 ( 0.3%) Arel::Collectors::Composite#add_bind
    2000 ( 0.3%) Arel::Collectors::SQLString#add_bind
    2000 ( 0.3%) Arel::Collectors::Bind#add_bind
    2000 ( 0.3%) ActiveRecord::ConnectionAdapters::Quoting#type_cast
    2000 ( 0.3%) ActiveRecord::ConnectionAdapters::SQLite3::Quoting#_type_cast
    2000 ( 0.3%) ActiveRecord::ConnectionAdapters::Quoting#_type_cast
    2000 ( 0.3%) ActiveSupport::Notifications::Instrumenter#instrument

Top 9 block calls from C (100.0% of all 50000 calls):
   22000 (44.0%) Array#each
   10000 (20.0%) Hash#each_key
    6000 (12.0%) Array#map
    4000 ( 8.0%) #<Class:Thread>#handle_interrupt
    2000 ( 4.0%) Hash#each
    2000 ( 4.0%) Array#map!
    2000 ( 4.0%) Monitor#synchronize
    1000 ( 2.0%) Integer#upto
    1000 ( 2.0%) Hash#select

Top 18 block calls from Ruby (100.0% of all 34002 calls):
    4000 (11.8%) ActiveRecord::Relation#_scoping
    4000 (11.8%) #<Class:ActiveRecord::Base>#default_connection_handler
    3000 ( 8.8%) #<Class:ActiveRecord::Base>#attribute_aliases
    2000 ( 5.9%) #<Class:Post(id: integer, title: string, body: string, type_name: string, key: string, upvotes: integer, author_id: integer, created_at: datetime, updated_at: datetime)>#default_scope_override
    2000 ( 5.9%) #<Class:ActiveRecord::Base>#implicit_order_column
    2000 ( 5.9%) Arel::Collectors::SQLString#add_bind
    2000 ( 5.9%) ActiveSupport::Notifications::Instrumenter#instrument
    2000 ( 5.9%) ActiveSupport::Concurrency::ShareLock#yield_shares
    2000 ( 5.9%) Kernel#loop
    2000 ( 5.9%) MonitorMixin::ConditionVariable#wait_while
    2000 ( 5.9%) ActiveRecord::Base#__callbacks
    2000 ( 5.9%) #<Class:ActiveRecord::Base>#__callbacks
    1000 ( 2.9%) #<Class:ActiveRecord::Base>#default_scopes
    1000 ( 2.9%) #<Class:ActiveRecord::Base>#_reflections
    1000 ( 2.9%) #<Class:ActiveRecord::Base>#aggregate_reflections
    1000 ( 2.9%) ActiveRecord::Relation#skip_query_cache_if_necessary
    1000 ( 2.9%) #<ActiveModel::Type::Helpers::AcceptsMultiparameterTime:0x00007f9e5eb71ae8>#cast
       2 ( 0.0%) #<Class:Benchmark>#realtime

hexapdf

$ ruby -Iharness-stats benchmarks/hexapdf/benchmark.rb
ruby 3.3.0dev (2023-01-11T19:12:15Z master 3642006872) [x86_64-linux]
Command: bundle check 2> /dev/null || bundle install
The Gemfile's dependencies are satisfied
Calling `DidYouMean::SPELL_CHECKERS.merge!(error_name => spell_checker)' has been deprecated. Please call `DidYouMean.correct_error(error_name, spell_checker)' instead.
itr #1: 50330ms
The overall breakdown of each call type:
45292659 (73.1%) C method calls
12839712 (20.7%) Ruby method calls
 3531428 ( 5.7%) block calls from C
  283387 ( 0.5%) block calls from Ruby

Top 100 C method calls (97.9% of all 45292659 calls):
 3239193 ( 7.2%) Hash#[]
 2491687 ( 5.5%) HexaPDF::Layout::TextFragment#style
 2377855 ( 5.2%) Kernel#kind_of?
 2254003 ( 5.0%) Integer#+
 1703849 ( 3.8%) Array#[]
 1514209 ( 3.3%) HexaPDF::Layout::TextFragment#items
 1421062 ( 3.1%) Kernel#hash
 1156732 ( 2.6%) Float#+
 1142618 ( 2.5%) Integer#-
 1127352 ( 2.5%) BasicObject#!
 1115692 ( 2.5%) Array#<<
 1082043 ( 2.4%) Geom2D::Point#x
  964984 ( 2.1%) Symbol#==
  932422 ( 2.1%) HexaPDF::Font::Type1Wrapper::Glyph#str
  822355 ( 1.8%) String#<<
  795689 ( 1.8%) Geom2D::Algorithms::PolygonOperation::SweepEvent#point
  793227 ( 1.8%) Geom2D::Point#y
  766402 ( 1.7%) Float#-
  697434 ( 1.5%) Regexp#match?
  677772 ( 1.5%) Integer#*
  661586 ( 1.5%) Array#size
  659622 ( 1.5%) HexaPDF::Content::Canvas#graphics_object
  626447 ( 1.4%) HexaPDF::Font::Type1Wrapper::Glyph#name
  613424 ( 1.4%) BasicObject#==
  607921 ( 1.3%) Integer#==
  601079 ( 1.3%) Class#new
  528014 ( 1.2%) #<Class:Geom2D::Utils>#precision
  525446 ( 1.2%) Float#<=
  507552 ( 1.1%) Integer#<
  478456 ( 1.1%) Array#empty?
  471727 ( 1.0%) BasicObject#!=
  450788 ( 1.0%) HexaPDF::Content::Canvas#graphics_state
  433155 ( 1.0%) Array#each
  425182 ( 0.9%) Float#<
  361961 ( 0.8%) Array#clear
  356875 ( 0.8%) HexaPDF::Object#data
  336821 ( 0.7%) HexaPDF::Layout::TextLayouter::Box#item
  303578 ( 0.7%) Kernel#instance_of?
  287243 ( 0.6%) HexaPDF::PDFData#value
  268250 ( 0.6%) Array#sum
  252165 ( 0.6%) Array#last
  248834 ( 0.5%) Kernel#dup
  248834 ( 0.5%) Kernel#initialize_dup
  243620 ( 0.5%) Float#>
  236755 ( 0.5%) Geom2D::Algorithms::PolygonOperation::SweepEvent#other_event
  234718 ( 0.5%) HexaPDF::Layout::TextLayouter#style
  224138 ( 0.5%) Kernel#nil?
  220463 ( 0.5%) Geom2D::Segment#start_point
  217254 ( 0.5%) HexaPDF::Layout::Line#x_offset=
  217254 ( 0.5%) HexaPDF::Layout::Line#x_offset
  217254 ( 0.5%) HexaPDF::Content::GraphicsState#tlm
  217167 ( 0.5%) Array#concat
  204662 ( 0.5%) Integer#/
  192485 ( 0.4%) Integer#>
  190881 ( 0.4%) Array#[]=
  190377 ( 0.4%) Geom2D::Algorithms::PolygonOperation::SweepEvent#left
  187015 ( 0.4%) Array#push
  165143 ( 0.4%) Geom2D::Segment#end_point
  163640 ( 0.4%) Float#*
  159104 ( 0.4%) Integer#<=>
  146464 ( 0.3%) HexaPDF::Layout::Line#y_offset
  144836 ( 0.3%) HexaPDF::Content::GraphicsState#leading
  144835 ( 0.3%) HexaPDF::Layout::Style::LineSpacing#type
  144835 ( 0.3%) HexaPDF::Layout::Style::LineSpacing#value
  140717 ( 0.3%) Array#length
  139550 ( 0.3%) Float#/
  137518 ( 0.3%) Float#<=>
  135569 ( 0.3%) String#==
  135303 ( 0.3%) Kernel#freeze
  134370 ( 0.3%) Module#===
  126941 ( 0.3%) Integer#>=
  125101 ( 0.3%) Kernel#initialize_copy
  124974 ( 0.3%) HexaPDF::Layout::TextLayouter::Glue#item
  124466 ( 0.3%) Geom2D::Algorithms::PolygonOperation::SweepEvent#polygon_type
   99694 ( 0.2%) NilClass#nil?
   94196 ( 0.2%) HexaPDF::Layout::Line#items
   74046 ( 0.2%) Enumerable#each_with_index
   74046 ( 0.2%) HexaPDF::Content::GraphicsState#tm=
   73232 ( 0.2%) HexaPDF::Layout::Line#y_offset=
   72670 ( 0.2%) String#initialize_copy
   72625 ( 0.2%) String#b
   72418 ( 0.2%) HexaPDF::Content::TransformationMatrix#e
   72418 ( 0.2%) HexaPDF::Content::TransformationMatrix#f
   72201 ( 0.2%) Hash#[]=
   71604 ( 0.2%) HexaPDF::Content::Canvas#operators
   71027 ( 0.2%) String#gsub!
   70971 ( 0.2%) String#encoding
   66479 ( 0.1%) Hash#key?
   65592 ( 0.1%) Integer#<=
   51058 ( 0.1%) Array#initialize_copy
   51055 ( 0.1%) HexaPDF::Layout::TextFragment#items=
   47287 ( 0.1%) Kernel#block_given?
   47132 ( 0.1%) String#getbyte
   45558 ( 0.1%) Geom2D::BoundingBox#max_x
   45556 ( 0.1%) Geom2D::Algorithms::PolygonOperation::SweepEvent#edge_type
   44746 ( 0.1%) Geom2D::Algorithms::PolygonOperation::SweepEvent#other_in_out
   43932 ( 0.1%) Array#bsearch_index
   43932 ( 0.1%) Array#insert
   39295 ( 0.1%) HexaPDF::PDFData#oid
   35958 ( 0.1%) HexaPDF::Font::InvalidGlyph#str

Top 100 Ruby method calls (93.6% of all 12839712 calls):
 1062997 ( 8.3%) HexaPDF::Layout::Style#scaled_item_width
  857707 ( 6.7%) HexaPDF::Layout::TextFragment#width
  626061 ( 4.9%) HexaPDF::Font::Type1Wrapper#encode
  423085 ( 3.3%) Float#abs
  322996 ( 2.5%) Integer#abs
  308867 ( 2.4%) HexaPDF::Layout::TextLayouter::Box#type
  302472 ( 2.4%) Kernel#class
  291260 ( 2.3%) Geom2D::Utils#float_compare
  262733 ( 2.0%) HexaPDF::Object#value
  223130 ( 1.7%) HexaPDF::Content::Canvas#raise_unless_at_page_description_level_or_in_text
  215804 ( 1.7%) HexaPDF::Content::Canvas#begin_text
  214722 ( 1.7%) HexaPDF::Layout::Line#add
  211534 ( 1.6%) Geom2D::Utils#float_equal
  200076 ( 1.6%) HexaPDF::Layout::TextLayouter::SimpleLineWrapping#add_box_item
  149717 ( 1.2%) HexaPDF::Layout::Line#y_max
  148814 ( 1.2%) HexaPDF::Layout::TextFragment#y_min
  148814 ( 1.2%) HexaPDF::Layout::Style#scaled_y_min
  148814 ( 1.2%) HexaPDF::Layout::TextFragment#y_max
  148814 ( 1.2%) HexaPDF::Layout::Style#scaled_y_max
  148089 ( 1.2%) HexaPDF::Layout::Line#y_min
  146462 ( 1.1%) HexaPDF::Layout::TextFragment#valign
  144836 ( 1.1%) HexaPDF::Layout::Style#line_spacing
  144812 ( 1.1%) #<Class:Geom2D>#Point
  143988 ( 1.1%) HexaPDF::Layout::TextFragment#initialize
  142499 ( 1.1%) HexaPDF::Layout::TextLayouter::Box#initialize
  139134 ( 1.1%) HexaPDF::Layout::TextLayouter::Glue#type
  139088 ( 1.1%) Kernel#frozen?
  139088 ( 1.1%) HexaPDF::Layout::TextFragment#clear_cache
  125638 ( 1.0%) HexaPDF::Layout::TextLayouter::Box#width
  125638 ( 1.0%) HexaPDF::Layout::TextLayouter::Box#height
  125638 ( 1.0%) HexaPDF::Layout::TextFragment#height
  124974 ( 1.0%) HexaPDF::Layout::TextLayouter::SimpleLineWrapping#add_glue_item
  118699 ( 0.9%) HexaPDF::Layout::TextLayouter::SimpleLineWrapping#update_last_breakpoint
  100887 ( 0.8%) Geom2D::Algorithms::PolygonOperation::SweepEvent#process_after?
   93567 ( 0.7%) Geom2D::Point#==
   81711 ( 0.6%) HexaPDF::DictionaryFields::Field#required?
   79300 ( 0.6%) HexaPDF::Dictionary#key?
   77288 ( 0.6%) #<Class:Geom2D::Algorithms>#ccw
   77147 ( 0.6%) HexaPDF::Layout::TextLayouter::SimpleLineWrapping#create_line
   77147 ( 0.6%) HexaPDF::Layout::Line#initialize
   76409 ( 0.6%) HexaPDF::Layout::Line::HeightCalculator#initialize
   76409 ( 0.6%) HexaPDF::Layout::Line::HeightCalculator#reset
   74860 ( 0.6%) HexaPDF::Content::Canvas#invoke0
   74860 ( 0.6%) HexaPDF::Content::Operator::NoArgumentOperator#serialize
   74044 ( 0.6%) HexaPDF::Layout::TextLayouter#create_combined_line
   74044 ( 0.6%) HexaPDF::Layout::Line#height
   74044 ( 0.6%) HexaPDF::Layout::Line#calculate_y_dimensions
   74044 ( 0.6%) HexaPDF::Layout::Line::HeightCalculator#add
   74044 ( 0.6%) HexaPDF::Layout::Line::HeightCalculator#result
   73232 ( 0.6%) HexaPDF::Layout::Line#width
   73232 ( 0.6%) HexaPDF::Layout::Style#underlays?
   73232 ( 0.6%) HexaPDF::Layout::Style#overlays?
   73231 ( 0.6%) HexaPDF::Layout::Style::LineSpacing#gap
   72418 ( 0.6%) HexaPDF::Layout::TextLayouter#apply_offsets
   72418 ( 0.6%) HexaPDF::Layout::TextLayouter#horizontal_alignment_offset
   72418 ( 0.6%) HexaPDF::Layout::Style#align
   72418 ( 0.6%) HexaPDF::Layout::TextLayouter::SimpleLineWrapping#reset_after_line_break
   72418 ( 0.6%) HexaPDF::Layout::Line#each
   72418 ( 0.6%) HexaPDF::Layout::TextFragment#draw
   72418 ( 0.6%) HexaPDF::Content::Canvas#move_text_cursor
   72418 ( 0.6%) HexaPDF::Content::Operator::MoveText#invoke
   72418 ( 0.6%) HexaPDF::Content::TransformationMatrix#translate
   72418 ( 0.6%) HexaPDF::Content::Canvas#show_glyphs_only
   72418 ( 0.6%) HexaPDF::Layout::Style#underline?
   72418 ( 0.6%) HexaPDF::Layout::Style#strikeout?
   71604 ( 0.6%) HexaPDF::Layout::Style::LineSpacing#baseline_distance
   71604 ( 0.6%) HexaPDF::Content::Operator::MoveTextNextLine#invoke
   71604 ( 0.6%) Float#-@
   70971 ( 0.6%) HexaPDF::Serializer#serialize_string
   70968 ( 0.6%) HexaPDF::Content::Canvas#raise_unless_font_set
   70968 ( 0.6%) HexaPDF::Content::Canvas#serialize1
   70968 ( 0.6%) HexaPDF::Content::Operator::ShowText#serialize
   62640 ( 0.5%) Geom2D::Segment#initialize
   48214 ( 0.4%) #<Class:HexaPDF::Dictionary>#field
   45556 ( 0.4%) Geom2D::Algorithms::PolygonOperation::SweepEvent#in_result?
   44113 ( 0.3%) HexaPDF::Object#document
   43932 ( 0.3%) Geom2D::Utils::SortedList#insert
   43158 ( 0.3%) HexaPDF::PDFArray#process_entry
   42344 ( 0.3%) HexaPDF::PDFArray#[]
   39295 ( 0.3%) HexaPDF::Object#oid
   37737 ( 0.3%) HexaPDF::Layout::TextLayouter::Penalty#type
   37528 ( 0.3%) HexaPDF::DictionaryFields::Field#type
   34278 ( 0.3%) HexaPDF::Dictionary#[]
   34170 ( 0.3%) Geom2D::Algorithms::PolygonOperation::SweepEvent#segment
   34170 ( 0.3%) #<Class:Geom2D>#Segment
   30915 ( 0.2%) Geom2D::Utils::SortedList#empty?
   30208 ( 0.2%) HexaPDF::DictionaryFields::Field#convert
   29288 ( 0.2%) Geom2D::Algorithms::PolygonOperation::SweepEvent#initialize
   29288 ( 0.2%) Geom2D::Utils::SortedList#push
   29288 ( 0.2%) Geom2D::Utils::SortedList#last
   29288 ( 0.2%) Geom2D::Utils::SortedList#pop
   27664 ( 0.2%) Geom2D::Point#initialize
   26034 ( 0.2%) Geom2D::Algorithms::PolygonOperation::SweepEvent#vertical?
   26032 ( 0.2%) Geom2D::Algorithms::PolygonOperation#compute_event_fields
   21967 ( 0.2%) Geom2D::Algorithms::PolygonOperation::SweepEvent#below?
   21483 ( 0.2%) HexaPDF::Serializer#__serialize
   20414 ( 0.2%) HexaPDF::Object#indirect?
   20339 ( 0.2%) Geom2D::Algorithms::PolygonOperation::SweepEvent#segment_below?
   17934 ( 0.1%) #<Class:HexaPDF::DictionaryFields::DictionaryConverter>#convert
   17899 ( 0.1%) Geom2D::Algorithms::PolygonOperation::SweepEvent#above?

Top 29 block calls from C (100.0% of all 3531428 calls):
 1343368 (38.0%) Array#each
 1062997 (30.1%) Array#sum
  697504 (19.8%) Array#map!
  209898 ( 5.9%) Array#bsearch_index
   81798 ( 2.3%) Hash#each
   39090 ( 1.1%) Array#each_index
   29288 ( 0.8%) Array#select
   12208 ( 0.3%) Array#sort!
   11480 ( 0.3%) Integer#downto
    9818 ( 0.3%) Hash#each_key
    9029 ( 0.3%) Array#map
    6539 ( 0.2%) Hash#each_value
    5726 ( 0.2%) Array#any?
    3281 ( 0.1%) Fiber#resume
    2388 ( 0.1%) Integer#times
    1628 ( 0.0%) Array#reject!
    1627 ( 0.0%) Array#select!
    1626 ( 0.0%) Array#rindex
     840 ( 0.0%) Hash#[]
     820 ( 0.0%) Array#delete_if
     224 ( 0.0%) Range#each
     223 ( 0.0%) Integer#upto
      14 ( 0.0%) Module#module_eval
       5 ( 0.0%) Hash#delete
       4 ( 0.0%) #<Class:IO>#open
       2 ( 0.0%) Hash#transform_values
       1 ( 0.0%) Module#refine
       1 ( 0.0%) #<Class:Struct>#new
       1 ( 0.0%) Enumerable#reverse_each

Top 14 block calls from Ruby (100.0% of all 283387 calls):
  200420 (70.7%) HexaPDF::Layout::TextLayouter::SimpleLineWrapping#fixed_width_wrapping
   72418 (25.6%) HexaPDF::Layout::TextLayouter::SimpleLineWrapping#reset_after_line_break
    3254 ( 1.1%) Geom2D::Polygon#each_segment
    2442 ( 0.9%) HexaPDF::Type::Resources#perform_validation
    2365 ( 0.8%) HexaPDF::Layout::TextLayouter::SimpleLineWrapping#initialize
     814 ( 0.3%) HexaPDF::Type::Page#canvas
     814 ( 0.3%) Kernel#tap
     814 ( 0.3%) HexaPDF::Content::Canvas#save_graphics_state
      30 ( 0.0%) HexaPDF::Font::Type1::AFMParser#each_line
      11 ( 0.0%) HexaPDF::Object#perform_validation
       2 ( 0.0%) #<Class:Benchmark>#realtime
       1 ( 0.0%) HexaPDF::Font::Type1Wrapper#glyph
       1 ( 0.0%) HexaPDF::Type::PageTreeNode#perform_validation
       1 ( 0.0%) HexaPDF::XRefSection#each_subsection

liquid-render

$ ruby -Iharness-stats benchmarks/liquid-render/benchmark.rb
ruby 3.3.0dev (2023-01-11T19:12:15Z master 3642006872) [x86_64-linux]
itr #1: 2236ms
The overall breakdown of each call type:
 1621330 (60.5%) C method calls
  915981 (34.2%) Ruby method calls
  131709 ( 4.9%) block calls from C
    8902 ( 0.3%) block calls from Ruby

Top 100 C method calls (99.9% of all 1621330 calls):
  241047 (14.9%) Kernel#respond_to?
  143341 ( 8.8%) Array#[]
  114997 ( 7.1%) Kernel#is_a?
  103104 ( 6.4%) Hash#[]
   98563 ( 6.1%) Integer#+
   88440 ( 5.5%) Liquid::Context#resource_limits
   81667 ( 5.0%) Kernel#instance_of?
   71220 ( 4.4%) BasicObject#!
   70660 ( 4.4%) String#<<
   61177 ( 3.8%) Hash#key?
   58861 ( 3.6%) NilClass#nil?
   57206 ( 3.5%) Array#each
   48640 ( 3.0%) String#to_s
   46361 ( 2.9%) Kernel#nil?
   42700 ( 2.6%) Array#empty?
   33860 ( 2.1%) Array#each_index
   33580 ( 2.1%) Array#find_index
   30060 ( 1.9%) Liquid::Context#global_filter
   19707 ( 1.2%) Array#map
   11960 ( 0.7%) Array#length
   11880 ( 0.7%) Integer#==
    8158 ( 0.5%) Hash#[]=
    7760 ( 0.5%) Module#===
    7200 ( 0.4%) BasicObject#!=
    6100 ( 0.4%) Liquid::Context#registers
    5340 ( 0.3%) Integer#to_s
    4700 ( 0.3%) Liquid::Condition#left
    4700 ( 0.3%) Liquid::Condition#right
    4700 ( 0.3%) Liquid::Condition#operator
    4700 ( 0.3%) Liquid::Condition#child_relation
    4500 ( 0.3%) Integer#<<
    4500 ( 0.3%) Integer#&
    4080 ( 0.3%) Liquid::Context#strict_variables
    3825 ( 0.2%) Class#new
    3620 ( 0.2%) Integer#<=
    3360 ( 0.2%) Integer#/
    3360 ( 0.2%) Kernel#format
    3161 ( 0.2%) Integer#>
    3160 ( 0.2%) String#==
    3101 ( 0.2%) Array#<<
    2760 ( 0.2%) Array#shift
    2517 ( 0.2%) Array#last
    2480 ( 0.2%) #<Class:Regexp>#last_match
    2400 ( 0.1%) Kernel#freeze
    2400 ( 0.1%) Liquid::Context#exception_renderer=
    2380 ( 0.1%) BasicObject#==
    2340 ( 0.1%) Liquid::Condition#attachment
    2300 ( 0.1%) Array#first
    1660 ( 0.1%) String#=~
    1640 ( 0.1%) Regexp#===
    1640 ( 0.1%) String#length
    1521 ( 0.1%) Array#size
    1500 ( 0.1%) CGI::Escape#escapeHTML
    1460 ( 0.1%) String#+@
    1460 ( 0.1%) Integer#-
    1340 ( 0.1%) Liquid::Drop#context=
    1280 ( 0.1%) Array#unshift
    1280 ( 0.1%) Liquid::Context#base_scope_depth
    1280 ( 0.1%) Integer#<
    1220 ( 0.1%) String#gsub!
    1200 ( 0.1%) Array#flatten!
    1200 ( 0.1%) Enumerable#flat_map
    1200 ( 0.1%) #<Class:Liquid::Template>#default_exception_renderer
    1200 ( 0.1%) Hash#each_key
    1200 ( 0.1%) Liquid::Context#errors
    1120 ( 0.1%) Array#hash
    1120 ( 0.1%) String#empty?
    1119 ( 0.1%) Array#eql?
    1100 ( 0.1%) Array#push
    1100 ( 0.1%) Array#pop
     900 ( 0.1%) String#concat
     860 ( 0.1%) String#gsub
     800 ( 0.0%) String#===
     780 ( 0.0%) String#bytesize
     720 ( 0.0%) Regexp#match
     720 ( 0.0%) Liquid::Context#strict_filters
     700 ( 0.0%) String#[]
     561 ( 0.0%) Array#join
     560 ( 0.0%) String#downcase
     560 ( 0.0%) Time#strftime
     480 ( 0.0%) MatchData#begin
     480 ( 0.0%) MatchData#end
     480 ( 0.0%) String#[]=
     460 ( 0.0%) String#split
     280 ( 0.0%) NilClass#===
     280 ( 0.0%) String#strip
     280 ( 0.0%) String#scan
     280 ( 0.0%) Integer#to_f
     240 ( 0.0%) Kernel#block_given?
     240 ( 0.0%) #<Class:Date>#_parse
     240 ( 0.0%) Time#getlocal
     240 ( 0.0%) #<Class:Time>#local
     201 ( 0.0%) Integer#>=
     141 ( 0.0%) Integer#*
     140 ( 0.0%) Array#include?
     140 ( 0.0%) Float#/
     140 ( 0.0%) Float#ceil
     140 ( 0.0%) String#+
     140 ( 0.0%) Array#reverse!
     120 ( 0.0%) Kernel#dup

Top 100 Ruby method calls (99.8% of all 915981 calls):
  115120 (12.6%) Liquid::Context#evaluate
   80520 ( 8.8%) Liquid::ResourceLimits#increment_write_score
   71457 ( 7.8%) Liquid::Context#lookup_and_evaluate
   40280 ( 4.4%) Liquid::Context#interrupt?
   37420 ( 4.1%) Liquid::BlockBody#render_node
   37420 ( 4.1%) #<Class:Liquid::BlockBody>#render_node
   33580 ( 3.7%) Liquid::VariableLookup#evaluate
   33580 ( 3.7%) Liquid::Context#find_variable
   32800 ( 3.6%) String#to_liquid
   30060 ( 3.3%) Liquid::Variable#render
   30060 ( 3.3%) Liquid::Context#apply_global_filter
   30000 ( 3.3%) Liquid::Variable#render_to_output_buffer
   26300 ( 2.9%) Hash#to_liquid
   23247 ( 2.5%) Kernel#class
   21047 ( 2.3%) Set#include?
   19713 ( 2.2%) #<Class:Liquid::StrainerTemplate>#filter_methods
   19707 ( 2.2%) #<Class:Liquid::StrainerTemplate>#invokable?
   19700 ( 2.2%) Liquid::Variable#evaluate_filter_expressions
   19700 ( 2.2%) Liquid::Context#invoke
   19700 ( 2.2%) Liquid::Context#strainer
   19700 ( 2.2%) Liquid::StrainerTemplate#invoke
   14080 ( 1.5%) Liquid::Context#try_variable_find_in_environments
   11240 ( 1.2%) Numeric#to_liquid
    6660 ( 0.7%) Liquid::BlockBody#render_to_output_buffer
    6660 ( 0.7%) Kernel#frozen?
    6660 ( 0.7%) Liquid::ResourceLimits#increment_render_score
    4900 ( 0.5%) NilClass#to_liquid
    4700 ( 0.5%) Liquid::If#render_to_output_buffer
    4700 ( 0.5%) Liquid::Condition#evaluate
    4700 ( 0.5%) Kernel#loop
    4700 ( 0.5%) Liquid::Condition#interpret_condition
    4260 ( 0.5%) Liquid::Context#[]=
    2860 ( 0.3%) Liquid::ForloopDrop#increment!
    2640 ( 0.3%) ShopFilter#asset_url
    2460 ( 0.3%) #<Class:Liquid::Utils>#slice_collection
    2460 ( 0.3%) #<Class:Liquid::Utils>#slice_collection_using_each
    2420 ( 0.3%) Liquid::For#render_to_output_buffer
    2420 ( 0.3%) Liquid::For#collection_segment
    2400 ( 0.3%) ShopFilter#script_tag
    2200 ( 0.2%) #<Class:Liquid::Condition>#operators
    1860 ( 0.2%) MoneyFilter#money
    1720 ( 0.2%) Liquid::Condition#equal_variables
    1700 ( 0.2%) Array#to_liquid
    1660 ( 0.2%) ShopFilter#stylesheet_tag
    1500 ( 0.2%) #<Class:Liquid::Utils>#to_integer
    1500 ( 0.2%) Liquid::StandardFilters#escape
    1500 ( 0.2%) MoneyFilter#money_with_currency
    1340 ( 0.1%) ShopFilter#global_asset_url
    1340 ( 0.1%) Liquid::Drop#to_liquid
    1340 ( 0.1%) Liquid::Drop#key?
    1340 ( 0.1%) Liquid::Drop#invoke_drop
    1340 ( 0.1%) #<Class:Liquid::Drop>#invokable?
    1340 ( 0.1%) #<Class:Liquid::Drop>#invokable_methods
    1340 ( 0.1%) Liquid::ForloopDrop#first
    1320 ( 0.1%) Liquid::For#render_else
    1280 ( 0.1%) Liquid::Context#stack
    1280 ( 0.1%) Liquid::Context#push
    1280 ( 0.1%) Liquid::Context#check_overflow
    1280 ( 0.1%) Liquid::Context#overflow?
    1280 ( 0.1%) Liquid::Context#pop
    1240 ( 0.1%) ShopFilter#product_img_url
    1200 ( 0.1%) Liquid::Template#render!
    1200 ( 0.1%) Liquid::Template#render
    1200 ( 0.1%) Liquid::Template#assigns
    1200 ( 0.1%) Liquid::Template#instance_assigns
    1200 ( 0.1%) Liquid::Template#registers
    1200 ( 0.1%) Liquid::Context#initialize
    1200 ( 0.1%) Liquid::Context#squash_instance_assigns_with_environments
    1200 ( 0.1%) Liquid::ResourceLimits#reset
    1200 ( 0.1%) Liquid::Template#with_profiling
    1200 ( 0.1%) Liquid::Document#render_to_output_buffer
    1180 ( 0.1%) ShopFilter#pluralize
    1120 ( 0.1%) Liquid::StrainerFactory#create
    1120 ( 0.1%) Liquid::StrainerFactory#strainer_from_cache
    1120 ( 0.1%) Liquid::StrainerFactory#strainer_class_cache
    1120 ( 0.1%) Liquid::StrainerTemplate#initialize
    1100 ( 0.1%) Liquid::For#render_segment
    1100 ( 0.1%) Liquid::ForloopDrop#initialize
    1040 ( 0.1%) FalseClass#to_liquid
     900 ( 0.1%) Liquid::Assign#assign_score_of
     860 ( 0.1%) Liquid::StandardFilters#strip_html
     820 ( 0.1%) Liquid::StandardFilters#truncate
     600 ( 0.1%) ShopFilter#shopify_asset_url
     560 ( 0.1%) Liquid::StandardFilters#date
     560 ( 0.1%) #<Class:Liquid::Utils>#to_date
     560 ( 0.1%) #<Class:Time>#now
     540 ( 0.1%) TrueClass#to_liquid
     460 ( 0.1%) Liquid::StandardFilters#truncatewords
     340 ( 0.0%) Liquid::ElseCondition#evaluate
     280 ( 0.0%) Liquid::Context#[]
     280 ( 0.0%) #<Class:Liquid::Expression>#parse
     280 ( 0.0%) #<Class:Liquid::VariableLookup>#parse
     280 ( 0.0%) Liquid::VariableLookup#initialize
     260 ( 0.0%) Liquid::Tag#render_to_output_buffer
     260 ( 0.0%) Liquid::Block#render
     260 ( 0.0%) Liquid::BlockBody#render
     240 ( 0.0%) #<Class:Time>#parse
     240 ( 0.0%) #<Class:Time>#make_time
     240 ( 0.0%) ShopFilter#link_to
     143 ( 0.0%) NilClass#to_i

Top 9 block calls from C (100.0% of all 131709 calls):
   57285 (43.5%) Array#each
   35200 (26.7%) Array#find_index
   30800 (23.4%) Array#each_index
    6160 ( 4.7%) Array#map
    1760 ( 1.3%) Hash#reject
     420 ( 0.3%) Hash#each
      57 ( 0.0%) Hash#each_key
      20 ( 0.0%) Integer#times
       7 ( 0.0%) Array#select

Top 5 block calls from Ruby (100.0% of all 8902 calls):
    4700 (52.8%) Kernel#loop
    1720 (19.3%) Liquid::Condition#interpret_condition
    1280 (14.4%) Liquid::Context#stack
    1200 (13.5%) Liquid::Template#with_profiling
       2 ( 0.0%) #<Class:Benchmark>#realtime

mail

$ ruby -Iharness-stats benchmarks/mail/benchmark.rb
ruby 3.3.0dev (2023-01-11T19:12:15Z master 3642006872) [x86_64-linux]
Command: bundle check 2> /dev/null || bundle install
The Gemfile's dependencies are satisfied
Calling `DidYouMean::SPELL_CHECKERS.merge!(error_name => spell_checker)' has been deprecated. Please call `DidYouMean.correct_error(error_name, spell_checker)' instead.
itr #1: 3036ms
The overall breakdown of each call type:
 3323422 (91.4%) C method calls
  259958 ( 7.2%) Ruby method calls
   49451 ( 1.4%) block calls from C
    1352 ( 0.0%) block calls from Ruby

Top 100 C method calls (98.6% of all 3323422 calls):
  674953 (20.3%) Array#[]
  404620 (12.2%) Integer#<=
  287232 ( 8.6%) Integer#==
  249285 ( 7.5%) Integer#+
  160988 ( 4.8%) BasicObject#!=
  151200 ( 4.5%) #<Class:Mail::Parsers::ReceivedParser>#_trans_keys
   98650 ( 3.0%) String#to_s
   86295 ( 2.6%) Integer#>
   84944 ( 2.6%) Integer#-
   79694 ( 2.4%) Integer#<<
   79694 ( 2.4%) String#[]
   79694 ( 2.4%) String#ord
   76700 ( 2.3%) #<Class:Mail::Parsers::ReceivedParser>#_trans_actions
   50400 ( 1.5%) #<Class:Mail::Parsers::ReceivedParser>#_index_offsets
   50400 ( 1.5%) #<Class:Mail::Parsers::ReceivedParser>#_key_spans
   50400 ( 1.5%) #<Class:Mail::Parsers::ReceivedParser>#_indicies
   50400 ( 1.5%) #<Class:Mail::Parsers::ReceivedParser>#_trans_targs
   42500 ( 1.3%) String#casecmp
   27132 ( 0.8%) #<Class:Mail::Parsers::MessageIdsParser>#_trans_keys
   23900 ( 0.7%) Kernel#kind_of?
   23250 ( 0.7%) #<Class:Mail::Parsers::AddressListsParser>#_trans_keys
   20850 ( 0.6%) #<Class:Mail::Parsers::ContentTypeParser>#_trans_keys
   16600 ( 0.5%) BasicObject#!
   14600 ( 0.4%) Regexp#===
   11350 ( 0.3%) String#downcase
   10601 ( 0.3%) Kernel#respond_to?
   10500 ( 0.3%) #<Class:Mail::Parsers::ContentTransferEncodingParser>#_trans_keys
   10150 ( 0.3%) String#=~
   10044 ( 0.3%) #<Class:Mail::Parsers::MessageIdsParser>#_trans_actions
    9650 ( 0.3%) Array#empty?
    9200 ( 0.3%) #<Class:Mail::Parsers::AddressListsParser>#_trans_actions
    9044 ( 0.3%) #<Class:Mail::Parsers::MessageIdsParser>#_index_offsets
    9044 ( 0.3%) #<Class:Mail::Parsers::MessageIdsParser>#_key_spans
    9044 ( 0.3%) #<Class:Mail::Parsers::MessageIdsParser>#_indicies
    9044 ( 0.3%) #<Class:Mail::Parsers::MessageIdsParser>#_trans_targs
    8100 ( 0.2%) #<Class:Mail::Parsers::ContentTypeParser>#_trans_actions
    7750 ( 0.2%) #<Class:Mail::Parsers::AddressListsParser>#_index_offsets
    7750 ( 0.2%) #<Class:Mail::Parsers::AddressListsParser>#_key_spans
    7750 ( 0.2%) #<Class:Mail::Parsers::AddressListsParser>#_indicies
    7750 ( 0.2%) #<Class:Mail::Parsers::AddressListsParser>#_trans_targs
    7650 ( 0.2%) String#force_encoding
    7401 ( 0.2%) Kernel#is_a?
    7250 ( 0.2%) Array#first
    6950 ( 0.2%) #<Class:Mail::Parsers::ContentTypeParser>#_index_offsets
    6950 ( 0.2%) #<Class:Mail::Parsers::ContentTypeParser>#_key_spans
    6950 ( 0.2%) #<Class:Mail::Parsers::ContentTypeParser>#_indicies
    6950 ( 0.2%) #<Class:Mail::Parsers::ContentTypeParser>#_trans_targs
    6700 ( 0.2%) Integer#<
    6602 ( 0.2%) Class#new
    6350 ( 0.2%) Kernel#dup
    6350 ( 0.2%) Kernel#initialize_dup
    6200 ( 0.2%) String#initialize_copy
    6100 ( 0.2%) Hash#[]
    6100 ( 0.2%) Array#select
    6050 ( 0.2%) String#tr
    6050 ( 0.2%) Kernel#!~
    5850 ( 0.2%) Comparable#<
    5700 ( 0.2%) #<Class:Mail::Parsers::ContentDispositionParser>#_trans_keys
    5050 ( 0.2%) Regexp#to_s
    4950 ( 0.1%) String#<<
    4850 ( 0.1%) String#length
    4750 ( 0.1%) String#gsub!
    4600 ( 0.1%) Integer#div
    4500 ( 0.1%) Symbol#to_s
    4450 ( 0.1%) Array#length
    4300 ( 0.1%) String#downcase!
    4250 ( 0.1%) String#gsub
    4200 ( 0.1%) Regexp#match
    4200 ( 0.1%) Mail::UnstructuredField#charset
    4000 ( 0.1%) #<Class:Encoding>#find
    3900 ( 0.1%) Integer#<=>
    3850 ( 0.1%) #<Class:Mail::Parsers::ContentTransferEncodingParser>#_trans_actions
    3500 ( 0.1%) #<Class:Mail::Parsers::ContentTransferEncodingParser>#_index_offsets
    3500 ( 0.1%) #<Class:Mail::Parsers::ContentTransferEncodingParser>#_key_spans
    3500 ( 0.1%) #<Class:Mail::Parsers::ContentTransferEncodingParser>#_indicies
    3500 ( 0.1%) #<Class:Mail::Parsers::ContentTransferEncodingParser>#_trans_targs
    3250 ( 0.1%) String#slice
    3100 ( 0.1%) String#encoding
    2800 ( 0.1%) String#index
    2800 ( 0.1%) String#strip
    2750 ( 0.1%) Kernel#nil?
    2500 ( 0.1%) BasicObject#==
    2300 ( 0.1%) #<Class:Mail::Parsers::ContentDispositionParser>#_trans_actions
    2150 ( 0.1%) Array#each
    2050 ( 0.1%) String#empty?
    2000 ( 0.1%) String#<=>
    2000 ( 0.1%) #<Class:Mail::Ruby19>#charset_encoder
    2000 ( 0.1%) Encoding#to_s
    2000 ( 0.1%) String#encode
    2000 ( 0.1%) Array#shift
    1900 ( 0.1%) String#split
    1900 ( 0.1%) #<Class:Mail::Parsers::ContentDispositionParser>#_index_offsets
    1900 ( 0.1%) #<Class:Mail::Parsers::ContentDispositionParser>#_key_spans
    1900 ( 0.1%) #<Class:Mail::Parsers::ContentDispositionParser>#_indicies
    1900 ( 0.1%) #<Class:Mail::Parsers::ContentDispositionParser>#_trans_targs
    1800 ( 0.1%) Struct#initialize
    1750 ( 0.1%) String#ascii_only?
    1750 ( 0.1%) String#rstrip
    1700 ( 0.1%) Array#include?
    1650 ( 0.0%) String#==

Top 100 Ruby method calls (89.1% of all 259958 calls):
   46150 (17.8%) Mail::Field#name
   42500 (16.3%) Mail::Field#responsible_for?
   11900 ( 4.6%) #<Class:Mail::Utilities>#blank?
    7700 ( 3.0%) Mail::Field#field_order_id
    6050 ( 2.3%) Mail::Header#fields
    5450 ( 2.1%) Mail::Header#select_field_for
    5100 ( 2.0%) Mail::Message#header
    5050 ( 1.9%) Mail::Field#field
    4900 ( 1.9%) Mail::Utilities#dasherize
    4900 ( 1.9%) Mail::Field#method_missing
    4300 ( 1.7%) Mail::Header#[]
    3850 ( 1.5%) Mail::Field#<=>
    3250 ( 1.3%) Mail::ParserTools#chars
    2950 ( 1.1%) Mail::StructuredField#charset=
    2900 ( 1.1%) Mail::CommonField#value
    2350 ( 0.9%) Mail::ContentTypeField#main_type
    2000 ( 0.8%) #<Class:Mail::Encodings>#transcode_charset
    2000 ( 0.8%) #<Class:Mail::Ruby19>#transcode_charset
    2000 ( 0.8%) Mail::Ruby19::BestEffortCharsetEncoder#encode
    2000 ( 0.8%) Mail::Ruby19::BestEffortCharsetEncoder#pick_encoding
    2000 ( 0.8%) #<Class:Mail::Ruby19>#pick_encoding
    2000 ( 0.8%) #<Class:Mail::Ruby19>#convert_to_encoding
    2000 ( 0.8%) Mail::UnstructuredField#encode_crlf
    1850 ( 0.7%) Mail::CommonField#value=
    1700 ( 0.7%) Mail::CommonField#name=
    1650 ( 0.6%) Mail::Field#create_field
    1650 ( 0.6%) Mail::Field#new_field
    1650 ( 0.6%) Mail::Field#field_class_for
    1600 ( 0.6%) Mail::Message#body
    1550 ( 0.6%) Mail::Header#limited_field?
    1550 ( 0.6%) Mail::Field#unfold
    1500 ( 0.6%) Mail::StructuredField#initialize
    1450 ( 0.6%) Mail::Header#charset
    1450 ( 0.6%) Mail::Field#initialize
    1450 ( 0.6%) Mail::FieldList#<<
    1450 ( 0.6%) Mail::Message#has_content_type?
    1350 ( 0.5%) Kernel#class
    1350 ( 0.5%) #<Class:Mail::Field>#parse
    1350 ( 0.5%) #<Class:Mail::Field>#split
    1350 ( 0.5%) #<Class:0x00007f5b43ca44c8>#__getobj__
    1350 ( 0.5%) Mail::ContentDispositionField#element
    1250 ( 0.5%) #<Class:Mail::Encodings>#decode_encode
    1150 ( 0.4%) Mail::Utilities#underscoreize
    1100 ( 0.4%) Mail::IndifferentHash#update
     950 ( 0.4%) #<Class:Mail::Encodings>#get_encoding
     950 ( 0.4%) #<Class:Mail::Encodings>#get_name
     950 ( 0.4%) Mail::Body#parts
     850 ( 0.3%) Mail::ContentTypeField#parameters
     800 ( 0.3%) Mail::Address#comments
     800 ( 0.3%) Mail::Address#get_comments
     700 ( 0.3%) Mail::Address#get_domain
     700 ( 0.3%) Mail::Address#get_local
     700 ( 0.3%) Mail::ReceivedField#element
     700 ( 0.3%) Mail::ReceivedField#date_time
     650 ( 0.3%) Mail::IndifferentHash#initialize
     650 ( 0.3%) Mail::Body#multipart?
     650 ( 0.3%) #<Class:0x00007f5b43ca44c8>#empty?
     600 ( 0.2%) Mail::ContentTypeField#element
     600 ( 0.2%) Mail::Body#raw_source
     600 ( 0.2%) Mail::ParameterHash#[]
     600 ( 0.2%) Mail::CommonMessageId#element
     550 ( 0.2%) #<Class:Mail::Encodings::TransferEncoding>#to_s
     500 ( 0.2%) Mail::ContentTypeField#string
     500 ( 0.2%) Mail::ContentTypeField#sub_type
     500 ( 0.2%) Mail::CommonAddress#address_list
     500 ( 0.2%) Mail::Address#strip_all_comments
     500 ( 0.2%) Mail::ContentDispositionField#parameters
     450 ( 0.2%) Mail::IndifferentHash#convert_key
     450 ( 0.2%) Mail::IndifferentHash#convert_value
     450 ( 0.2%) #<Class:0x00007f5b43ca44c8>#each
     450 ( 0.2%) Mail::Message#default
     400 ( 0.2%) Mail::Message#main_type
     400 ( 0.2%) Mail::Message#has_content_transfer_encoding?
     400 ( 0.2%) Mail::ContentTransferEncodingField#encoding
     400 ( 0.2%) Mail::ContentTransferEncodingField#element
     400 ( 0.2%) Mail::Body#encoding
     400 ( 0.2%) Mail::CommonField#name
     350 ( 0.1%) #<Class:Mail::Utilities>#binary_unsafe_to_crlf
     350 ( 0.1%) Mail::ContentTransferEncodingField#initialize
     350 ( 0.1%) Mail::ContentTransferEncodingField#parse
     350 ( 0.1%) Mail::ContentTransferEncodingElement#initialize
     350 ( 0.1%) #<Class:Mail::Parsers::ContentTransferEncodingParser>#parse
     350 ( 0.1%) Mail::StructuredField#errors
     350 ( 0.1%) Mail::StructuredField#default
     350 ( 0.1%) Mail::ContentDispositionField#disposition_type
     350 ( 0.1%) Mail::Address#address
     350 ( 0.1%) Mail::Address#domain
     350 ( 0.1%) #<Class:Mail::Encodings>#value_decode
     350 ( 0.1%) Mail::Address#local
     350 ( 0.1%) Mail::ReceivedField#initialize
     350 ( 0.1%) Mail::ReceivedField#parse
     350 ( 0.1%) Mail::ReceivedElement#initialize
     350 ( 0.1%) #<Class:Mail::Parsers::ReceivedParser>#parse
     350 ( 0.1%) Mail::ReceivedField#encoded
     350 ( 0.1%) Mail::ReceivedField#info
     350 ( 0.1%) Mail::ReceivedField#formatted_date
     300 ( 0.1%) Mail::Message#raw_source
     300 ( 0.1%) #<Class:Mail::Utilities>#to_crlf
     300 ( 0.1%) #<Class:Mail::Utilities>#safe_for_line_ending_conversion?
     300 ( 0.1%) #<Class:Mail::Header>#maximum_amount

Top 9 block calls from C (100.0% of all 49451 calls):
   42500 (85.9%) Array#select
    4150 ( 8.4%) Array#each
     800 ( 1.6%) Hash#each
     750 ( 1.5%) Array#map
     450 ( 0.9%) Hash#each_pair
     450 ( 0.9%) Array#reject
     300 ( 0.6%) Array#map!
      50 ( 0.1%) Integer#times
       1 ( 0.0%) Thread::Mutex#synchronize

Top 5 block calls from Ruby (100.0% of all 1352 calls):
     650 (48.1%) #<Class:0x00007f5b43ca44c8>#empty?
     450 (33.3%) #<Class:0x00007f5b43ca44c8>#each
     200 (14.8%) #<Class:0x00007f5b43ca44c8>#<<
      50 ( 3.7%) #<Class:0x00007f5b43ca44c8>#to_ary
       2 ( 0.1%) #<Class:Benchmark>#realtime

psych-load

$ ruby -Iharness-stats benchmarks/psych-load/benchmark.rb
ruby 3.3.0dev (2023-01-11T19:12:15Z master 3642006872) [x86_64-linux]
Command: bundle check 2> /dev/null || bundle install
The Gemfile's dependencies are satisfied
Calling `DidYouMean::SPELL_CHECKERS.merge!(error_name => spell_checker)' has been deprecated. Please call `DidYouMean.correct_error(error_name, spell_checker)' instead.
itr #1: 33611ms
The overall breakdown of each call type:
23760136 (56.4%) C method calls
17795207 (42.3%) Ruby method calls
  535404 ( 1.3%) block calls from C
     302 ( 0.0%) block calls from Ruby

Top 66 C method calls (100.0% of all 23760136 calls):
 2067900 ( 8.7%) Hash#[]
 1942700 ( 8.2%) BasicObject#!
 1500800 ( 6.3%) Psych::Nodes::Scalar#tag
 1124600 ( 4.7%) Psych::Nodes::Node#children
 1035901 ( 4.4%) Class#new
 1034100 ( 4.4%) Psych::Nodes::Node#start_line=
 1034100 ( 4.4%) Psych::Nodes::Node#start_column=
 1034100 ( 4.4%) Hash#empty?
 1033801 ( 4.4%) Array#<<
 1033800 ( 4.4%) Psych::Nodes::Node#end_line=
 1033800 ( 4.4%) Psych::Nodes::Node#end_column=
  952600 ( 4.0%) String#match?
  945700 ( 4.0%) Psych::Visitors::ToRuby#class_loader
  943300 ( 4.0%) Psych::Nodes::Scalar#quoted
  943300 ( 4.0%) Psych::Nodes::Scalar#value
  943300 ( 4.0%) Psych::Nodes::Scalar#anchor
  579000 ( 2.4%) String#==
  557500 ( 2.3%) String#empty?
  557300 ( 2.3%) Hash#key?
  556601 ( 2.3%) Integer#>
  556600 ( 2.3%) String#length
  498504 ( 2.1%) Hash#[]=
  498500 ( 2.1%) Kernel#is_a?
  498500 ( 2.1%) String#-@
  176200 ( 0.7%) Psych::Nodes::Mapping#tag
  127600 ( 0.5%) Array#push
   90800 ( 0.4%) Array#pop
   90800 ( 0.4%) Array#last
   90600 ( 0.4%) Array#each
   88101 ( 0.4%) Array#size
   88100 ( 0.4%) Psych::Nodes::Mapping#anchor
   88100 ( 0.4%) Enumerable#each_slice
    4800 ( 0.0%) Psych::Nodes::Sequence#tag
    2400 ( 0.0%) NilClass#===
    2400 ( 0.0%) Psych::Nodes::Sequence#anchor
     600 ( 0.0%) Array#map
     300 ( 0.0%) Kernel#block_given?
     300 ( 0.0%) Psych::Parser#parse
     300 ( 0.0%) Psych::Nodes::Document#implicit_end=
     300 ( 0.0%) Module#to_s
     300 ( 0.0%) Kernel#dup
     300 ( 0.0%) Kernel#initialize_dup
     300 ( 0.0%) Hash#initialize_copy
     300 ( 0.0%) BasicObject#initialize
     300 ( 0.0%) #<Class:0x00007fa2bf7c23b0>::Config#load_tags
     300 ( 0.0%) #<Class:0x00007fa2bf7c23b0>::Config#domain_types
     300 ( 0.0%) Array#first
       4 ( 0.0%) Module#name
       4 ( 0.0%) String#gsub
       3 ( 0.0%) Integer#+
       2 ( 0.0%) #<Class:Process>#clock_gettime
       1 ( 0.0%) Integer#times
       1 ( 0.0%) Hash#initialize
       1 ( 0.0%) Hash#compare_by_identity
       1 ( 0.0%) Float#-
       1 ( 0.0%) Integer#*
       1 ( 0.0%) Float#to_i
       1 ( 0.0%) Kernel#puts
       1 ( 0.0%) IO#puts
       1 ( 0.0%) IO#write
       1 ( 0.0%) Integer#>=
       1 ( 0.0%) Float#>=
       1 ( 0.0%) Array#join
       1 ( 0.0%) Float#to_s
       1 ( 0.0%) #<Class:IO>#write
       1 ( 0.0%) Array#[]

Top 60 Ruby method calls (100.0% of all 17795207 calls):
 1125200 ( 6.3%) Psych::TreeBuilder#event_location
 1034100 ( 5.8%) Psych::TreeBuilder#set_start_location
 1034100 ( 5.8%) Psych::Visitors::ToRuby#accept
 1034100 ( 5.8%) Psych::Visitors::Visitor#accept
 1034100 ( 5.8%) Psych::Visitors::Visitor#visit
 1034100 ( 5.8%) Psych::Visitors::Visitor#dispatch
 1034100 ( 5.8%) Kernel#class
 1033800 ( 5.8%) Psych::TreeBuilder#set_end_location
 1033800 ( 5.8%) Psych::Visitors::ToRuby#register
  945700 ( 5.3%) Psych::Visitors::ToRuby#resolve_class
  945700 ( 5.3%) Psych::ClassLoader#load
  943300 ( 5.3%) Psych::TreeBuilder#scalar
  943300 ( 5.3%) Psych::Nodes::Scalar#initialize
  943300 ( 5.3%) Psych::TreeBuilder#set_location
  943300 ( 5.3%) Psych::Visitors::ToRuby#visit_Psych_Nodes_Scalar
  943300 ( 5.3%) Psych::Visitors::ToRuby#deserialize
  557500 ( 3.1%) Psych::ScalarScanner#tokenize
  498500 ( 2.8%) Psych::Visitors::ToRuby#deduplicate
   91100 ( 0.5%) Psych::Nodes::Node#initialize
   91100 ( 0.5%) Psych::TreeBuilder#push
   90800 ( 0.5%) Psych::TreeBuilder#pop
   88100 ( 0.5%) Psych::TreeBuilder#start_mapping
   88100 ( 0.5%) Psych::Nodes::Mapping#initialize
   88100 ( 0.5%) Psych::TreeBuilder#end_mapping
   88100 ( 0.5%) Psych::Visitors::ToRuby#visit_Psych_Nodes_Mapping
   88100 ( 0.5%) Psych::Visitors::ToRuby#revive_hash
    2400 ( 0.0%) Psych::TreeBuilder#start_sequence
    2400 ( 0.0%) Psych::Nodes::Sequence#initialize
    2400 ( 0.0%) Psych::TreeBuilder#end_sequence
    2400 ( 0.0%) Psych::Visitors::ToRuby#visit_Psych_Nodes_Sequence
    2400 ( 0.0%) Psych::Visitors::ToRuby#register_empty
     900 ( 0.0%) #<Class:Ractor>#current
     900 ( 0.0%) Ractor#[]
     600 ( 0.0%) #<Class:Psych>#config
     300 ( 0.0%) #<Class:Psych>#load
     300 ( 0.0%) #<Class:Psych>#safe_load
     300 ( 0.0%) #<Class:Psych>#parse
     300 ( 0.0%) #<Class:Psych>#parse_stream
     300 ( 0.0%) Psych::Handlers::DocumentStream#initialize
     300 ( 0.0%) Psych::TreeBuilder#initialize
     300 ( 0.0%) Psych::Parser#initialize
     300 ( 0.0%) Psych::TreeBuilder#start_stream
     300 ( 0.0%) Psych::Nodes::Stream#initialize
     300 ( 0.0%) Psych::Handlers::DocumentStream#start_document
     300 ( 0.0%) Psych::Nodes::Document#initialize
     300 ( 0.0%) Psych::Handlers::DocumentStream#end_document
     300 ( 0.0%) Psych::ClassLoader::Restricted#initialize
     300 ( 0.0%) Psych::ClassLoader#initialize
     300 ( 0.0%) Psych::ScalarScanner#initialize
     300 ( 0.0%) Psych::Visitors::ToRuby#initialize
     300 ( 0.0%) #<Class:Psych>#load_tags
     300 ( 0.0%) #<Class:Psych>#domain_types
     300 ( 0.0%) Psych::Visitors::ToRuby#visit_Psych_Nodes_Document
     300 ( 0.0%) Psych::Nodes::Document#root
       2 ( 0.0%) TracePoint#disable
       1 ( 0.0%) TracePoint#enable
       1 ( 0.0%) Object#run_benchmark
       1 ( 0.0%) #<Class:Benchmark>#realtime
       1 ( 0.0%) #<Class:Psych::Visitors::Visitor>#dispatch_cache
       1 ( 0.0%) Ractor#[]=

Top 3 block calls from C (100.0% of all 535404 calls):
  535300 (100.0%) Array#each
     100 ( 0.0%) Integer#times
       4 ( 0.0%) Hash#[]

Top 2 block calls from Ruby (100.0% of all 302 calls):
     300 (99.3%) Psych::Handlers::DocumentStream#end_document
       2 ( 0.7%) #<Class:Benchmark>#realtime

railsbench

$ ruby -Iharness-stats benchmarks/railsbench/benchmark.rb
ruby 3.3.0dev (2023-01-11T19:12:15Z master 3642006872) [x86_64-linux]
Command: bundle check 2> /dev/null || bundle install
The Gemfile's dependencies are satisfied
Command: bin/rails db:migrate db:seed
Using 100 posts in the database
itr #1: 15509ms
The overall breakdown of each call type:
 9532234 (55.4%) C method calls
 6470966 (37.6%) Ruby method calls
  804710 ( 4.7%) block calls from C
  393325 ( 2.3%) block calls from Ruby

Top 100 C method calls (84.9% of all 9532234 calls):
 1105900 (11.6%) Hash#[]
  453811 ( 4.8%) Hash#[]=
  350840 ( 3.7%) Hash#fetch
  348210 ( 3.7%) Module#===
  344733 ( 3.6%) String#to_s
  305403 ( 3.2%) Class#new
  215855 ( 2.3%) BasicObject#!
  197383 ( 2.1%) Kernel#is_a?
  143338 ( 1.5%) Kernel#initialize_dup
  143332 ( 1.5%) Kernel#dup
  130418 ( 1.4%) Array#each
  128396 ( 1.3%) Kernel#nil?
  127168 ( 1.3%) String#getbyte
  127116 ( 1.3%) String#==
  118219 ( 1.2%) Array#[]
  116544 ( 1.2%) #<Class:Thread>#current
  114213 ( 1.2%) Kernel#respond_to?
  106518 ( 1.1%) Hash#key?
  105765 ( 1.1%) Integer#+
  101810 ( 1.1%) Integer#<
   98206 ( 1.0%) String#empty?
   96699 ( 1.0%) Kernel#block_given?
   93628 ( 1.0%) ActiveSupport::OrderedOptions#[]
   92093 ( 1.0%) Thread#[]
   90291 ( 0.9%) Array#empty?
   82735 ( 0.9%) BasicObject#==
   75565 ( 0.8%) String#gsub!
   74273 ( 0.8%) Symbol#to_s
   71723 ( 0.8%) String#initialize
   71216 ( 0.7%) Hash#initialize_copy
   70454 ( 0.7%) Array#hash
   70357 ( 0.7%) Hash#delete
   63584 ( 0.7%) Integer#^
   63584 ( 0.7%) String#setbyte
   62658 ( 0.7%) ActionController::Metal#_request
   60265 ( 0.6%) String#<<
   58030 ( 0.6%) ActiveModel::LazyAttributeHash#delegate_hash
   56920 ( 0.6%) Kernel#hash
   55511 ( 0.6%) Array#map
   48763 ( 0.5%) ActionView::Helpers::ControllerHelper#_controller
   47772 ( 0.5%) Array#include?
   46463 ( 0.5%) Hash#empty?
   45231 ( 0.5%) #<Class:Process>#clock_gettime
   44878 ( 0.5%) Array#eql?
   44639 ( 0.5%) Array#any?
   44591 ( 0.5%) Array#first
   44012 ( 0.5%) Kernel#kind_of?
   43701 ( 0.5%) Integer#==
   42172 ( 0.4%) CGI::Escape#escapeHTML
   41090 ( 0.4%) Hash#each
   40026 ( 0.4%) ActiveModel::Attribute#type
   39388 ( 0.4%) Kernel#public_send
   37966 ( 0.4%) ActiveModel::Attribute#value_before_type_cast
   37952 ( 0.4%) String#downcase
   37753 ( 0.4%) ActionDispatch::Cookies::CookieJar#request
   37438 ( 0.4%) String#initialize_copy
   36112 ( 0.4%) BasicObject#initialize
   35569 ( 0.4%) ActiveModel::AttributeSet#attributes
   35470 ( 0.4%) #<Class:ActiveSupport::JSON::Encoding>#escape_html_entities_in_json
   35470 ( 0.4%) JSON::Ext::Generator::GeneratorMethods::String#to_json
   35102 ( 0.4%) Regexp#match?
   33984 ( 0.4%) Array#initialize_copy
   33706 ( 0.4%) ActiveSupport::SafeBuffer#concat
   32961 ( 0.3%) Hash#merge
   32221 ( 0.3%) Monitor#synchronize
   31574 ( 0.3%) Module#==
   31162 ( 0.3%) String#scrub
   30257 ( 0.3%) Class#superclass
   29934 ( 0.3%) Array#unshift
   29922 ( 0.3%) Hash#initialize
   28130 ( 0.3%) String#freeze
   27997 ( 0.3%) Array#[]=
   27899 ( 0.3%) Array#pop
   27777 ( 0.3%) Kernel#freeze
   27691 ( 0.3%) #<Class:ActiveSupport::Notifications>#notifier
   27674 ( 0.3%) Array#last
   27010 ( 0.3%) Kernel#instance_variable_defined?
   26923 ( 0.3%) BasicObject#!=
   26732 ( 0.3%) Hash#each_pair
   26392 ( 0.3%) Array#join
   25341 ( 0.3%) String#concat
   25192 ( 0.3%) NilClass#nil?
   24637 ( 0.3%) String#split
   24546 ( 0.3%) NilClass#===
   24494 ( 0.3%) Hash#merge!
   23600 ( 0.2%) String#force_encoding
   22480 ( 0.2%) ActiveModel::LazyAttributeHash#additional_types
   22480 ( 0.2%) ActiveModel::LazyAttributeHash#types
   22480 ( 0.2%) ActiveModel::LazyAttributeHash#values
   22307 ( 0.2%) String#to_i
   22261 ( 0.2%) Array#size
   21987 ( 0.2%) Hash#to_hash
   21897 ( 0.2%) Hash#default
   20000 ( 0.2%) ActionDispatch::Response#header
   19910 ( 0.2%) Hash#default_proc
   19300 ( 0.2%) MatchData#[]
   19292 ( 0.2%) Regexp#match
   17970 ( 0.2%) ActionController::Metal#_response
   17949 ( 0.2%) ActiveSupport::ParameterFilter::CompiledFilter#deep_regexps
   17352 ( 0.2%) ActiveModel::Attribute#name

Top 100 Ruby method calls (49.5% of all 6470966 calls):
  254060 ( 3.9%) Rack::Request::Env#get_header
  137854 ( 2.1%) Concurrent::Map#[]
  137854 ( 2.1%) Concurrent::Collection::NonConcurrentMapBackend#[]
  132074 ( 2.0%) Kernel#class
  124000 ( 1.9%) ActionController::Renderer#rack_key_for
  124000 ( 1.9%) ActionController::Renderer#rack_value_for
   81807 ( 1.3%) Set#include?
   70778 ( 1.1%) ActiveSupport::SafeBuffer#html_safe?
   67144 ( 1.0%) ActiveSupport::PerThreadRegistry#instance
   62399 ( 1.0%) Symbol#to_sym
   55279 ( 0.9%) ActiveSupport::JSON::Encoding::JSONGemEncoder#jsonify
   42172 ( 0.7%) Object#html_safe?
   41948 ( 0.6%) Rack::Request::Env#set_header
   35876 ( 0.6%) ActiveSupport::HashWithIndifferentAccess#convert_key
   35752 ( 0.6%) ActiveModel::Attribute#value
   35556 ( 0.5%) ActiveModel::AttributeSet#[]
   35550 ( 0.5%) ActiveRecord::AttributeMethods::Read#_read_attribute
   35550 ( 0.5%) ActiveModel::AttributeSet#fetch_value
   35550 ( 0.5%) ActiveModel::LazyAttributeHash#[]
   35483 ( 0.5%) ActiveSupport::ToJsonWithActiveSupportEncoder#to_json
   35470 ( 0.5%) ActiveSupport::JSON::Encoding::JSONGemEncoder::EscapedString#to_json
   34636 ( 0.5%) Object#present?
   33883 ( 0.5%) Rack::Request::Env#fetch_header
   33779 ( 0.5%) ActionDispatch::Cookies::AbstractCookieJar#request
   33706 ( 0.5%) ActionView::OutputBuffer#safe_concat
   32189 ( 0.5%) MonitorMixin#mon_synchronize
   31162 ( 0.5%) #<Class:ERB::Util>#unwrapped_html_escape
   31162 ( 0.5%) ActiveSupport::Multibyte::Unicode#tidy_bytes
   28015 ( 0.4%) ActionView::OutputBuffer#<<
   27921 ( 0.4%) ActiveSupport::Subscriber#event_stack
   27921 ( 0.4%) ActiveSupport::SubscriberQueueRegistry#get_queue
   27798 ( 0.4%) ActiveSupport::Notifications::Fanout#listeners_for
   25940 ( 0.4%) #<Class:0x00007f8f04520298>#logger
   24464 ( 0.4%) ActiveModel::Attribute#initialize
   24279 ( 0.4%) ActiveSupport::SafeBuffer#initialize
   24041 ( 0.4%) ActiveSupport::SafeBuffer#concat
   24041 ( 0.4%) ActiveSupport::SafeBuffer#html_escape_interpolated_argument
   23987 ( 0.4%) Rack::Utils::HeaderHash#[]=
   23942 ( 0.4%) ActiveSupport::Configurable::ClassMethods#config
   23922 ( 0.4%) Rack::Request::Helpers#allowed_scheme
   22486 ( 0.3%) #<Class:ActiveModel::Attribute>#from_database
   22480 ( 0.3%) ActiveModel::LazyAttributeHash#assign_default_value
   22480 ( 0.3%) ActiveModel::Attribute::FromDatabase#type_cast
   22105 ( 0.3%) ActionView::Helpers::TagHelper::TagBuilder#tag_option
   22000 ( 0.3%) ActionDispatch::Request#initialize
   22000 ( 0.3%) Rack::Request::Env#initialize
   22000 ( 0.3%) ActionDispatch::Http::URL#initialize
   22000 ( 0.3%) ActionDispatch::Http::FilterParameters#initialize
   22000 ( 0.3%) #<Class:0x00007f8f04576ff8>#__getobj__
   21940 ( 0.3%) ActionController::LogSubscriber#logger
   21940 ( 0.3%) #<Class:ActionController::Base>#logger
   21856 ( 0.3%) ActiveRecord::ConnectionHandling#connection_specification_name
   21022 ( 0.3%) ActiveSupport::JSON::Encoding::JSONGemEncoder::EscapedString#to_s
   20714 ( 0.3%) ActionDispatch::Journey::Format#evaluate
   20357 ( 0.3%) Rack::Request::Helpers#script_name
   20000 ( 0.3%) Rack::Request::Helpers#path_info
   19922 ( 0.3%) ActiveSupport::Configurable#config
   19896 ( 0.3%) ActionDispatch::Request::Session#loaded?
   19846 ( 0.3%) ActiveSupport::HashWithIndifferentAccess#convert_value
   19414 ( 0.3%) #<Module:0x00007f8f045993a0>#_routes
   18615 ( 0.3%) #<Class:Concurrent>#monotonic_time
   18615 ( 0.3%) #<Class:0x00007f8f05752088>#get_time
   18614 ( 0.3%) ActiveSupport::Notifications::Event#now
   18614 ( 0.3%) ActiveSupport::Notifications::Event#now_cpu
   18614 ( 0.3%) ActiveSupport::Notifications::Event#now_allocations
   18614 ( 0.3%) #<Class:GC>#stat
   18226 ( 0.3%) Jbuilder#_blank?
   18226 ( 0.3%) Jbuilder::Blank#==
   18040 ( 0.3%) ActiveRecord::AttributeMethods::PrimaryKey#id
   17957 ( 0.3%) Kernel#tap
   17610 ( 0.3%) ActiveModel::Type::Value#cast
   17510 ( 0.3%) ActiveModel::Type::Value#deserialize
   17200 ( 0.3%) ActionView::LogSubscriber#logger
   17200 ( 0.3%) #<Class:ActionView::Base>#logger
   17005 ( 0.3%) String#html_safe
   17005 ( 0.3%) ActiveSupport::SafeBuffer#to_s
   16359 ( 0.3%) ActiveSupport::NumericWithFormat#to_s
   15970 ( 0.2%) ActiveSupport::HashWithIndifferentAccess#update
   15940 ( 0.2%) ActionDispatch::Request#request_method
   15413 ( 0.2%) Object#presence
   15321 ( 0.2%) #<Class:ActionView::Template::Types>#type_klass
   14491 ( 0.2%) #<Class:ActiveSupport::Notifications>#instrument
   14491 ( 0.2%) ActiveSupport::Notifications::Fanout#listening?
   14000 ( 0.2%) ActionDispatch::Response#get_header
   14000 ( 0.2%) #<Class:0x00007f8f04576ff8>#[]
   13970 ( 0.2%) ActionDispatch::Response#committed?
   13948 ( 0.2%) ActionDispatch::Request::Session::Options#[]
   13942 ( 0.2%) ActiveSupport::Callbacks#run_callbacks
   13942 ( 0.2%) ActiveSupport::Callbacks::CallbackChain#empty?
   13757 ( 0.2%) #<Module:0x00007f8f03f3dcb8>#_routes
   13365 ( 0.2%) ActionView::Resolver#find_all
   13365 ( 0.2%) ActionView::Resolver#cached
   13365 ( 0.2%) ActionView::Resolver::Cache#cache
   13320 ( 0.2%) String#blank?
   13313 ( 0.2%) #<Class:ActionView::Template::Types>#symbols
   13313 ( 0.2%) Mime::Mimes#symbols
   13307 ( 0.2%) ActiveSupport::Notifications::Instrumenter#start
   13307 ( 0.2%) ActiveSupport::Notifications::Fanout#start
   13270 ( 0.2%) Logger#info
   13270 ( 0.2%) ActiveSupport::LoggerThreadSafeLevel#add

Top 40 block calls from C (100.0% of all 804710 calls):
  224448 (27.9%) Array#each
  155760 (19.4%) Hash#each_pair
   83229 (10.3%) Hash#each
   53766 ( 6.7%) ActiveSupport::OrderedOptions#[]
   39740 ( 4.9%) Hash#transform_keys!
   37946 ( 4.7%) Array#map
   32221 ( 4.0%) Monitor#synchronize
   29970 ( 3.7%) Array#reject!
   19301 ( 2.4%) Hash#each_key
   18348 ( 2.3%) Hash#delete_if
   17949 ( 2.2%) Hash#fetch
   13969 ( 1.7%) Array#any?
    9970 ( 1.2%) Array#select
    9075 ( 1.1%) Hash#[]
    7070 ( 0.9%) Integer#times
    6533 ( 0.8%) #<Class:Thread>#handle_interrupt
    6000 ( 0.7%) String#sub
    5983 ( 0.7%) Hash#each_value
    4026 ( 0.5%) Array#initialize
    4002 ( 0.5%) BasicObject#instance_exec
    4000 ( 0.5%) Array#all?
    3971 ( 0.5%) Array#map!
    3970 ( 0.5%) Array#zip
    2600 ( 0.3%) Array#reverse_each
    2600 ( 0.3%) Hash#keep_if
    2155 ( 0.3%) Thread::Mutex#synchronize
    2000 ( 0.2%) Array#collect
    2000 ( 0.2%) Hash#delete
    1983 ( 0.2%) Hash#transform_values
      67 ( 0.0%) Enumerable#reverse_each
      19 ( 0.0%) String#scan
      15 ( 0.0%) Array#reject
       9 ( 0.0%) Kernel#catch
       4 ( 0.0%) Hash#transform_keys
       3 ( 0.0%) Class#initialize
       3 ( 0.0%) Module#class_eval
       2 ( 0.0%) String#gsub!
       1 ( 0.0%) Hash#select
       1 ( 0.0%) Module#initialize
       1 ( 0.0%) String#sub!

Top 91 block calls from Ruby (100.0% of all 393325 calls):
  124000 (31.5%) ActionController::Renderer#rack_value_for
   19907 ( 5.1%) ActiveSupport::Notifications::Instrumenter#instrument
   19414 ( 4.9%) #<Module:0x00007f8f045993a0>#_routes
   17957 ( 4.6%) Kernel#tap
   17200 ( 4.4%) #<Class:ActionView::Base>#logger
   14000 ( 3.6%) #<Class:0x00007f8f04576ff8>#[]
   13757 ( 3.5%) #<Module:0x00007f8f03f3dcb8>#_routes
   11057 ( 2.8%) ActionController::Base#default_url_options
   11057 ( 2.8%) #<Class:ActionController::Base>#default_url_options
   10060 ( 2.6%) #<Class:ActiveRecord::Base>#default_connection_handler
    9940 ( 2.5%) ActiveRecord::Base#__callbacks
    9940 ( 2.5%) #<Class:ActiveRecord::Base>#__callbacks
    9670 ( 2.5%) ActionDispatch::Journey::Format::Parameter#escape
    9057 ( 2.3%) ActionDispatch::Routing::RouteSet::NamedRouteCollection::UrlHelper::OptimizedUrlHelper#call
    7948 ( 2.0%) ActionDispatch::Session::StaleSessionCheck#stale_session_check!
    5292 ( 1.3%) #<Class:ActiveSupport::Notifications>#instrument
    4002 ( 1.0%) #<Class:Benchmark>#realtime
    4001 ( 1.0%) ActiveSupport::Callbacks#run_callbacks
    4000 ( 1.0%) ActiveSupport::TaggedLogging::Formatter#tagged
    4000 ( 1.0%) #<Class:#<Class:0x00007f8f03f3e258>>#compiled_method_container
    3670 ( 0.9%) #<Module:0x00007f8f04d14120>#edit_post_path
    3400 ( 0.9%) #<Module:0x00007f8f03f3dcb8>#_generate_paths_by_default
    3400 ( 0.9%) #<Module:0x00007f8f04d14120>#post_path
    3300 ( 0.8%) ActionView::Base#in_rendering_context
    2600 ( 0.7%) Jbuilder#_scope
    2600 ( 0.7%) #<Class:0x00007f8f044c0960>#deserialize
    2600 ( 0.7%) #<ActiveModel::Type::Helpers::AcceptsMultiparameterTime:0x00007f8f03f87598>#cast
    2600 ( 0.7%) #<Class:TZInfo::TimeOrDateTime>#wrap
    2001 ( 0.5%) #<Class:ActiveRecord::Base>#default_scopes
    2000 ( 0.5%) ActionDispatch::Callbacks#__callbacks
    2000 ( 0.5%) #<Class:ActionDispatch::Callbacks>#__callbacks
    2000 ( 0.5%) #<Class:PostsController>#middleware_stack
    2000 ( 0.5%) ActionDispatch::Http::Parameters#parse_formatted_parameters
    2000 ( 0.5%) ActionController::Base#__callbacks
    2000 ( 0.5%) #<Class:PostsController>#__callbacks
    2000 ( 0.5%) ActionController::Instrumentation#cleanup_view_runtime
    2000 ( 0.5%) ActionController::Base#_renderers
    2000 ( 0.5%) #<Class:ActionController::Base>#_renderers
    2000 ( 0.5%) #<Class:ActionController::Base>#_default_form_builder
    2000 ( 0.5%) ActionView::TemplateRenderer#resolve_layout
    2000 ( 0.5%) ActionView::TemplateRenderer#render_with_layout
    2000 ( 0.5%) #<Class:0x00007f8f04576ff8>#[]=
    2000 ( 0.5%) #<Class:0x00007f8f04576ff8>#key?
    1987 ( 0.5%) ActionController::Base#notice
    1987 ( 0.5%) OpenSSL::Digest::SHA256#initialize
    1987 ( 0.5%) #<Class:0x00007f8f03f3e258>#_app_views_layouts_application_html_erb__1011275717024092096_16960
    1970 ( 0.5%) #<Module:0x00007f8f04d14120>#posts_path
    1300 ( 0.3%) #<Module:0x00007f8f04d141c0>#post_url
    1300 ( 0.3%) ActionDispatch::Journey::Formatter#match_route
    1300 ( 0.3%) ActionDispatch::Routing::RouteSet#url_for
     325 ( 0.1%) Kernel#loop
     216 ( 0.1%) ActiveSupport::Concurrency::ShareLock#yield_shares
     216 ( 0.1%) MonitorMixin::ConditionVariable#wait_while
      64 ( 0.0%) Concurrent::Map#[]
      61 ( 0.0%) #<Class:Post(id: integer, title: string, body: text, published: boolean, created_at: datetime, updated_at: datetime)>#default_scope_override
      34 ( 0.0%) ActionView::Resolver::Cache#cache
      30 ( 0.0%) ActiveRecord::Relation#skip_query_cache_if_necessary
      17 ( 0.0%) #<Module:0x00007f8f04d14120>#new_post_path
      15 ( 0.0%) Concurrent::Map#fetch
      10 ( 0.0%) #<Class:ActiveModel::AttributeMethods::AttrNames>#define_attribute_accessor_method
       6 ( 0.0%) #<Class:Post(id: integer, title: string, body: text, published: boolean, created_at: datetime, updated_at: datetime)>#attribute_type_decorations
       6 ( 0.0%) #<Class:ActiveRecord::Base>#lock_optimistically
       6 ( 0.0%) #<Class:ActiveRecord::Base>#skip_time_zone_conversion_for_attributes
       6 ( 0.0%) #<Class:ActiveRecord::Base>#time_zone_aware_types
       6 ( 0.0%) Concurrent::Collection::NonConcurrentMapBackend#compute_if_absent
       6 ( 0.0%) #<Class:ActiveRecord::Base>#attribute_method_matchers
       5 ( 0.0%) ActiveRecord::Type::TypeMap#perform_fetch
       5 ( 0.0%) #<Class:0x00007f8f03f3e258>#compiled_method_container
       3 ( 0.0%) #<Class:ActionView::Template::Handlers::ERB>#erb_implementation
       3 ( 0.0%) #<Class:ActionView::Template::Handlers::ERB>#escape_ignore_list
       3 ( 0.0%) #<Class:ActionView::Template::Handlers::ERB>#erb_trim_mode
       2 ( 0.0%) Arel::Collectors::SQLString#add_bind
       1 ( 0.0%) ActiveSupport::ExecutionWrapper#__callbacks
       1 ( 0.0%) #<Class:#<Class:0x00007f8f04596100>>#__callbacks
       1 ( 0.0%) SQLite3::Database#prepare
       1 ( 0.0%) ActiveRecord::ConnectionAdapters::AbstractAdapter#__callbacks
       1 ( 0.0%) #<Class:ActiveRecord::ConnectionAdapters::AbstractAdapter>#__callbacks
       1 ( 0.0%) #<Class:ActiveRecord::Base>#table_name_prefix
       1 ( 0.0%) #<Class:ActiveRecord::Base>#pluralize_table_names
       1 ( 0.0%) #<Class:ActiveRecord::Base>#table_name_suffix
       1 ( 0.0%) #<Class:ActiveRecord::Base>#attributes_to_define_after_schema_loads
       1 ( 0.0%) #<Class:ActiveRecord::StatementCache>#create
       1 ( 0.0%) #<Class:ActiveRecord::Base>#default_scope_override
       1 ( 0.0%) #<Class:ActiveRecord::Base>#default_scope_override=
       1 ( 0.0%) #<Class:ActiveRecord::Base>#_reflections
       1 ( 0.0%) #<Class:ActiveRecord::Base>#aggregate_reflections
       1 ( 0.0%) #<Class:ActiveRecord::Base>#attribute_aliases
       1 ( 0.0%) #<Class:PostsController>#_routes
       1 ( 0.0%) #<Class:PostsController>#_helpers
       1 ( 0.0%) #<Class:#<Class:0x00007f8f03f3dad8>>#default_url_options=
       1 ( 0.0%) #<Class:#<Class:0x00007f8f03f3ddf8>>#default_url_options=

ruby-lsp

$ ruby -Iharness-stats benchmarks/ruby-lsp/benchmark.rb
ruby 3.3.0dev (2023-01-11T19:12:15Z master 3642006872) [x86_64-linux]
Command: bundle check 2> /dev/null || bundle install
The Gemfile's dependencies are satisfied
itr #1: 4097ms
The overall breakdown of each call type:
 3194889 (67.5%) C method calls
 1067369 (22.6%) Ruby method calls
  403528 ( 8.5%) block calls from C
   67514 ( 1.4%) block calls from Ruby

Top 100 C method calls (93.2% of all 3194889 calls):
  305572 ( 9.6%) Psych::Nodes::Scalar#value
  176300 ( 5.5%) String#==
  164582 ( 5.2%) RuboCop::AST::NodePattern::LexerRex#ss
  158077 ( 4.9%) Array#[]
  139640 ( 4.4%) Integer#+
  116718 ( 3.7%) Hash#[]
  104428 ( 3.3%) StringScanner#skip
   75894 ( 2.4%) Kernel#is_a?
   68923 ( 2.2%) String#empty?
   62047 ( 1.9%) Class#new
   60653 ( 1.9%) Symbol#==
   60348 ( 1.9%) Integer#<=
   54673 ( 1.7%) AST::Node#children
   53105 ( 1.7%) AST::Node#type
   53049 ( 1.7%) Array#<<
   49756 ( 1.6%) Integer#==
   45698 ( 1.4%) Symbol#match?
   45303 ( 1.4%) BasicObject#!
   32508 ( 1.0%) Array#size
   31201 ( 1.0%) RuboCop::AST::NodePattern::Compiler::Subcompiler#node
   31167 ( 1.0%) StringScanner#eos?
   30148 ( 0.9%) Psych::Nodes::Node#children
   29469 ( 0.9%) Array#[]=
   28258 ( 0.9%) String#to_i
   27415 ( 0.9%) Kernel#freeze
   25601 ( 0.8%) Array#hash
   25083 ( 0.8%) Integer#>
   25052 ( 0.8%) Integer#-
   23675 ( 0.7%) BasicObject#!=
   23372 ( 0.7%) Psych::Nodes::Node#start_line=
   23372 ( 0.7%) Psych::Nodes::Node#start_column=
   23356 ( 0.7%) Kernel#block_given?
   23344 ( 0.7%) Psych::Nodes::Node#end_line=
   23344 ( 0.7%) Psych::Nodes::Node#end_column=
   23195 ( 0.7%) Module#===
   22916 ( 0.7%) Integer#>=
   22824 ( 0.7%) Array#each
   22809 ( 0.7%) Range#begin
   22516 ( 0.7%) Array#empty?
   22065 ( 0.7%) Psych::Nodes::Scalar#tag
   20530 ( 0.6%) Kernel#nil?
   20157 ( 0.6%) Hash#fetch
   19822 ( 0.6%) Range#max
   19635 ( 0.6%) Hash#[]=
   19134 ( 0.6%) RuboCop::AST::NodePattern::LexerRex#state
   17175 ( 0.5%) Kernel#hash
   16995 ( 0.5%) Array#first
   16970 ( 0.5%) StringScanner#matched
   16168 ( 0.5%) Symbol#===
   15256 ( 0.5%) #<Class:RuboCop::AST::NodePattern::Compiler::Subcompiler>#registry
   15142 ( 0.5%) RuboCop::AST::Token#type
   14863 ( 0.5%) Hash#empty?
   14433 ( 0.5%) String#match?
   14402 ( 0.5%) Module#method_added
   14265 ( 0.4%) String#to_sym
   13658 ( 0.4%) Array#last
   13397 ( 0.4%) Array#map
   13369 ( 0.4%) RuboCop::AST::NodePattern::Compiler::Subcompiler#compiler
   12930 ( 0.4%) Psych::Visitors::ToRuby#class_loader
   12739 ( 0.4%) RuboCop::Cop::Badge#department
   12380 ( 0.4%) Integer#<
   12362 ( 0.4%) Psych::Nodes::Scalar#quoted
   12362 ( 0.4%) Psych::Nodes::Scalar#anchor
   12088 ( 0.4%) Kernel#respond_to?
   12033 ( 0.4%) StringScanner#[]
   11866 ( 0.4%) Hash#key?
   11321 ( 0.4%) Parser::AST::Node#location
   11158 ( 0.3%) Kernel#public_send
   10397 ( 0.3%) String#length
   10034 ( 0.3%) Array#to_a
    9734 ( 0.3%) Integer#<=>
    9589 ( 0.3%) Array#include?
    9238 ( 0.3%) AST::Node#hash
    9038 ( 0.3%) Integer#<<
    8711 ( 0.3%) Array#shift
    8508 ( 0.3%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#access
    8448 ( 0.3%) Array#push
    8427 ( 0.3%) BasicObject#equal?
    7387 ( 0.2%) Array#join
    6911 ( 0.2%) String#[]
    6389 ( 0.2%) RuboCop::Cop::Badge#cop_name
    6291 ( 0.2%) String#-@
    6001 ( 0.2%) Integer#===
    5927 ( 0.2%) NilClass#nil?
    5709 ( 0.2%) Symbol#to_s
    5667 ( 0.2%) Module#const_added
    5074 ( 0.2%) Symbol#<=>
    4948 ( 0.2%) Array#any?
    4855 ( 0.2%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#seq_head
    4648 ( 0.1%) Parser::Source::Range#begin_pos
    4566 ( 0.1%) SyntaxTree::Op#value
    4530 ( 0.1%) Array#<=>
    4377 ( 0.1%) Enumerable#find
    4337 ( 0.1%) BasicObject#==
    4313 ( 0.1%) Numeric#negative?
    4211 ( 0.1%) String#split
    3667 ( 0.1%) Parser::Source::Range#end_pos
    3448 ( 0.1%) Integer#/
    3448 ( 0.1%) Integer#%
    3389 ( 0.1%) Array#map!

Top 100 Ruby method calls (71.2% of all 1067369 calls):
   56798 ( 5.3%) Kernel#class
   26257 ( 2.5%) Psych::TreeBuilder#event_location
   23372 ( 2.2%) Psych::TreeBuilder#set_start_location
   23344 ( 2.2%) Psych::TreeBuilder#set_end_location
   20515 ( 1.9%) Psych::TreeBuilder#scalar
   20515 ( 1.9%) Psych::Nodes::Scalar#initialize
   20515 ( 1.9%) Psych::TreeBuilder#set_location
   15253 ( 1.4%) RuboCop::AST::NodePattern::Compiler::Subcompiler#compile
   15253 ( 1.4%) RuboCop::AST::NodePattern::Compiler::Subcompiler#do_compile
   14134 ( 1.3%) Psych::Visitors::ToRuby#accept
   14134 ( 1.3%) Psych::Visitors::Visitor#accept
   14134 ( 1.3%) Psych::Visitors::Visitor#visit
   14134 ( 1.3%) Psych::Visitors::Visitor#dispatch
   14110 ( 1.3%) Psych::Visitors::ToRuby#register
   12946 ( 1.2%) Psych::ClassLoader#load
   12925 ( 1.2%) Psych::Visitors::ToRuby#resolve_class
   12651 ( 1.2%) RuboCop::AST::NodePattern::Parser#next_token
   12651 ( 1.2%) RuboCop::AST::NodePattern::LexerRex#next_token
   12582 ( 1.2%) RuboCop::AST::NodePattern::LexerRex#action
   12362 ( 1.2%) Psych::Visitors::ToRuby#visit_Psych_Nodes_Scalar
   12362 ( 1.2%) Psych::Visitors::ToRuby#deserialize
   12025 ( 1.1%) RuboCop::AST::NodePattern::Lexer#emit
   12025 ( 1.1%) RuboCop::AST::NodePattern::Lexer#token
   11100 ( 1.0%) RuboCop::Cop::Commissioner#with_cop_error_handling
   10867 ( 1.0%) Symbol#to_sym
   10012 ( 0.9%) #<Class:RuboCop::Cop::Base>#badge
    9913 ( 0.9%) AST::Node#initialize
    9913 ( 0.9%) Parser::AST::Node#assign_properties
    9693 ( 0.9%) Psych::ScalarScanner#tokenize
    9661 ( 0.9%) RuboCop::AST::Node#node_parts
    9288 ( 0.9%) RuboCop::AST::NodePattern::Builder#n
    9139 ( 0.9%) Parser::Source::Buffer#line_index_for_position
    8827 ( 0.8%) RuboCop::AST::NodePattern::Compiler::Subcompiler#initialize
    7948 ( 0.7%) Parser::Source::Buffer#line_for_position
    7834 ( 0.7%) RuboCop::AST::NodePattern::Node#arity_range
    7608 ( 0.7%) RuboCop::AST::NodePattern::Node#arity
    7390 ( 0.7%) Parser::Source::Range#line
    7269 ( 0.7%) RuboCop::AST::Descendence#each_child_node
    6998 ( 0.7%) Set#include?
    6948 ( 0.7%) RuboCop::AST::Node#send_type?
    6933 ( 0.6%) RuboCop::Cop::Base#cop_config
    6485 ( 0.6%) RuboCop::AST::Node#parent
    6298 ( 0.6%) Psych::Visitors::ToRuby#deduplicate
    6253 ( 0.6%) RuboCop::AST::NodePattern::Compiler#compile_as_node_pattern
    6253 ( 0.6%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#initialize
    6205 ( 0.6%) RuboCop::AST::NodePattern::Node#child
    6003 ( 0.6%) RuboCop::AST::MethodDispatchNode#method_name
    5977 ( 0.6%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#use_index_from_end
    5974 ( 0.6%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#handle_prev
    5629 ( 0.5%) RuboCop::Cop::Badge#hash
    5518 ( 0.5%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#visit_other_type
    5518 ( 0.5%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#compile_and_advance
    5032 ( 0.5%) #<Class:RuboCop::YAMLDuplicationChecker>#traverse
    4788 ( 0.4%) RuboCop::AST::Token#rescue_modifier?
    4090 ( 0.4%) #<Class:RuboCop::Cop::Base>#department
    4049 ( 0.4%) RuboCop::AST::Node#block_type?
    3986 ( 0.4%) RuboCop::AST::NodePattern::Parser#_reduce_43
    3894 ( 0.4%) RuboCop::Cop::Badge#to_s
    3583 ( 0.3%) Integer#-@
    3528 ( 0.3%) Set#add
    3412 ( 0.3%) #<Class:RuboCop::Cop::Base>#cop_name
    3320 ( 0.3%) RuboCop::AST::NodePattern::Compiler#with_temp_variables
    3259 ( 0.3%) RuboCop::AST::Token#line
    2917 ( 0.3%) RuboCop::AST::NodePattern::Parser#emit_call
    2917 ( 0.3%) RuboCop::AST::NodePattern::Builder#emit_call
    2899 ( 0.3%) RuboCop::AST::SendNode#first_argument_index
    2892 ( 0.3%) RuboCop::Config#for_cop
    2888 ( 0.3%) RuboCop::AST::Descendence#visit_descendants
    2885 ( 0.3%) Psych::Nodes::Node#initialize
    2885 ( 0.3%) Psych::TreeBuilder#push
    2857 ( 0.3%) Psych::TreeBuilder#pop
    2820 ( 0.3%) RuboCop::AST::NodePattern::Parser#emit_atom
    2820 ( 0.3%) RuboCop::AST::NodePattern::Builder#emit_atom
    2741 ( 0.3%) Parser::Source::Buffer#slice
    2693 ( 0.3%) RuboCop::AST::NodePattern::Builder#emit_list
    2687 ( 0.3%) RuboCop::Config#[]
    2646 ( 0.2%) RuboCop::AST::NodePattern#captures
    2498 ( 0.2%) #<Class:RuboCop::Cop::Badge>#camel_case
    2428 ( 0.2%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#access_node
    2427 ( 0.2%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#access_element
    2409 ( 0.2%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#multiple_access
    2390 ( 0.2%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#compile_guard_clause
    2367 ( 0.2%) RuboCop::AST::NodePattern::Parser#_reduce_11
    2367 ( 0.2%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#visit_node_type
    2270 ( 0.2%) RuboCop::AST::Node#numblock_type?
    2202 ( 0.2%) RuboCop::AST::MethodDispatchNode#receiver
    2187 ( 0.2%) Parser::Source::Range#initialize
    2090 ( 0.2%) Parser::Source::Buffer#line_begins
    2068 ( 0.2%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#compile_remaining
    2065 ( 0.2%) RuboCop::Cop::Base#cop_name
    2017 ( 0.2%) RuboCop::AST::NodePattern::Parser#emit_list
    2008 ( 0.2%) Kernel#frozen?
    2007 ( 0.2%) RuboCop::AST::NodePattern::Node#in_sequence_head
    1958 ( 0.2%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#compile_terms
    1958 ( 0.2%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#remaining_arities
    1958 ( 0.2%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#compile_child_nb_guard
    1948 ( 0.2%) RuboCop::AST::NodePattern::Parser#_reduce_42
    1948 ( 0.2%) RuboCop::AST::NodePattern::Parser#_reduce_3
    1948 ( 0.2%) RuboCop::AST::NodePattern::Node::Sequence#initialize
    1948 ( 0.2%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#visit_sequence

Top 55 block calls from C (100.0% of all 403528 calls):
  280186 (69.4%) Array#each
   47382 (11.7%) Array#select
   18166 ( 4.5%) Array#map
   11496 ( 2.8%) Array#any?
    8283 ( 2.1%) Array#rindex
    8237 ( 2.0%) Array#map!
    7376 ( 1.8%) Array#bsearch_index
    4737 ( 1.2%) Hash#each
    2907 ( 0.7%) Hash#[]
    2498 ( 0.6%) String#gsub
    2145 ( 0.5%) Array#delete_if
    1662 ( 0.4%) Hash#each_key
    1348 ( 0.3%) Hash#fetch
     915 ( 0.2%) BasicObject#instance_exec
     907 ( 0.2%) Array#reverse_each
     872 ( 0.2%) Hash#each_pair
     798 ( 0.2%) Array#each_index
     676 ( 0.2%) Array#reject!
     574 ( 0.1%) Array#reject
     326 ( 0.1%) Array#index
     281 ( 0.1%) Range#each
     259 ( 0.1%) String#each_line
     212 ( 0.1%) Array#all?
     201 ( 0.0%) #<Class:IO>#foreach
     194 ( 0.0%) Array#select!
     179 ( 0.0%) Module#module_eval
     128 ( 0.0%) Range#bsearch
      93 ( 0.0%) Module#module_exec
      80 ( 0.0%) Hash#each_value
      58 ( 0.0%) Array#zip
      58 ( 0.0%) Array#drop_while
      48 ( 0.0%) Module#class_eval
      35 ( 0.0%) Integer#upto
      29 ( 0.0%) Hash#select
      19 ( 0.0%) Class#initialize
      18 ( 0.0%) Kernel#require
      18 ( 0.0%) Enumerator::Yielder#<<
      18 ( 0.0%) Array#count
      15 ( 0.0%) Hash#merge!
      15 ( 0.0%) Integer#downto
      13 ( 0.0%) String#sub
      13 ( 0.0%) Array#to_h
      12 ( 0.0%) #<Class:Struct>#new
       7 ( 0.0%) Array#fetch
       6 ( 0.0%) Hash#transform_values
       6 ( 0.0%) Module#refine
       5 ( 0.0%) Kernel#catch
       4 ( 0.0%) #<Class:Dir>#chdir
       4 ( 0.0%) Array#take_while
       2 ( 0.0%) BasicObject#instance_eval
       2 ( 0.0%) Thread::Mutex#synchronize
       2 ( 0.0%) Integer#times
       1 ( 0.0%) #<Class:IO>#open
       1 ( 0.0%) Kernel#require_relative
       1 ( 0.0%) Module#initialize

Top 100 block calls from Ruby (99.8% of all 67514 calls):
   12582 (18.6%) RuboCop::AST::NodePattern::LexerRex#action
   11100 (16.4%) RuboCop::Cop::Commissioner#with_cop_error_handling
    6948 (10.3%) RuboCop::AST::Node#send_type?
    5974 ( 8.8%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#handle_prev
    4570 ( 6.8%) RuboCop::AST::NodePattern::Compiler#with_temp_variables
    4049 ( 6.0%) RuboCop::AST::Node#block_type?
    2270 ( 3.4%) RuboCop::AST::Node#numblock_type?
    1815 ( 2.7%) RuboCop::AST::Node#class_type?
    1650 ( 2.4%) RuboCop::AST::Node#sclass_type?
    1471 ( 2.2%) RuboCop::AST::Node#casgn_type?
    1418 ( 2.1%) RuboCop::AST::Node#def_type?
    1334 ( 2.0%) RuboCop::AST::Node#module_type?
    1159 ( 1.7%) RuboCop::AST::NodePattern::Compiler::NodePatternSubcompiler#multiple_access
    1127 ( 1.7%) RuboCop::AST::Node#defs_type?
     889 ( 1.3%) RuboCop::AST::Node#begin_type?
     708 ( 1.0%) RuboCop::AST::Node#op_asgn_type?
     626 ( 0.9%) RuboCop::AST::NodePattern::MethodDefiner#def_helper
     619 ( 0.9%) RuboCop::AST::Node#hash_type?
     595 ( 0.9%) RuboCop::AST::Node#kwbegin_type?
     530 ( 0.8%) T::Private::Methods::Signature#each_args_value_type
     360 ( 0.5%) RuboCop::AST::Node#const_type?
     280 ( 0.4%) RuboCop::AST::Node#visit_ancestors
     273 ( 0.4%) RuboCop::AST::Node#masgn_type?
     218 ( 0.3%) RubyLsp::Requests::SemanticHighlighting#add_token
     218 ( 0.3%) RubyLsp::Requests::Support::SemanticTokenEncoder#compute_delta
     218 ( 0.3%) RubyLsp::Requests::Support::SemanticTokenEncoder#encode_modifiers
     216 ( 0.3%) RuboCop::AST::Node#if_type?
     173 ( 0.3%) RuboCop::AST::Node#array_type?
     164 ( 0.2%) RuboCop::AST::Node#lvasgn_type?
     140 ( 0.2%) RuboCop::AST::Node#csend_type?
     139 ( 0.2%) RuboCop::AST::Node#ivasgn_type?
     136 ( 0.2%) RuboCop::AST::Node#self_type?
     133 ( 0.2%) RuboCop::AST::Node#dstr_type?
     131 ( 0.2%) RuboCop::AST::Node#or_asgn_type?
     129 ( 0.2%) RuboCop::AST::Node#cvasgn_type?
     129 ( 0.2%) RuboCop::AST::Node#gvasgn_type?
     129 ( 0.2%) RuboCop::AST::Node#and_asgn_type?
     118 ( 0.2%) RuboCop::AST::NodePattern::Compiler::SequenceSubcompiler#sync
      97 ( 0.1%) #<Class:T::Configuration>#without_ruby_warnings
      93 ( 0.1%) T::Private::DeclState#without_on_method_added
      88 ( 0.1%) RuboCop::AST::Node#sym_type?
      86 ( 0.1%) RuboCop::Cop::Metrics::Utils::AbcSizeCalculator#visit_depth_last
      85 ( 0.1%) RuboCop::AST::Node#rescue_type?
      85 ( 0.1%) RuboCop::AST::Node#ensure_type?
      85 ( 0.1%) RuboCop::AST::Node#for_type?
      81 ( 0.1%) RubyLsp::Requests::SemanticHighlighting#visit_var_ref
      77 ( 0.1%) RuboCop::AST::Node#return_type?
      77 ( 0.1%) RuboCop::AST::Node#break_type?
      77 ( 0.1%) RuboCop::AST::Node#defined_type?
      76 ( 0.1%) RuboCop::AST::Node#next_type?
      75 ( 0.1%) Pathname#ascend
      75 ( 0.1%) RuboCop::AST::Node#retry_type?
      75 ( 0.1%) RuboCop::AST::Node#redo_type?
      74 ( 0.1%) RuboCop::AST::Node#pair_type?
      73 ( 0.1%) Kernel#tap
      71 ( 0.1%) OptionParser::List#search
      70 ( 0.1%) RuboCop::AST::NodePattern::Lexer#emit
      69 ( 0.1%) RubyLsp::Requests::SemanticHighlighting#special_method?
      55 ( 0.1%) RubyLsp::Requests::SemanticHighlighting#visit_const
      43 ( 0.1%) #<Class:T::Private::Methods>#run_sig_block_for_key
      43 ( 0.1%) RuboCop::Cop::VariableForce#before_entering_scope
      43 ( 0.1%) RuboCop::Cop::VariableForce#after_entering_scope
      43 ( 0.1%) RuboCop::Cop::VariableForce#before_leaving_scope
      43 ( 0.1%) RuboCop::Cop::VariableForce#after_leaving_scope
      37 ( 0.1%) RubyLsp::Requests::SemanticHighlighting#visit_call
      35 ( 0.1%) RuboCop::AST::Descendence#each_node
      34 ( 0.1%) RuboCop::Cop::Base#correct
      34 ( 0.1%) RuboCop::Cop::Util#on_node
      34 ( 0.1%) RuboCop::AST::Node#cbase_type?
      34 ( 0.1%) RubyLsp::Requests::Support::RuboCopDiagnostic#initialize
      33 ( 0.0%) RuboCop::AST::Node#str_type?
      33 ( 0.0%) RubyLsp::Requests::SemanticHighlighting#visit_fcall
      32 ( 0.0%) RuboCop::AST::Node#args_type?
      31 ( 0.0%) RubyLsp::Requests::Support::RuboCopDiagnostic#offense_replacements
      28 ( 0.0%) Psych::Handlers::DocumentStream#end_document
      28 ( 0.0%) RuboCop::AST::Node#mlhs_type?
      26 ( 0.0%) RuboCop::AST::Node#shadowarg_type?
      24 ( 0.0%) RuboCop::AST::Node#blockarg_type?
      24 ( 0.0%) RuboCop::AST::Node#recursive_basic_literal?
      24 ( 0.0%) RuboCop::AST::Node#optarg_type?
      24 ( 0.0%) RubyLsp::Requests::SemanticHighlighting#visit_command
      22 ( 0.0%) RubyLsp::Requests::SemanticHighlighting#visit_params
      21 ( 0.0%) RubyLsp::Requests::SemanticHighlighting#visit_kw
      19 ( 0.0%) RuboCop::AST::Node#regexp_type?
      18 ( 0.0%) RuboCop::AST::Node#arg_type?
      17 ( 0.0%) RuboCop::AST::Node#int_type?
      16 ( 0.0%) RuboCop::Cop::VariableForce#before_declaring_variable
      16 ( 0.0%) RuboCop::Cop::VariableForce#after_declaring_variable
      16 ( 0.0%) RuboCop::AST::Node#restarg_type?
      16 ( 0.0%) RuboCop::AST::Node#kwrestarg_type?
      16 ( 0.0%) RuboCop::Cop::EmptyParameter#empty_arguments?
      12 ( 0.0%) RubyLsp::Requests::SemanticHighlighting#visit_vcall
      11 ( 0.0%) RuboCop::AST::Node#zsuper_type?
      11 ( 0.0%) RuboCop::AST::Node#super_type?
      11 ( 0.0%) RubyLsp::Requests::SemanticHighlighting#visit_def
      10 ( 0.0%) RubyLsp::Requests::SemanticHighlighting#visit_var_field
       8 ( 0.0%) RuboCop::Options#section
       8 ( 0.0%) ERB::Compiler::SimpleScanner#scan
       8 ( 0.0%) Parser::Lexer#advance
       8 ( 0.0%) RuboCop::Cop::Style::ConditionalAssignment#on_ivasgn

sequel

$ ruby -Iharness-stats benchmarks/sequel/benchmark.rb
ruby 3.3.0dev (2023-01-11T19:12:15Z master 3642006872) [x86_64-linux]
Command: bundle check 2> /dev/null || bundle install
The Gemfile's dependencies are satisfied
itr #1: 684ms
The overall breakdown of each call type:
  507021 (65.5%) C method calls
  194005 (25.1%) Ruby method calls
   64000 ( 8.3%) block calls from C
    9002 ( 1.2%) block calls from Ruby

Top 100 C method calls (100.0% of all 507021 calls):
   68000 (13.4%) Hash#[]
   36000 ( 7.1%) Module#===
   31001 ( 6.1%) Array#[]
   25000 ( 4.9%) Symbol#===
   19003 ( 3.7%) Integer#+
   18000 ( 3.6%) String#downcase
   16000 ( 3.2%) Thread::Mutex#synchronize
   15000 ( 3.0%) String#<<
   14000 ( 2.8%) Hash#[]=
   13000 ( 2.6%) BasicObject#!
   12000 ( 2.4%) Kernel#nil?
   10000 ( 2.0%) Array#first
   10000 ( 2.0%) Integer#<
    9000 ( 1.8%) Sequel::Dataset#opts
    9000 ( 1.8%) SQLite3::Statement#column_name
    9000 ( 1.8%) SQLite3::Statement#column_decltype
    9000 ( 1.8%) String#=~
    9000 ( 1.8%) String#==
    9000 ( 1.8%) String#to_sym
    8000 ( 1.6%) Kernel#freeze
    7000 ( 1.4%) Sequel::Dataset#db
    6000 ( 1.2%) Integer#==
    6000 ( 1.2%) Regexp#match
    5000 ( 1.0%) Array#include?
    5000 ( 1.0%) Kernel#is_a?
    5000 ( 1.0%) Kernel#block_given?
    4000 ( 0.8%) Hash#==
    4000 ( 0.8%) Class#new
    4000 ( 0.8%) Array#map
    4000 ( 0.8%) Array#length
    4000 ( 0.8%) Sequel::SequelMethods#datetime_class
    4000 ( 0.8%) MatchData#begin
    4000 ( 0.8%) MatchData#end
    4000 ( 0.8%) String#[]=
    3000 ( 0.6%) Hash#each
    3000 ( 0.6%) #<Class:Hash>#[]
    3000 ( 0.6%) Hash#merge!
    3000 ( 0.6%) Hash#each_key
    3000 ( 0.6%) Symbol#==
    3000 ( 0.6%) BasicObject#==
    3000 ( 0.6%) Array#empty?
    3000 ( 0.6%) SQLite3::Statement#column_count
    3000 ( 0.6%) SQLite3::Statement#closed?
    3000 ( 0.6%) #<Class:Array>#new
    3000 ( 0.6%) Array#initialize
    3000 ( 0.6%) Kernel#respond_to?
    2000 ( 0.4%) Array#==
    2000 ( 0.4%) Array#each
    2000 ( 0.4%) Integer#to_s
    2000 ( 0.4%) SQLite3::Statement#done?
    2000 ( 0.4%) Method#call
    2000 ( 0.4%) Sequel::SequelMethods#database_timezone
    2000 ( 0.4%) Module#==
    2000 ( 0.4%) #<Class:Date>#_parse
    2000 ( 0.4%) String#gsub!
    2000 ( 0.4%) Integer#**
    2000 ( 0.4%) Time#getlocal
    2000 ( 0.4%) Rational#*
    2000 ( 0.4%) BasicObject#!=
    2000 ( 0.4%) #<Class:Time>#local
    2000 ( 0.4%) Sequel::SequelMethods#application_timezone
    1001 ( 0.2%) Array#size
    1001 ( 0.2%) Integer#>=
    1001 ( 0.2%) Array#<<
    1000 ( 0.2%) Enumerable#map
    1000 ( 0.2%) String#initialize
    1000 ( 0.2%) NilClass#nil?
    1000 ( 0.2%) Symbol#to_s
    1000 ( 0.2%) #<Class:Thread>#current
    1000 ( 0.2%) Array#shift
    1000 ( 0.2%) Hash#fetch
    1000 ( 0.2%) SQLite3::Statement#initialize
    1000 ( 0.2%) SQLite3::Statement#reset!
    1000 ( 0.2%) Array#flatten
    1000 ( 0.2%) Sequel::SQLite::Database#conversion_procs
    1000 ( 0.2%) SQLite3::Database#results_as_hash
    1000 ( 0.2%) SQLite3::Statement#step
    1000 ( 0.2%) SQLite3::Database#encoding
    1000 ( 0.2%) SQLite3::ResultSet::ArrayWithTypesAndFields#fields=
    1000 ( 0.2%) SQLite3::ResultSet::ArrayWithTypesAndFields#types=
    1000 ( 0.2%) Class#allocate
    1000 ( 0.2%) Kernel#instance_variable_set
    1000 ( 0.2%) SQLite3::Statement#close
    1000 ( 0.2%) Hash#delete
    1000 ( 0.2%) Thread::ConditionVariable#signal
    1000 ( 0.2%) Time#to_s
    1000 ( 0.2%) Integer#round
       2 ( 0.0%) #<Class:Process>#clock_gettime
       1 ( 0.0%) Integer#upto
       1 ( 0.0%) Float#-
       1 ( 0.0%) Integer#*
       1 ( 0.0%) Float#to_i
       1 ( 0.0%) Kernel#puts
       1 ( 0.0%) IO#puts
       1 ( 0.0%) IO#write
       1 ( 0.0%) Float#>=
       1 ( 0.0%) Array#join
       1 ( 0.0%) Float#to_s
       1 ( 0.0%) #<Class:IO>#write
       1 ( 0.0%) Integer#>

Top 100 Ruby method calls (92.3% of all 194005 calls):
   13000 ( 6.7%) Sequel::SequelMethods#synchronize
    9000 ( 4.6%) Sequel::SQLite::Dataset#base_type_name
    9000 ( 4.6%) Sequel::UnmodifiedIdentifiers::DatasetMethods#output_identifier
    7000 ( 3.6%) Sequel::Dataset#cache_get
    5000 ( 2.6%) Sequel::Dataset#literal_append
    5000 ( 2.6%) Sequel::Model::InstanceMethods#[]
    4000 ( 2.1%) Sequel::Dataset#cache_set
    3000 ( 1.5%) Sequel::Dataset#clone
    3000 ( 1.5%) Kernel#clone
    3000 ( 1.5%) Sequel::Dataset#initialize_clone
    3000 ( 1.5%) Sequel::Dataset#freeze
    3000 ( 1.5%) Sequel::ThreadedConnectionPool#sync
    3000 ( 1.5%) SQLite3::Statement#types
    3000 ( 1.5%) SQLite3::Statement#must_be_open!
    3000 ( 1.5%) #<Class:#<Object:0x00007f3eef0db6c0>>#call
    3000 ( 1.5%) Integer#to_i
    2000 ( 1.0%) Sequel::SequelMethods#condition_specifier?
    2000 ( 1.0%) Sequel::Dataset#cached_dataset
    2000 ( 1.0%) Sequel::Dataset#skip_symbol_cache?
    2000 ( 1.0%) Sequel::Database#literal_symbol
    2000 ( 1.0%) Sequel::Dataset#literal_integer
    2000 ( 1.0%) SQLite3::Statement#columns
    2000 ( 1.0%) Sequel::SQLite::Database#to_application_timestamp
    2000 ( 1.0%) Sequel::Database#to_application_timestamp
    2000 ( 1.0%) Sequel::Database#timezone
    2000 ( 1.0%) Sequel::SequelMethods#convert_timestamp
    2000 ( 1.0%) Sequel::SequelMethods#convert_input_timestamp
    2000 ( 1.0%) Sequel::SequelMethods#string_to_datetime
    2000 ( 1.0%) #<Class:Time>#now
    2000 ( 1.0%) #<Class:Time>#parse
    2000 ( 1.0%) #<Class:Time>#make_time
    2000 ( 1.0%) Sequel::SequelMethods#convert_output_timestamp
    1000 ( 0.5%) Sequel::Model::ClassMethods#where
    1000 ( 0.5%) Sequel::Model::ClassMethods#dataset
    1000 ( 0.5%) Sequel::Dataset#where
    1000 ( 0.5%) Sequel::Dataset#add_filter
    1000 ( 0.5%) Sequel::Dataset#filter_expr
    1000 ( 0.5%) #<Class:Sequel::SQL::BooleanExpression>#from_value_pairs
    1000 ( 0.5%) #<Class:Sequel::SQL::BooleanExpression>#from_value_pair
    1000 ( 0.5%) Sequel::SQL::ComplexExpression#initialize
    1000 ( 0.5%) Sequel::Dataset#_invert_filter
    1000 ( 0.5%) Sequel::Dataset#first
    1000 ( 0.5%) Sequel::Dataset#single_record
    1000 ( 0.5%) Sequel::Dataset#_single_record_ds
    1000 ( 0.5%) Sequel::Dataset#single_record!
    1000 ( 0.5%) Sequel::SQLite::DatasetMethods#select_sql
    1000 ( 0.5%) Sequel::Dataset#sql_string_origin
    1000 ( 0.5%) Sequel::Dataset#select_with_sql
    1000 ( 0.5%) Sequel::SQLite::DatasetMethods#supports_cte?
    1000 ( 0.5%) Sequel::SQLite::DatabaseMethods#sqlite_version
    1000 ( 0.5%) Sequel::Dataset#select_select_sql
    1000 ( 0.5%) Sequel::Dataset#select_distinct_sql
    1000 ( 0.5%) Sequel::Dataset#select_columns_sql
    1000 ( 0.5%) Sequel::Dataset#column_list_append
    1000 ( 0.5%) Sequel::Dataset#select_from_sql
    1000 ( 0.5%) Sequel::Dataset#source_list_append
    1000 ( 0.5%) Sequel::Dataset#identifier_list_append
    1000 ( 0.5%) Sequel::Dataset#identifier_append
    1000 ( 0.5%) Sequel::Dataset#select_join_sql
    1000 ( 0.5%) Sequel::Dataset#select_where_sql
    1000 ( 0.5%) Sequel::Dataset#literal_expression_append
    1000 ( 0.5%) Sequel::SQL::ComplexExpression#to_s_append
    1000 ( 0.5%) Sequel::Model::Associations::DatasetMethods#complex_expression_sql_append
    1000 ( 0.5%) Sequel::SQLite::Dataset#complex_expression_sql_append
    1000 ( 0.5%) Sequel::SQLite::DatasetMethods#complex_expression_sql_append
    1000 ( 0.5%) Sequel::Dataset#complex_expression_sql_append
    1000 ( 0.5%) Sequel::Dataset#select_group_sql
    1000 ( 0.5%) Sequel::Dataset#select_having_sql
    1000 ( 0.5%) Sequel::Dataset#select_window_sql
    1000 ( 0.5%) Sequel::Dataset#select_compounds_sql
    1000 ( 0.5%) Sequel::Dataset#select_order_sql
    1000 ( 0.5%) Sequel::Dataset#select_limit_sql
    1000 ( 0.5%) Sequel::SQLite::DatasetMethods#select_lock_sql
    1000 ( 0.5%) Sequel::Dataset#select_lock_sql
    1000 ( 0.5%) Sequel::Dataset#cache_sql?
    1000 ( 0.5%) Sequel::Dataset#with_sql_first
    1000 ( 0.5%) Sequel::Dataset#with_sql_each
    1000 ( 0.5%) Sequel::Dataset#row_proc
    1000 ( 0.5%) Sequel::Dataset#_with_sql_dataset
    1000 ( 0.5%) Sequel::SQLite::Dataset#fetch_rows
    1000 ( 0.5%) Sequel::Dataset#execute
    1000 ( 0.5%) Sequel::Database#sharded?
    1000 ( 0.5%) Sequel::SQLite::Database#execute
    1000 ( 0.5%) Sequel::SQLite::Database#_execute
    1000 ( 0.5%) Sequel::Database#synchronize
    1000 ( 0.5%) Sequel::ThreadedConnectionPool#hold
    1000 ( 0.5%) Sequel::SequelMethods#current
    1000 ( 0.5%) Sequel::ThreadedConnectionPool#owned_connection
    1000 ( 0.5%) Sequel::ThreadedConnectionPool#acquire
    1000 ( 0.5%) Sequel::ThreadedConnectionPool#assign_connection
    1000 ( 0.5%) Sequel::ThreadedConnectionPool#next_available
    1000 ( 0.5%) Sequel::Database#log_connection_yield
    1000 ( 0.5%) Sequel::Database#skip_logging?
    1000 ( 0.5%) SQLite3::Database#query
    1000 ( 0.5%) SQLite3::Database#prepare
    1000 ( 0.5%) SQLite3::Statement#execute
    1000 ( 0.5%) SQLite3::Statement#active?
    1000 ( 0.5%) SQLite3::Statement#bind_params
    1000 ( 0.5%) SQLite3::ResultSet#initialize
    1000 ( 0.5%) SQLite3::ResultSet#types

Top 7 block calls from C (100.0% of all 64000 calls):
   20000 (31.2%) Array#map
   19000 (29.7%) Thread::Mutex#synchronize
   18000 (28.1%) Array#initialize
    3000 ( 4.7%) Hash#each_key
    2000 ( 3.1%) Array#each
    1000 ( 1.6%) Integer#upto
    1000 ( 1.6%) Hash#each

Top 7 block calls from Ruby (100.0% of all 9002 calls):
    3000 (33.3%) SQLite3::ResultSet#each
    2000 (22.2%) Sequel::Dataset#cached_dataset
    1000 (11.1%) Sequel::ThreadedConnectionPool#hold
    1000 (11.1%) Sequel::Database#log_connection_yield
    1000 (11.1%) SQLite3::Database#query
    1000 (11.1%) SQLite3::Database#translate_from_db
       2 ( 0.0%) #<Class:Benchmark>#realtime

maximecb · 2023-01-11T21:25:56Z

Thanks for including this :)

Some small suggestions:

Could you move the overall breakdown to the top?
Could you change the naming, I think it would be clearer as:

 1621330 (60.5%) Ruby => C method calls
  915981 (34.2%) Ruby => Ruby method calls
  131709 ( 4.9%) C    => block calls
    8902 ( 0.3%) Ruby => block calls

k0kubun · 2023-01-11T22:09:38Z

Could you change the naming, I think it would be clearer as:

The first two could be called from C as well. Do you want to make it * => C method calls and * => Ruby method calls or Ruby/C => C method calls and Ruby/C => Ruby method calls? I feel it's fine to keep them C method calls and Ruby method calls.

k0kubun · 2023-01-11T22:13:25Z

How about this? f6955d8

The overall breakdown of each call type:
 9532941 (55.4%) C method calls
 6470859 (37.6%) Ruby method calls
  805035 ( 4.7%) block calls from C
  393000 ( 2.3%) block calls from Ruby

eregon · 2023-01-12T15:44:00Z

Interesting data!
I'm copying here the overall breakdowns as well as the overall average:
(I used d=s.scan(/\(\s*(\d+\.\d+)%\) C method calls/).map { Float _1.first } to get the values for all benchmarks)
(referred by https://ruby.social/@eregon/109677015400707156)

activerecord

The overall breakdown of each call type:
944021 (55.6%) C method calls
670005 (39.5%) Ruby method calls
50000 ( 2.9%) block calls from C
34002 ( 2.0%) block calls from Ruby

hexapdf

The overall breakdown of each call type:
45292659 (73.1%) C method calls
12839712 (20.7%) Ruby method calls
3531428 ( 5.7%) block calls from C
283387 ( 0.5%) block calls from Ruby

liquid-render

The overall breakdown of each call type:
1621330 (60.5%) C method calls
915981 (34.2%) Ruby method calls
131709 ( 4.9%) block calls from C
8902 ( 0.3%) block calls from Ruby

mail

The overall breakdown of each call type:
3323422 (91.4%) C method calls
259958 ( 7.2%) Ruby method calls
49451 ( 1.4%) block calls from C
1352 ( 0.0%) block calls from Ruby

psych-load

23760136 (56.4%) C method calls
17795207 (42.3%) Ruby method calls
535404 ( 1.3%) block calls from C
302 ( 0.0%) block calls from Ruby

railsbench

9532234 (55.4%) C method calls
6470966 (37.6%) Ruby method calls
804710 ( 4.7%) block calls from C
393325 ( 2.3%) block calls from Ruby

ruby-lsp

The overall breakdown of each call type:
3194889 (67.5%) C method calls
1067369 (22.6%) Ruby method calls
403528 ( 8.5%) block calls from C
67514 ( 1.4%) block calls from Ruby

sequel

The overall breakdown of each call type:
507021 (65.5%) C method calls
194005 (25.1%) Ruby method calls
64000 ( 8.3%) block calls from C
9002 ( 1.2%) block calls from Ruby

Average of the above benchmarks

average (min - max)
65.7% (55.4% - 91.4%) C method calls
28.7% ( 7.2% - 42.3%) Ruby method calls
 4.7% ( 1.3% -  8.5%) block calls from C
 1.0% ( 0.0% -  2.3%) block calls from Ruby

maximecb · 2023-01-12T16:39:02Z

The numbers go against my initial intuition. I thought that we would see a lot of loop iterations using .each and such, which would mean a lot of block calls, and that this would be really worth optimizing. In actuality, all of our benchmarks are dominated by C calls, by something like two thirds. That makes me think we might well want to spend more effort making C calls faster.

k0kubun · 2024-01-24T01:06:16Z

It's been a year since I collected the stats in the PR description. I uploaded the latest call stats here https://gist.github.com/k0kubun/34d82ceed5ded1b7a881a56edaaf3f6f.

Even headline benchmarks alone don't fit in GitHub's size limit for comments and issue descriptions, so it's now on gist.

eregon · 2024-01-24T15:49:29Z

From a quick look at the number of block calls from C/Ruby seems very low.
And it seems even less than before, e.g. activesupport:

Before:
Top 9 block calls from C (100.0% of all 50000 calls):
   22000 (44.0%) Array#each
   10000 (20.0%) Hash#each_key
    6000 (12.0%) Array#map
    4000 ( 8.0%) #<Class:Thread>#handle_interrupt
    2000 ( 4.0%) Hash#each
    2000 ( 4.0%) Array#map!
    2000 ( 4.0%) Monitor#synchronize
    1000 ( 2.0%) Integer#upto
    1000 ( 2.0%) Hash#select

Top 18 block calls from Ruby (100.0% of all 34002 calls):
    4000 (11.8%) ActiveRecord::Relation#_scoping
    4000 (11.8%) #<Class:ActiveRecord::Base>#default_connection_handler
    3000 ( 8.8%) #<Class:ActiveRecord::Base>#attribute_aliases
    2000 ( 5.9%) #<Class:Post(id: integer, title: string, body: string, type_name: string, key: string, upvotes: integer, author_id: integer, created_at: datetime, updated_at: datetime)>#default_scope_override
    2000 ( 5.9%) #<Class:ActiveRecord::Base>#implicit_order_column
    2000 ( 5.9%) Arel::Collectors::SQLString#add_bind
    2000 ( 5.9%) ActiveSupport::Notifications::Instrumenter#instrument
    2000 ( 5.9%) ActiveSupport::Concurrency::ShareLock#yield_shares
    2000 ( 5.9%) Kernel#loop
    2000 ( 5.9%) MonitorMixin::ConditionVariable#wait_while
    2000 ( 5.9%) ActiveRecord::Base#__callbacks
    2000 ( 5.9%) #<Class:ActiveRecord::Base>#__callbacks
    1000 ( 2.9%) #<Class:ActiveRecord::Base>#default_scopes
    1000 ( 2.9%) #<Class:ActiveRecord::Base>#_reflections
    1000 ( 2.9%) #<Class:ActiveRecord::Base>#aggregate_reflections
    1000 ( 2.9%) ActiveRecord::Relation#skip_query_cache_if_necessary
    1000 ( 2.9%) #<ActiveModel::Type::Helpers::AcceptsMultiparameterTime:0x00007f9e5eb71ae8>#cast
       2 ( 0.0%) #<Class:Benchmark>#realtime


After:
Top 5 block calls from C (100.0% of all 19000 calls):
    9000 (47.4%) Hash#each_key
    4000 (21.1%) #<Class:Thread>#handle_interrupt
    3000 (15.8%) Array#map
    2000 (10.5%) Monitor#synchronize
    1000 ( 5.3%) Integer#upto

Top 12 block calls from Ruby (100.0% of all 29041 calls):
   11032 (38.0%) Array#each
    4000 (13.8%) #<Class:ActiveRecord::Base>#default_connection_handler
    2000 ( 6.9%) ActiveSupport::Notifications::Instrumenter#instrument
    2000 ( 6.9%) ActiveSupport::Concurrency::ShareLock#yield_shares
    2000 ( 6.9%) Kernel#loop
    2000 ( 6.9%) MonitorMixin::ConditionVariable#wait_while
    2000 ( 6.9%) ActiveRecord::Base#__callbacks
    2000 ( 6.9%) #<Class:ActiveRecord::Base>#__callbacks
    1000 ( 3.4%) #<Class:ActiveRecord::Base>#default_scopes
    1000 ( 3.4%) #<ActiveModel::Type::Helpers::AcceptsMultiparameterTime:0x00007fb178afc3b0>#cast
       7 ( 0.0%) ActiveSupport::Dependencies::Loadable#load_dependency
       2 ( 0.0%) #<Class:Benchmark>#realtime

Are Array#map! and Hash#select no longer used? Or maybe the instrumentation is somehow missing some?
I'd expect at least a few blocks and even nested blocks on the call stack but it seems with that output there is barely any block being called (or specifically very few distinct blocks called in the benchmarks).

I think actually it's mostly a different reporting than I would expect, e.g. it doesn't show block in foo that's passed to Array#each but it shows Array#each. So then of course it gives the impression of very few blocks when there are far more but they are grouped by their caller, undistinguished.
The number of block calls is independent of this reporting so that's probably correct, it's just the presentation of the data confused me.

Show stats about call types and block calls by Ruby

9567455

k0kubun requested a review from a team January 11, 2023 19:54

Reorder and relabel stats

f6955d8

maximecb merged commit 17e4c55 into ruby:main Jan 12, 2023

k0kubun deleted the block-stats branch January 12, 2023 17:19

k0kubun mentioned this pull request Jan 31, 2023

YJIT: Implement codegen for Kernel#block_given? ruby/ruby#7202

Merged

This was referenced Feb 13, 2023

Unblock inlining and reduce interp_return by rewriting C methods in Ruby Shopify/ruby#493

Closed

YJIT: Optimize != for Integers and Strings ruby/ruby#7301

Merged

maximecb mentioned this pull request Feb 22, 2023

List of YJIT experiments and takeaways Shopify/ruby#497

Closed

k0kubun mentioned this pull request Mar 13, 2023

YJIT: Introduce no_gc attribute ruby/ruby#7511

Merged

k0kubun mentioned this pull request Sep 6, 2023

Rewrite Integer#times in Ruby ruby/ruby#8388

Merged

k0kubun mentioned this pull request Jan 20, 2024

YJIT: Allow inlining ISEQ calls with a block ruby/ruby#9622

Merged

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Uh oh!

Show stats about call types and block calls by Ruby #168

Show stats about call types and block calls by Ruby #168

Uh oh!

k0kubun commented Jan 11, 2023 •

edited

Loading

Uh oh!

maximecb commented Jan 11, 2023

Uh oh!

k0kubun commented Jan 11, 2023

Uh oh!

k0kubun commented Jan 11, 2023

Uh oh!

eregon commented Jan 12, 2023 •

edited

Loading

Uh oh!

maximecb commented Jan 12, 2023

Uh oh!

k0kubun commented Jan 24, 2024

Uh oh!

eregon commented Jan 24, 2024

Uh oh!

Reviewers

Assignees

Labels

Projects

Milestone

Development

Uh oh!

3 participants

Show stats about call types and block calls by Ruby #168

Show stats about call types and block calls by Ruby #168

Uh oh!

Conversation

k0kubun commented Jan 11, 2023 • edited Loading Uh oh! There was an error while loading. Please reload this page.

Uh oh!

Example

activerecord

hexapdf

liquid-render

mail

psych-load

railsbench

ruby-lsp

sequel

Uh oh!

maximecb commented Jan 11, 2023

Uh oh!

k0kubun commented Jan 11, 2023

Uh oh!

k0kubun commented Jan 11, 2023

Uh oh!

eregon commented Jan 12, 2023 • edited Loading Uh oh! There was an error while loading. Please reload this page.

Uh oh!

activerecord

hexapdf

liquid-render

mail

psych-load

railsbench

ruby-lsp

sequel

Average of the above benchmarks

Uh oh!

maximecb commented Jan 12, 2023

Uh oh!

k0kubun commented Jan 24, 2024

Uh oh!

eregon commented Jan 24, 2024

Uh oh!

Reviewers

Assignees

Labels

Projects

Milestone

Development

Uh oh!

3 participants

k0kubun commented Jan 11, 2023 •

edited

Loading

eregon commented Jan 12, 2023 •

edited

Loading