2009-05-07 15:10:26 -04:00
|
|
|
#!/usr/bin/env ruby
|
|
|
|
# Example:
|
2009-05-13 14:55:19 -04:00
|
|
|
# tools/profile_requires activesupport/lib/active_support.rb
|
2009-05-07 15:10:26 -04:00
|
|
|
abort 'Use REE so you can profile memory and object allocation' unless GC.respond_to?(:enable_stats)
|
|
|
|
|
2009-05-21 13:03:32 -04:00
|
|
|
ENV['NO_RELOAD'] ||= '1'
|
|
|
|
ENV['RAILS_ENV'] ||= 'development'
|
|
|
|
|
2009-05-07 15:10:26 -04:00
|
|
|
GC.enable_stats
|
|
|
|
Gem.source_index
|
|
|
|
require 'benchmark'
|
|
|
|
|
2009-05-21 13:03:32 -04:00
|
|
|
module RequireProfiler
|
2010-04-24 13:26:09 -04:00
|
|
|
private
|
2009-05-21 13:03:32 -04:00
|
|
|
def require(file, *args) RequireProfiler.profile(file) { super } end
|
|
|
|
def load(file, *args) RequireProfiler.profile(file) { super } end
|
|
|
|
|
|
|
|
@depth, @stats = 0, []
|
2009-05-07 15:10:26 -04:00
|
|
|
class << self
|
2009-05-21 13:03:32 -04:00
|
|
|
attr_accessor :depth
|
2009-05-07 15:10:26 -04:00
|
|
|
attr_accessor :stats
|
|
|
|
|
2009-05-21 13:03:32 -04:00
|
|
|
def profile(file)
|
|
|
|
stats << [file, depth]
|
|
|
|
self.depth += 1
|
|
|
|
heap_before, objects_before = GC.allocated_size, ObjectSpace.allocated_objects
|
|
|
|
result = nil
|
|
|
|
elapsed = Benchmark.realtime { result = yield }
|
|
|
|
heap_after, objects_after = GC.allocated_size, ObjectSpace.allocated_objects
|
|
|
|
self.depth -= 1
|
|
|
|
stats.pop if stats.last.first == file
|
|
|
|
stats << [file, depth, elapsed, heap_after - heap_before, objects_after - objects_before] if result
|
|
|
|
result
|
|
|
|
end
|
2009-05-07 15:10:26 -04:00
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
GC.start
|
|
|
|
before = GC.allocated_size
|
2010-06-23 19:11:07 -04:00
|
|
|
before_gctime, before_gcruns = GC.time, GC.collections
|
2009-05-07 15:10:26 -04:00
|
|
|
before_rss = `ps -o rss= -p #{Process.pid}`.to_i
|
|
|
|
before_live_objects = ObjectSpace.live_objects
|
|
|
|
|
|
|
|
path = ARGV.shift
|
|
|
|
if mode = ARGV.shift
|
|
|
|
require 'ruby-prof'
|
|
|
|
RubyProf.measure_mode = RubyProf.const_get(mode.upcase)
|
|
|
|
RubyProf.start
|
2009-05-21 13:03:32 -04:00
|
|
|
else
|
|
|
|
Object.instance_eval { include RequireProfiler }
|
2009-05-07 15:10:26 -04:00
|
|
|
end
|
|
|
|
|
|
|
|
elapsed = Benchmark.realtime { require path }
|
|
|
|
results = RubyProf.stop if mode
|
|
|
|
|
2010-06-23 19:11:07 -04:00
|
|
|
after_gctime, after_gcruns = GC.time, GC.collections
|
2009-05-07 15:10:26 -04:00
|
|
|
GC.start
|
|
|
|
after_live_objects = ObjectSpace.live_objects
|
|
|
|
after_rss = `ps -o rss= -p #{Process.pid}`.to_i
|
|
|
|
after = GC.allocated_size
|
|
|
|
usage = (after - before) / 1024.0
|
|
|
|
|
|
|
|
if mode
|
2010-06-24 04:08:12 -04:00
|
|
|
if printer = ARGV.shift
|
|
|
|
RubyProf.const_get("#{printer.to_s.classify}Printer").new(results).print($stdout)
|
|
|
|
elsif RubyProf.const_defined?(:CallStackPrinter)
|
2010-04-04 14:56:49 -04:00
|
|
|
File.open("#{File.basename(path, '.rb')}.#{mode}.html", 'w') do |out|
|
|
|
|
RubyProf::CallStackPrinter.new(results).print(out)
|
|
|
|
end
|
|
|
|
else
|
|
|
|
File.open("#{File.basename(path, '.rb')}.#{mode}.callgrind", 'w') do |out|
|
|
|
|
RubyProf::CallTreePrinter.new(results).print(out)
|
|
|
|
end
|
2009-05-07 15:10:26 -04:00
|
|
|
end
|
|
|
|
end
|
|
|
|
|
2009-05-21 13:03:32 -04:00
|
|
|
RequireProfiler.stats.each do |file, depth, sec, bytes, objects|
|
2009-05-12 20:55:45 -04:00
|
|
|
if sec
|
2009-05-21 13:03:32 -04:00
|
|
|
puts "%10.2f KB %10d obj %8.1f ms %s%s" % [bytes / 1024.0, objects, sec * 1000, ' ' * depth, file]
|
2009-05-12 20:55:45 -04:00
|
|
|
else
|
2009-05-21 13:03:32 -04:00
|
|
|
puts "#{' ' * (42 + depth)}#{file}"
|
2009-05-12 20:55:45 -04:00
|
|
|
end
|
2009-05-07 15:10:26 -04:00
|
|
|
end
|
2010-06-23 19:11:07 -04:00
|
|
|
puts "%10.2f KB %10d obj %8.1f ms %d KB RSS %8.1f ms GC time %d GC runs" % [usage, after_live_objects - before_live_objects, elapsed * 1000, after_rss - before_rss, (after_gctime - before_gctime) / 1000.0, after_gcruns - before_gcruns]
|