gem env

RubyGems Environment:
  - RUBYGEMS VERSION: 2.2.2
  - RUBY VERSION: 2.1.3 (2014-09-19 patchlevel 242) [x86_64-linux]
  - INSTALLATION DIRECTORY: /home/rwh/.gem/ruby/2.1.3
  - RUBY EXECUTABLE: /opt/rubies/ruby-2.1.3/bin/ruby
  - EXECUTABLE DIRECTORY: /home/rwh/.gem/ruby/2.1.3/bin
  - SPEC CACHE DIRECTORY: /home/rwh/.gem/specs
  - RUBYGEMS PLATFORMS:
    - ruby
    - x86_64-linux
  - GEM PATHS:
     - /home/rwh/.gem/ruby/2.1.3
     - /opt/rubies/ruby-2.1.3/lib/ruby/gems/2.1.0
  - GEM CONFIGURATION:
     - :update_sources => true
     - :verbose => true
     - :backtrace => false
     - :bulk_threshold => 1000
     - :sources => ["https://rubygems.org/", "http://localhost:9292/"]
  - REMOTE SOURCES:
     - https://rubygems.org/
     - http://localhost:9292/
  - SHELL PATH:
     - /home/rwh/.gem/ruby/2.1.3/bin
     - /opt/rubies/ruby-2.1.3/lib/ruby/gems/2.1.0/bin
     - /opt/rubies/ruby-2.1.3/bin
     - /usr/local/bin
     - /usr/bin
     - /bin
     - /usr/local/games
     - /usr/games

root gem env

# gem env
RubyGems Environment:
  - RUBYGEMS VERSION: 2.2.2
  - RUBY VERSION: 2.1.3 (2014-09-19 patchlevel 242) [x86_64-linux]
  - INSTALLATION DIRECTORY: /opt/rubies/ruby-2.1.3/lib/ruby/gems/2.1.0
  - RUBY EXECUTABLE: /opt/rubies/ruby-2.1.3/bin/ruby
  - EXECUTABLE DIRECTORY: /opt/rubies/ruby-2.1.3/bin
  - SPEC CACHE DIRECTORY: /root/.gem/specs
  - RUBYGEMS PLATFORMS:
    - ruby
    - x86_64-linux
  - GEM PATHS:
     - /opt/rubies/ruby-2.1.3/lib/ruby/gems/2.1.0
     - /root/.gem/ruby/2.1.0
  - GEM CONFIGURATION:
     - :update_sources => true
     - :verbose => true
     - :backtrace => false
     - :bulk_threshold => 1000
     - :sources => ["https://rubygems.org/", "http://localhost:9292/"]
  - REMOTE SOURCES:
     - https://rubygems.org/
     - http://localhost:9292/
  - SHELL PATH:
     - /opt/rubies/ruby-2.1.3/bin
     - /usr/local/sbin
     - /usr/local/bin
     - /usr/sbin
     - /usr/bin
     - /sbin
     - /bin

root rprof output

Measure Mode: wall_time
Thread ID: 69903640482600
Fiber ID: 69903644581980
Total: 0.301950
Sort by: self_time

 %self      total      self      wait     child     calls  name
  5.16      0.090     0.016     0.000     0.075     1909   MIME::Type#init_with
  3.72      0.011     0.011     0.000     0.000        1   JSON::Ext::Parser#parse
  2.73      0.008     0.008     0.000     0.000    11454   String#gsub!
  2.55      0.010     0.008     0.000     0.002    26204   Hash#[]
  1.73      0.101     0.005     0.000     0.096     1909   MIME::Type#initialize
  1.68      0.005     0.005     0.000     0.000     7636   MatchData#captures
  1.59      0.016     0.005     0.000     0.011     1909   <Class::MIME::Type>#i18n_key
  1.39      0.013     0.004     0.000     0.008     1909   MIME::Type#xrefs=
  1.39      0.009     0.004     0.000     0.005     1909   <Class::MIME::Type>#simplified
  1.38      0.004     0.004     0.000     0.000     3818   Regexp#match
  1.36      0.035     0.004     0.000     0.031     1909   MIME::Type#content_type=
  1.22      0.264     0.004     0.000     0.261      266  *Kernel#gem_original_require
  1.12      0.003     0.003     0.000     0.000    19531   Module#===
  1.08      0.009     0.003     0.000     0.006     1232   Enumerable#find_all

ruby-prof output

# this is essentially (99.99%) just requiring some files and installed gems
# it takes well under a second as root, and 13 seconds without rprof
# and obviously here it took 50 seconds with rprof

Measure Mode: wall_time
Thread ID: 70344739337000
Fiber ID: 70344742750280
Total: 50.128534
Sort by: self_time

 %self      total      self      wait     child     calls  name
 23.24     25.780    11.649     0.000    14.131  3216308   Enumerable#find_all
 13.12     12.986     6.577     0.000     6.409  3244339  *Array#select
 12.70      6.365     6.365     0.000     0.000 27724182   Gem::StubSpecification#name
  8.17      4.098     4.098     0.000     0.000 18652805   Gem::Dependency#type
  6.70      3.360     3.360     0.000     0.000 27698625   String#===
  4.61      2.311     2.311     0.000     0.000 18654750   Symbol#==
  3.10     18.146     1.553     0.000    16.593   190571   Gem::Specification#conflicts
  2.69     15.160     1.350     0.000    13.810  3244307   Gem::Specification#runtime_dependencies
  2.04      2.573     1.024     0.000     1.549   329285   Gem::Version#<=>
  1.64      0.824     0.824     0.000     0.000  3244307   Gem::Specification#dependencies
  1.39      0.699     0.699     0.000     0.000   596861   <Class::File>#file?
  1.31      1.943     0.658     0.000     1.284   670817  *Enumerable#any?





# updated rubygems to 2.4.8

Measure Mode: wall_time
Thread ID: 70163333559080
Fiber ID: 70163336810580
Total: 26.626076
Sort by: self_time

 %self      total      self      wait     child     calls  name
 33.05     20.855     8.799     0.000    12.056   163066   Enumerable#find_all
 21.77      5.796     5.796     0.000     0.000 26579723   Gem::StubSpecification#name
 11.83      3.150     3.150     0.000     0.000 26557609   String#===
  3.55      1.826     0.944     0.000     0.882   322146   Gem::Version#<=>
  3.03      1.579     0.806     0.000     0.773   381670  *Array#select
  1.91      0.509     0.509     0.000     0.000  2201719   Gem::Dependency#type
  1.17      0.434     0.311     0.000     0.123   291725  *Enumerable#any?
  1.15     22.604     0.306     0.000    22.297   162930   Gem::Dependency#matching_specs
  1.13      0.301     0.301     0.000     0.000  2397357   Symbol#==
  1.08      0.288     0.288     0.000     0.000  1682388   Module#===
  1.03      0.545     0.274     0.000     0.271   162931   Enumerable#sort_by
  1.01      2.910     0.269     0.000     2.641   201135   Enumerable#all?