2009年6月22日 - kaeruspoon

« 2009年06月21日 | 2009年07月04日 »

Rails2.3ではログのレスポンスタイムからRackの処理時間が抜けている 2009-06-22 19:33:30
Rails2.3からwebサーバとのmiddlewareとしてRackが使われていますが、Rackでの処理時間はRailsのログには記録されません。
```
class RealLog
  def initialize(app)
    @app = app
    @logger = Logger.new("realtime.log")
  end

  def call(env)
    start_time = Time.now
    ret = @app.call(env)
    @logger.info "[#{Time.now - start_time}] #{env["PATH_INFO"] || env["REQUEST_URI"]}"
    ret
  end
end
```
こんな計測ツールをRackの一番上に積んでみます。
config/environment.rb
```
ActionController::Dispatcher.middleware.insert 0, RealLog
```
適当なページにアクセスして、Railsログの時間とrealtime.logに記録された時間を比べると、500msくらいの差がありました。
Railsのログだけみて、レスポンスが早くなったと考えるのは早計のようです。
コメント(0) リンク元(101)

« 2009年06月21日 | 2009年07月04日 »