Logging is a powerful and often undervalued tool in software development. When done right, it's a great way to document code, and it provides a simple & effective way to solve problems when things go awry. All an important part of maintainable code.
Catamaran was built around a named hierarchy and level inheritance, and with performance in mind.
gem 'catamaran', '~> 2.7.0'
Rails-related setup:
rails g catamaran:install
Now modify config/initializers/catamaran/development.rb as needed
require 'catamaran'
class QuickstartWithRuby
LOGGER = Catamaran.logger( "com.mytld.QuickstartWithRuby" )
# or equivalently:
# LOGGER = Catamaran.logger.com.mytld.QuickstartWithRuby
def demonstrating_log_levels
# Disabled by default
LOGGER.trace( "TRACE logs are NOT captured by default" ) if LOGGER.trace?
LOGGER.debug( "DEBUG logs are NOT captured by default" ) if LOGGER.debug?
LOGGER.info( "INFO logs are NOT captured by default" ) if LOGGER.info?
# Enabled by default
LOGGER.notice( "NOTICE logs are captured by default" )
LOGGER.warn( "WARN logs are captured by default" )
LOGGER.error( "ERROR logs are captured by default" )
LOGGER.severe( "SEVERE logs are captured by default" )
LOGGER.fatal( "FATAL logs are captured by default" )
end
def using_the_caller
# Enable the caller (it's disabled by default)
Catamaran::Manager.formatter_caller_enabled = true
LOGGER.notice( "The caller will append log location info to this message" )
LOGGER.notice( "If the user specifies the :file, :line, AND :method, the caller will NOT get invoked", { :file => __FILE__, :line => __LINE__, :method => 'run' } )
LOGGER.notice( "To prove the caller is not used, we can put dummy data in and see that it's being used instead", { :file => 'just_kidding.rb', :line => 123456789, :method => 'whatever' } )
# Turn it back off
Catamaran::Manager.formatter_caller_enabled = false
end
def changing_the_log_level
# Note that the log level can be changed
Catamaran.logger.log_level = Catamaran::LogLevel::DEBUG
Catamaran::Manager.forget_cached_log_levels()
LOGGER.trace( "TRACE logs are STILL NOT captured" ) if LOGGER.trace?
LOGGER.debug( "Now DEBUG messages should show up" ) if LOGGER.debug?
end
def displaying_backtrace_information
Catamaran.logger.log_level = Catamaran::LogLevel::NOTICE
Catamaran.logger.backtrace_log_level = Catamaran::LogLevel::ERROR
Catamaran::Manager.forget_cached_log_levels()
LOGGER.debug( "Sample DEBUG statement with backtrace requested", { :backtrace => true } )
LOGGER.warn( "Sample WARN statement with backtrace requested", { :backtrace => true } )
LOGGER.error( "Sample ERROR statement with backtrace requested", { :backtrace => true } )
end
end
quickstart_with_ruby = QuickstartWithRuby.new
quickstart_with_ruby.demonstrating_log_levels
quickstart_with_ruby.using_the_caller
quickstart_with_ruby.changing_the_log_level
quickstart_with_ruby.displaying_backtrace_informationAnd the output
NOTICE pid-50247 [2014-01-07 13:22:28:516] com.mytld.QuickstartWithRuby - NOTICE logs are captured by default
WARN pid-50247 [2014-01-07 13:22:28:516] com.mytld.QuickstartWithRuby - WARN logs are captured by default
ERROR pid-50247 [2014-01-07 13:22:28:516] com.mytld.QuickstartWithRuby - ERROR logs are captured by default
SEVERE pid-50247 [2014-01-07 13:22:28:516] com.mytld.QuickstartWithRuby - SEVERE logs are captured by default
FATAL pid-50247 [2014-01-07 13:22:28:516] com.mytld.QuickstartWithRuby - FATAL logs are captured by default
NOTICE pid-50247 [2014-01-07 13:22:28:516] com.mytld.QuickstartWithRuby - The caller will append log location info to this message (quickstart_with_ruby.rb:27:in `using_the_caller')
NOTICE pid-50247 [2014-01-07 13:22:28:516] com.mytld.QuickstartWithRuby - If the user specifies the :file, :line, AND :method, the caller will NOT get invoked (quickstart_with_ruby.rb:28:in `run')
NOTICE pid-50247 [2014-01-07 13:22:28:517] com.mytld.QuickstartWithRuby - To prove the caller is not used, we can put dummy data in and see that it's being used instead (just_kidding.rb:123456789:in `whatever')
DEBUG pid-50247 [2014-01-07 13:22:28:517] com.mytld.QuickstartWithRuby - Now DEBUG messages should show up
WARN pid-50247 [2014-01-07 13:22:28:517] com.mytld.QuickstartWithRuby - Sample WARN statement with backtrace requested
ERROR pid-50247 [2014-01-07 13:22:28:517] com.mytld.QuickstartWithRuby - Sample ERROR statement with backtrace requested from:
quickstart_with_ruby.rb:51:in `displaying_backtrace_information'
quickstart_with_ruby.rb:59:in `<main>'
class WidgetsController < ApplicationController
LOGGER = Catamaran.logger.com.mytld.myrailsapp.app.controllers.WidgetsController
def index
LOGGER.debug "Handling a Widgets index request with params = #{params}" if LOGGER.debug?
end
endLoad the index page and check out your development.log file
DEBUG pid-86000 [2013-12-17 17:26:39:176] ld.myrailsapp.app.controllers.WidgetsController - "Handling a Widgets index request with params = {"controller"=>"widgets", "action"=>"index"} (`/myrailsapp/app/controllers/widgets_controller.rb:7`:in `index`)
See the examples folder.
Available log levels: TRACE (verbose and trivial log messages), DEBUG, INFO, NOTICE, WARN, ERROR, SEVERE (logs related to very serious error conditions), FATAL
The NOTICE log level severity is the default. Any logs with NOTICE or higher severity will be captured.
- Years ago we used log4j with Java. It worked great. It made use of Named Hierarchy and Level Inheritance which seemed well thought out.
- It's valuable to be able to enable verbose logging in targetted areas (but not all areas) of the application. For instance, I might want to enable verbose DEBUG logs in the models but not in the controllers. That was easy with log4j.
- Log messages should have graunlar timestamps (milliseconds), process IDs, and location information embedded in them. That was easy with log4j.
- There needs to be more than one log severity available for "debugging stuff." Some debug log statements are too verbose for every day use. log4j handled this by creating another log level,
TRACE, that was less-critical thanDEBUG. - Many of the Ruby logging tools don't seem to put any emphasis on performance. With potentially large number of log statements being traversed on every request, I think it's a valuable consideration.
- I was looking for a utility that works equally well with or without Rails
- log4r seemed like a natural choice coming from log4j. But at the time, log4r wasn't actively being worked on. It seems they've recently restarted development.
- I started thinking about Ruby logging a few years ago. I wasn't aware of TwP/logging until recently.
$: << '../lib'
require 'catamaran'
require 'benchmark'
Catamaran.logger.log_level = Catamaran::LogLevel::INFO
Catamaran::Manager.stderr = false
class BenchmarkingConditionalLogStatements
LOGGER = Catamaran.logger( "BenchmarkingConditionalLogStatements" )
# NOTE that the log level for this test is set to INFO,
# so 'warn' logs are enabled and 'debug' logs are disabled
n = 500000
Benchmark.bm(7) do |x|
x.report("LOGGER.warn WITHOUT if LOGGER.warn? ") {
n.times do |i|
LOGGER.warn "Based on the current log level, this log is being captured"
end
}
x.report("LOGGER.warn WITH if LOGGER.warn? ") {
n.times do |i|
LOGGER.warn "Based on the current log level, this log is being captured" if LOGGER.warn?
end
}
x.report("LOGGER.debug WITHOUT if LOGGER.debug?") {
n.times do |i|
LOGGER.debug "Based on the current log level, this log is NOT being captured"
end
}
x.report("LOGGER.debug WITH if LOGGER.debug? ") {
n.times do |i|
LOGGER.debug "Based on the current log level, this log is NOT being captured" if LOGGER.debug?
end
}
end
end
# user system total real
# LOGGER.warn WITHOUT if LOGGER.warn? 6.440000 0.090000 6.530000 ( 6.533838)
# LOGGER.warn WITH if LOGGER.warn? 7.110000 0.120000 7.230000 ( 7.242870)
# LOGGER.debug WITHOUT if LOGGER.debug? 0.530000 0.020000 0.550000 ( 0.548454)
# LOGGER.debug WITH if LOGGER.debug? 0.450000 0.030000 0.480000 ( 0.474419)- For log messages that are usually disabled in the production environment ( TRACE, DEBUG, INFO ), it's generally better to invoke the log only after confirming that the log level is enabled
ex:
LOGGER.trace "This is a TRACE log" if LOGGER.trace?
LOGGER.debug "This is a DEBUG log" if LOGGER.debug?
LOGGER.info "This is a INFO log" if LOGGER.info?- For log messages that are usually enabled in the production environment ( NOTICE, WARN, ERROR, SEVERE, FATAL ), it's generally better to invoke the log WITHOUT first testing the log level
ex:
LOGGER.notice "This is a NOTICE log"
LOGGER.warn "This is a WARN log"
LOGGER.error "This is a ERROR log"
LOGGER.severe "This is a SEVERE log"
LOGGER.fatal "This is a FATAL log" require 'catamaran'
require 'benchmark'
Catamaran::Manager.stderr = false
class CatamaranCallerBenchmark
LOGGER = Catamaran.logger( "CatamaranCallerBenchmark" )
n = 500000
Benchmark.bm(7) do |x|
Catamaran::Manager.formatter_caller_enabled = false
x.report("Catamaran::Manager.formatter_caller_enabled = false") {
n.times do |i|
LOGGER.error "This is a ERROR log"
end
}
Catamaran::Manager.formatter_caller_enabled = true
x.report("Catamaran::Manager.formatter_caller_enabled = true ") {
n.times do |i|
LOGGER.error "This is a ERROR log"
end
}
end
end
# user system total real
# Catamaran::Manager.formatter_caller_enabled = false 6.710000 0.060000 6.770000 ( 6.780703)
# Catamaran::Manager.formatter_caller_enabled = true 13.940000 0.400000 14.340000 ( 14.345921)Using the caller() is slower but generate more details in the log statements
WARN pid-4407 [2013-12-26 14:18:19:697] FirstRubyDemo - Note that WARN messages are getting logged
WARN pid-4410 [2013-12-26 14:18:38:276] FirstRubyDemo - Note that WARN messages are getting logged (catamaran_ruby_demos.rb:12:in `run')
Because of this performance difference, the caller() is disabled by default. Though enabling it tends to be especially useful in the development and test environments when debugging problems during the course of functional testing.
require 'catamaran'
require 'ruby-prof'
class CatamaranProfilerTest
LOGGER = Catamaran.logger( "com.mycompany.CatamaranProfilerTest" )
def run
# Disabled by default
LOGGER.trace( "TRACE logs are NOT captured by default" ) if LOGGER.trace?
LOGGER.debug( "DEBUG logs are NOT captured by default" ) if LOGGER.debug?
LOGGER.info( "INFO logs are NOT captured by default" ) if LOGGER.info?
# Enabled by default
LOGGER.notice( "NOTICE logs are captured by default" )
LOGGER.warn( "WARN logs are captured by default" )
LOGGER.error( "ERROR logs are captured by default" )
LOGGER.severe( "SEVERE logs are captured by default" )
LOGGER.fatal( "FATAL logs are captured by default" )
end
end
RubyProf.start
catamaran_profiler_test = CatamaranProfilerTest.new
1000.times do
demo.run
end
result = RubyProf.stop
printer = RubyProf::GraphPrinter.new(result)
printer.print(STDOUT)Thread ID: 2152122180
Fiber ID: 2164584480
Total Time: 0.69042
Sort by: total_time
%total %self total self wait child calls Name
--------------------------------------------------------------------------------
100.00% 0.01% 0.690 0.000 0.000 0.690 1 Global#[No method]
0.690 0.003 0.000 0.687 1/1 Integer#times
0.000 0.000 0.000 0.000 1/1 Class#new
--------------------------------------------------------------------------------
0.690 0.003 0.000 0.687 1/1 Global#[No method]
99.99% 0.46% 0.690 0.003 0.000 0.687 1 Integer#times
0.687 0.023 0.000 0.664 1000/4000 WithTheRubyProfiler#run
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 3000/4000 WithTheRubyProfiler#run
0.687 0.023 0.000 0.664 1000/4000 Integer#times
99.53% 3.38% 0.687 0.023 0.000 0.664 4000 *WithTheRubyProfiler#run
0.134 0.006 0.000 0.128 1000/2000 Catamaran::Logger#error
0.134 0.006 0.000 0.128 1000/2000 Catamaran::Logger#notice
0.131 0.006 0.000 0.125 1000/2000 Catamaran::Logger#warn
0.129 0.006 0.000 0.123 1000/2000 Catamaran::Logger#fatal
0.129 0.006 0.000 0.123 1000/2000 Catamaran::Logger#severe
0.000 0.000 0.000 0.000 3/11 Catamaran::Logger#log_level
0.000 0.000 0.000 0.000 3/8 Module#define_method
0.000 0.000 0.000 0.000 3/8 <Class::Catamaran::LogLevel>#symbol_to_severity
0.000 0.000 0.000 0.000 3/8 String#to_sym
0.000 0.000 0.000 0.000 3/8 Symbol#to_s
0.000 0.000 0.000 0.000 3000/4000 WithTheRubyProfiler#run
--------------------------------------------------------------------------------
0.120 0.007 0.000 0.113 1000/5000 Catamaran::Logger#severe
0.121 0.007 0.000 0.114 1000/5000 Catamaran::Logger#fatal
0.122 0.007 0.000 0.115 1000/5000 Catamaran::Logger#warn
0.125 0.007 0.000 0.118 1000/5000 Catamaran::Logger#error
0.125 0.007 0.000 0.118 1000/5000 Catamaran::Logger#notice
88.77% 5.03% 0.613 0.035 0.000 0.578 5000 Catamaran::Logger#log
0.416 0.025 0.000 0.391 5000/5000 Catamaran::Logger#_format_msg
0.163 0.062 0.000 0.101 5000/5000 <Class::Catamaran::Outputter>#write
--------------------------------------------------------------------------------
0.416 0.025 0.000 0.391 5000/5000 Catamaran::Logger#log
60.19% 3.55% 0.416 0.025 0.000 0.391 5000 Catamaran::Logger#_format_msg
0.329 0.073 0.000 0.256 5000/5000 <Class::Catamaran::Formatter>#construct_formatted_message
0.062 0.036 0.000 0.026 5000/5000 Catamaran::Logger#path_to_s
--------------------------------------------------------------------------------
0.329 0.073 0.000 0.256 5000/5000 Catamaran::Logger#_format_msg
47.71% 10.63% 0.329 0.073 0.000 0.256 5000 <Class::Catamaran::Formatter>#construct_formatted_message
0.256 0.077 0.000 0.179 5000/5000 <Class::Catamaran::Formatter>#construct_favorite_pattern_number_1
0.000 0.000 0.000 0.000 1/1 <Class::Catamaran::Manager>#formatter_pattern
--------------------------------------------------------------------------------
0.256 0.077 0.000 0.179 5000/5000 <Class::Catamaran::Formatter>#construct_formatted_message
37.08% 11.17% 0.256 0.077 0.000 0.179 5000 <Class::Catamaran::Formatter>#construct_favorite_pattern_number_1
0.085 0.061 0.000 0.024 5000/5000 Time#strftime
0.038 0.017 0.000 0.021 5000/5000 <Class::Time>#now
0.025 0.025 0.000 0.000 5000/5000 Kernel#sprintf
0.015 0.015 0.000 0.000 5000/5000 <Class::Catamaran::LogLevel>#severity_to_s
0.009 0.009 0.000 0.000 5000/5000 Fixnum#to_s
0.007 0.007 0.000 0.000 5000/5000 <Module::Process>#pid
--------------------------------------------------------------------------------
0.163 0.062 0.000 0.101 5000/5000 Catamaran::Logger#log
23.56% 8.97% 0.163 0.062 0.000 0.101 5000 <Class::Catamaran::Outputter>#write
0.090 0.021 0.000 0.069 5000/5000 IO#puts
0.011 0.011 0.000 0.000 9998/10015 Kernel#nil?
0.000 0.000 0.000 0.000 1/1 <Class::Catamaran::Manager>#stderr?
0.000 0.000 0.000 0.000 1/1 <Class::Catamaran::Manager>#stdout?
0.000 0.000 0.000 0.000 2/6 NilClass#nil?
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#error
0.134 0.006 0.000 0.128 1000/2000 WithTheRubyProfiler#run
19.40% 0.85% 0.134 0.006 0.000 0.128 2000 *Catamaran::Logger#error
0.125 0.007 0.000 0.118 1000/5000 Catamaran::Logger#log
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#log_level
0.000 0.000 0.000 0.000 1/8 <Class::Catamaran::LogLevel>#symbol_to_severity
0.000 0.000 0.000 0.000 1/8 Module#define_method
0.000 0.000 0.000 0.000 1/8 String#to_sym
0.000 0.000 0.000 0.000 1/8 Symbol#to_s
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#error
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#notice
0.134 0.006 0.000 0.128 1000/2000 WithTheRubyProfiler#run
19.38% 0.85% 0.134 0.006 0.000 0.128 2000 *Catamaran::Logger#notice
0.125 0.007 0.000 0.118 1000/5000 Catamaran::Logger#log
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#log_level
0.000 0.000 0.000 0.000 1/8 Symbol#to_s
0.000 0.000 0.000 0.000 1/8 Module#define_method
0.000 0.000 0.000 0.000 1/8 <Class::Catamaran::LogLevel>#symbol_to_severity
0.000 0.000 0.000 0.000 1/8 String#to_sym
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#notice
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#warn
0.131 0.006 0.000 0.125 1000/2000 WithTheRubyProfiler#run
18.91% 0.86% 0.131 0.006 0.000 0.125 2000 *Catamaran::Logger#warn
0.122 0.007 0.000 0.115 1000/5000 Catamaran::Logger#log
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#log_level
0.000 0.000 0.000 0.000 1/8 Module#define_method
0.000 0.000 0.000 0.000 1/8 <Class::Catamaran::LogLevel>#symbol_to_severity
0.000 0.000 0.000 0.000 1/8 String#to_sym
0.000 0.000 0.000 0.000 1/8 Symbol#to_s
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#warn
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#fatal
0.129 0.006 0.000 0.123 1000/2000 WithTheRubyProfiler#run
18.73% 0.85% 0.129 0.006 0.000 0.123 2000 *Catamaran::Logger#fatal
0.121 0.007 0.000 0.114 1000/5000 Catamaran::Logger#log
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#log_level
0.000 0.000 0.000 0.000 1/8 Module#define_method
0.000 0.000 0.000 0.000 1/8 Symbol#to_s
0.000 0.000 0.000 0.000 1/8 <Class::Catamaran::LogLevel>#symbol_to_severity
0.000 0.000 0.000 0.000 1/8 String#to_sym
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#fatal
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#severe
0.129 0.006 0.000 0.123 1000/2000 WithTheRubyProfiler#run
18.64% 0.86% 0.129 0.006 0.000 0.123 2000 *Catamaran::Logger#severe
0.120 0.007 0.000 0.113 1000/5000 Catamaran::Logger#log
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#log_level
0.000 0.000 0.000 0.000 1/8 Module#define_method
0.000 0.000 0.000 0.000 1/8 <Class::Catamaran::LogLevel>#symbol_to_severity
0.000 0.000 0.000 0.000 1/8 Symbol#to_s
0.000 0.000 0.000 0.000 1/8 String#to_sym
0.000 0.000 0.000 0.000 1000/2000 Catamaran::Logger#severe
--------------------------------------------------------------------------------
0.090 0.021 0.000 0.069 5000/5000 <Class::Catamaran::Outputter>#write
12.98% 3.01% 0.090 0.021 0.000 0.069 5000 IO#puts
0.069 0.069 0.000 0.000 10000/10000 IO#write
--------------------------------------------------------------------------------
0.085 0.061 0.000 0.024 5000/5000 <Class::Catamaran::Formatter>#construct_favorite_pattern_number_1
12.34% 8.89% 0.085 0.061 0.000 0.024 5000 Time#strftime
0.018 0.018 0.000 0.000 10000/10000 Fixnum#divmod
0.006 0.006 0.000 0.000 5000/5000 Fixnum#%
--------------------------------------------------------------------------------
0.069 0.069 0.000 0.000 10000/10000 IO#puts
9.97% 9.97% 0.069 0.069 0.000 0.000 10000 IO#write
--------------------------------------------------------------------------------
0.062 0.036 0.000 0.026 5000/5000 Catamaran::Logger#_format_msg
8.92% 5.16% 0.062 0.036 0.000 0.026 5000 Catamaran::Logger#path_to_s
0.026 0.026 0.000 0.000 5000/5000 Array#join
0.000 0.000 0.000 0.000 1/1 <Class::Catamaran::Manager>#delimiter
--------------------------------------------------------------------------------
0.038 0.017 0.000 0.021 5000/5000 <Class::Catamaran::Formatter>#construct_favorite_pattern_number_1
5.54% 2.51% 0.038 0.017 0.000 0.021 5000 <Class::Time>#now
0.021 0.015 0.000 0.006 5000/5000 Time#initialize
--------------------------------------------------------------------------------
0.026 0.026 0.000 0.000 5000/5000 Catamaran::Logger#path_to_s
3.77% 3.77% 0.026 0.026 0.000 0.000 5000 Array#join
--------------------------------------------------------------------------------
0.025 0.025 0.000 0.000 5000/5000 <Class::Catamaran::Formatter>#construct_favorite_pattern_number_1
3.62% 3.62% 0.025 0.025 0.000 0.000 5000 Kernel#sprintf
--------------------------------------------------------------------------------
0.021 0.015 0.000 0.006 5000/5000 <Class::Time>#now
3.03% 2.14% 0.021 0.015 0.000 0.006 5000 Time#initialize
0.006 0.006 0.000 0.000 5000/5000 Fixnum#+
--------------------------------------------------------------------------------
0.018 0.018 0.000 0.000 10000/10000 Time#strftime
2.56% 2.56% 0.018 0.018 0.000 0.000 10000 Fixnum#divmod
--------------------------------------------------------------------------------
0.015 0.015 0.000 0.000 5000/5000 <Class::Catamaran::Formatter>#construct_favorite_pattern_number_1
2.14% 2.14% 0.015 0.015 0.000 0.000 5000 <Class::Catamaran::LogLevel>#severity_to_s
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 17/10015 Catamaran::Logger#log_level
0.011 0.011 0.000 0.000 9998/10015 <Class::Catamaran::Outputter>#write
1.61% 1.61% 0.011 0.011 0.000 0.000 10015 Kernel#nil?
--------------------------------------------------------------------------------
0.009 0.009 0.000 0.000 5000/5000 <Class::Catamaran::Formatter>#construct_favorite_pattern_number_1
1.30% 1.30% 0.009 0.009 0.000 0.000 5000 Fixnum#to_s
--------------------------------------------------------------------------------
0.007 0.007 0.000 0.000 5000/5000 <Class::Catamaran::Formatter>#construct_favorite_pattern_number_1
0.97% 0.97% 0.007 0.007 0.000 0.000 5000 <Module::Process>#pid
--------------------------------------------------------------------------------
0.006 0.006 0.000 0.000 5000/5000 Time#initialize
0.90% 0.90% 0.006 0.006 0.000 0.000 5000 Fixnum#+
--------------------------------------------------------------------------------
0.006 0.006 0.000 0.000 5000/5000 Time#strftime
0.89% 0.89% 0.006 0.006 0.000 0.000 5000 Fixnum#%
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 3/11 Catamaran::Logger#log_level
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#notice
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#error
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#warn
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#fatal
0.000 0.000 0.000 0.000 1/11 Catamaran::Logger#severe
0.000 0.000 0.000 0.000 3/11 WithTheRubyProfiler#run
0.03% 0.02% 0.000 0.000 0.000 0.000 11 *Catamaran::Logger#log_level
0.000 0.000 0.000 0.000 17/10015 Kernel#nil?
0.000 0.000 0.000 0.000 4/6 NilClass#nil?
0.000 0.000 0.000 0.000 1/1 <Object::Object>#[]
0.000 0.000 0.000 0.000 3/11 Catamaran::Logger#log_level
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#notice
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#error
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#fatal
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#severe
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#warn
0.000 0.000 0.000 0.000 3/8 WithTheRubyProfiler#run
0.01% 0.01% 0.000 0.000 0.000 0.000 8 Module#define_method
0.000 0.000 0.000 0.000 8/8 BasicObject#singleton_method_added
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#notice
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#fatal
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#warn
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#severe
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#error
0.000 0.000 0.000 0.000 3/8 WithTheRubyProfiler#run
0.00% 0.00% 0.000 0.000 0.000 0.000 8 <Class::Catamaran::LogLevel>#symbol_to_severity
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#error
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#notice
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#fatal
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#severe
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#warn
0.000 0.000 0.000 0.000 3/8 WithTheRubyProfiler#run
0.00% 0.00% 0.000 0.000 0.000 0.000 8 String#to_sym
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#error
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#warn
0.000 0.000 0.000 0.000 3/8 WithTheRubyProfiler#run
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#severe
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#notice
0.000 0.000 0.000 0.000 1/8 Catamaran::Logger#fatal
0.00% 0.00% 0.000 0.000 0.000 0.000 8 Symbol#to_s
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 8/8 Module#define_method
0.00% 0.00% 0.000 0.000 0.000 0.000 8 BasicObject#singleton_method_added
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 2/6 <Class::Catamaran::Outputter>#write
0.000 0.000 0.000 0.000 4/6 Catamaran::Logger#log_level
0.00% 0.00% 0.000 0.000 0.000 0.000 6 NilClass#nil?
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/1 <Class::Catamaran::Outputter>#write
0.00% 0.00% 0.000 0.000 0.000 0.000 1 <Class::Catamaran::Manager>#stderr?
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/1 Global#[No method]
0.00% 0.00% 0.000 0.000 0.000 0.000 1 Class#new
0.000 0.000 0.000 0.000 1/1 BasicObject#initialize
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/1 <Class::Catamaran::Outputter>#write
0.00% 0.00% 0.000 0.000 0.000 0.000 1 <Class::Catamaran::Manager>#stdout?
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/1 Catamaran::Logger#path_to_s
0.00% 0.00% 0.000 0.000 0.000 0.000 1 <Class::Catamaran::Manager>#delimiter
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/1 Catamaran::Logger#log_level
0.00% 0.00% 0.000 0.000 0.000 0.000 1 <Object::Object>#[]
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/1 <Class::Catamaran::Formatter>#construct_formatted_message
0.00% 0.00% 0.000 0.000 0.000 0.000 1 <Class::Catamaran::Manager>#formatter_pattern
--------------------------------------------------------------------------------
0.000 0.000 0.000 0.000 1/1 Class#new
0.00% 0.00% 0.000 0.000 0.000 0.000 1 BasicObject#initialize
* indicates recursively called methods
- Make it easier to change the formatting pattern (Perhaps support for something similar to this: http://logging.apache.org/log4j/1.2/apidocs/org/apache/log4j/PatternLayout.html?)
- Another iteration of improvements on logger.io
- Consider capturing log messages beyond stderr, stdout, and local files
- Ongoing performance considerations
- Something like
filter_parameter_logging - Color support
- Log rotation
- Heroku support (https://blog.heroku.com/archives/2013/7/15/logging-on-heroku)
- Buffered file I/O considerations
- http://stackoverflow.com/questions/462651/rails-logger-format-string-configuration
- http://stackoverflow.com/questions/3654827/logging-in-rails-app
- http://stackoverflow.com/questions/11991967/rails-log-too-verbose
- http://www.ietf.org/rfc/rfc3164.txt
- http://logging.apache.org/log4j/1.2
- https://github.com/TwP/logging
- https://github.com/colbygk/log4r
- http://guides.rubyonrails.org/debugging_rails_applications.html#the-logger