Output request time spend and parameters in log for Grape

grape_runtime_log.rb

require 'grape'

class API < Grape::API
  before do
    @log_start_t = Time.now
    Rails.logger.info "  Parameters: #{params.to_hash.except("route_info")}"
  end
  
  after do
    @log_end_t = Time.now
    total_runtime = ((@log_end_t -  @log_start_t) * 1000).round(1)
    db_runtime = (ActiveRecord::RuntimeRegistry.sql_runtime || 0).round(1)
    Rails.logger.info "Completed in #{total_runtime}ms (ActiveRecord: #{db_runtime}ms)"
  end
end

And then, the output log will be:

Started GET "/api/v1/photos/5323" for 127.0.0.1 at 2014-01-08 14:49:57 +0800
  Parameters: {"id"=>"5323"}
   (5.3ms)  SELECT COUNT(*) FROM `user_like_photos` WHERE `user_like_photos`.`user_id` = 26 AND `user_like_photos`.`photo_id` = 5323
   (14.4ms)  SELECT COUNT(*) FROM `user_favorite_photos` WHERE `user_favorite_photos`.`user_id` = 26 AND `user_favorite_photos`.`photo_id` = 5323
Completed in 24.9ms (ActiveRecord: 19.8ms)

created a gem for grape with exact this functionality, check it out: https://rubygems.org/gems/grape_logging