2010-01-12 19:00:53 +00:00
|
|
|
require "cases/helper"
|
|
|
|
require "models/developer"
|
2010-06-24 11:23:43 +00:00
|
|
|
require "active_support/log_subscriber/test_helper"
|
2010-01-12 19:00:53 +00:00
|
|
|
|
2010-07-14 23:35:52 +00:00
|
|
|
class LogSubscriberTest < ActiveRecord::TestCase
|
2010-06-24 11:23:43 +00:00
|
|
|
include ActiveSupport::LogSubscriber::TestHelper
|
2010-07-20 20:00:12 +00:00
|
|
|
include ActiveSupport::BufferedLogger::Severity
|
2010-01-12 19:00:53 +00:00
|
|
|
|
2010-01-12 23:41:04 +00:00
|
|
|
def setup
|
2010-03-04 10:20:01 +00:00
|
|
|
@old_logger = ActiveRecord::Base.logger
|
2010-01-12 19:00:53 +00:00
|
|
|
super
|
2010-06-24 11:23:43 +00:00
|
|
|
ActiveRecord::LogSubscriber.attach_to(:active_record)
|
2010-01-12 19:00:53 +00:00
|
|
|
end
|
|
|
|
|
|
|
|
def teardown
|
|
|
|
super
|
2010-06-24 11:23:43 +00:00
|
|
|
ActiveRecord::LogSubscriber.log_subscribers.pop
|
2010-01-12 19:00:53 +00:00
|
|
|
ActiveRecord::Base.logger = @old_logger
|
|
|
|
end
|
|
|
|
|
|
|
|
def set_logger(logger)
|
2010-01-12 23:41:04 +00:00
|
|
|
ActiveRecord::Base.logger = logger
|
2010-01-12 19:00:53 +00:00
|
|
|
end
|
|
|
|
|
2011-03-28 22:27:37 +00:00
|
|
|
def test_schema_statements_are_ignored
|
|
|
|
event = Struct.new(:duration, :payload)
|
|
|
|
|
|
|
|
logger = Class.new(ActiveRecord::LogSubscriber) {
|
|
|
|
attr_accessor :debugs
|
|
|
|
|
|
|
|
def initialize
|
|
|
|
@debugs = []
|
|
|
|
super
|
|
|
|
end
|
|
|
|
|
|
|
|
def debug message
|
|
|
|
@debugs << message
|
|
|
|
end
|
|
|
|
}.new
|
|
|
|
assert_equal 0, logger.debugs.length
|
|
|
|
|
|
|
|
logger.sql(event.new(0, { :sql => 'hi mom!' }))
|
|
|
|
assert_equal 1, logger.debugs.length
|
|
|
|
|
|
|
|
logger.sql(event.new(0, { :sql => 'hi mom!', :name => 'foo' }))
|
|
|
|
assert_equal 2, logger.debugs.length
|
|
|
|
|
|
|
|
logger.sql(event.new(0, { :sql => 'hi mom!', :name => 'SCHEMA' }))
|
|
|
|
assert_equal 2, logger.debugs.length
|
|
|
|
end
|
|
|
|
|
2010-01-12 19:00:53 +00:00
|
|
|
def test_basic_query_logging
|
|
|
|
Developer.all
|
|
|
|
wait
|
|
|
|
assert_equal 1, @logger.logged(:debug).size
|
2010-03-16 01:09:33 +00:00
|
|
|
assert_match(/Developer Load/, @logger.logged(:debug).last)
|
2010-02-15 15:57:45 +00:00
|
|
|
assert_match(/SELECT .*?FROM .?developers.?/i, @logger.logged(:debug).last)
|
2010-01-12 19:00:53 +00:00
|
|
|
end
|
|
|
|
|
|
|
|
def test_cached_queries
|
|
|
|
ActiveRecord::Base.cache do
|
|
|
|
Developer.all
|
|
|
|
Developer.all
|
|
|
|
end
|
|
|
|
wait
|
|
|
|
assert_equal 2, @logger.logged(:debug).size
|
2010-03-16 01:09:33 +00:00
|
|
|
assert_match(/CACHE/, @logger.logged(:debug).last)
|
2010-02-15 15:57:45 +00:00
|
|
|
assert_match(/SELECT .*?FROM .?developers.?/i, @logger.logged(:debug).last)
|
2010-01-12 19:00:53 +00:00
|
|
|
end
|
2010-07-19 20:28:51 +00:00
|
|
|
|
|
|
|
def test_basic_query_doesnt_log_when_level_is_not_debug
|
2010-07-20 20:00:12 +00:00
|
|
|
@logger.level = INFO
|
2010-07-19 20:28:51 +00:00
|
|
|
Developer.all
|
|
|
|
wait
|
|
|
|
assert_equal 0, @logger.logged(:debug).size
|
|
|
|
end
|
|
|
|
|
|
|
|
def test_cached_queries_doesnt_log_when_level_is_not_debug
|
2010-07-20 20:00:12 +00:00
|
|
|
@logger.level = INFO
|
2010-07-19 20:28:51 +00:00
|
|
|
ActiveRecord::Base.cache do
|
|
|
|
Developer.all
|
|
|
|
Developer.all
|
|
|
|
end
|
|
|
|
wait
|
|
|
|
assert_equal 0, @logger.logged(:debug).size
|
|
|
|
end
|
2010-07-26 17:14:24 +00:00
|
|
|
|
|
|
|
def test_initializes_runtime
|
|
|
|
Thread.new { assert_equal 0, ActiveRecord::LogSubscriber.runtime }.join
|
|
|
|
end
|
2010-03-16 01:09:33 +00:00
|
|
|
end
|