require 'abstract_unit' require 'stringio' require 'fileutils' require 'active_support/buffered_logger' class BufferedLoggerTest < Test::Unit::TestCase Logger = ActiveSupport::BufferedLogger def setup @message = "A debug message" @integer_message = 12345 @output = StringIO.new @logger = Logger.new(@output) end def test_should_log_debugging_message_when_debugging @logger.level = Logger::DEBUG @logger.add(Logger::DEBUG, @message) assert @output.string.include?(@message) end def test_should_not_log_debug_messages_when_log_level_is_info @logger.level = Logger::INFO @logger.add(Logger::DEBUG, @message) assert ! @output.string.include?(@message) end def test_should_add_message_passed_as_block_when_using_add @logger.level = Logger::INFO @logger.add(Logger::INFO) {@message} assert @output.string.include?(@message) end def test_should_add_message_passed_as_block_when_using_shortcut @logger.level = Logger::INFO @logger.info {@message} assert @output.string.include?(@message) end def test_should_convert_message_to_string @logger.level = Logger::INFO @logger.info @integer_message assert @output.string.include?(@integer_message.to_s) end def test_should_convert_message_to_string_when_passed_in_block @logger.level = Logger::INFO @logger.info {@integer_message} assert @output.string.include?(@integer_message.to_s) end def test_should_not_evaluate_block_if_message_wont_be_logged @logger.level = Logger::INFO evaluated = false @logger.add(Logger::DEBUG) {evaluated = true} assert evaluated == false end def test_should_not_mutate_message message_copy = @message.dup @logger.info @message assert_equal message_copy, @message end [false, nil, 0].each do |disable| define_method "test_disabling_auto_flush_with_#{disable.inspect}_should_buffer_until_explicit_flush" do @logger.auto_flushing = disable 4.times do @logger.info 'wait for it..' assert @output.string.empty?, @output.string end @logger.flush assert !@output.string.empty?, @logger.send(:buffer).size end define_method "test_disabling_auto_flush_with_#{disable.inspect}_should_flush_at_max_buffer_size_as_failsafe" do @logger.auto_flushing = disable assert_equal Logger::MAX_BUFFER_SIZE, @logger.auto_flushing (Logger::MAX_BUFFER_SIZE - 1).times do @logger.info 'wait for it..' assert @output.string.empty?, @output.string end @logger.info 'there it is.' assert !@output.string.empty?, @logger.send(:buffer).size end end def test_should_know_if_its_loglevel_is_below_a_given_level Logger::Severity.constants.each do |level| @logger.level = Logger::Severity.const_get(level) - 1 assert @logger.send("#{level.downcase}?"), "didn't know if it was #{level.downcase}? or below" end end def test_should_auto_flush_every_n_messages @logger.auto_flushing = 5 4.times do @logger.info 'wait for it..' assert @output.string.empty?, @output.string end @logger.info 'there it is.' assert !@output.string.empty?, @output.string end def test_should_create_the_log_directory_if_it_doesnt_exist tmp_directory = File.join(File.dirname(__FILE__), "tmp") log_file = File.join(tmp_directory, "development.log") assert !File.exist?(tmp_directory) @logger = Logger.new(log_file) assert File.exist?(tmp_directory) ensure FileUtils.rm_rf(tmp_directory) end def test_logger_should_maintain_separate_buffers_for_each_thread @logger.auto_flushing = false a = Thread.new do @logger.info("a"); Thread.pass; @logger.info("b"); Thread.pass; @logger.info("c"); @logger.flush end b = Thread.new do @logger.info("x"); Thread.pass; @logger.info("y"); Thread.pass; @logger.info("z"); @logger.flush end a.join b.join assert @output.string.include?("a\nb\nc\n") assert @output.string.include?("x\ny\nz\n") end def test_flush_should_remove_empty_buffers @logger.send :buffer @logger.expects :clear_buffer @logger.flush end end