require 'abstract_unit' require 'rails/log_subscriber/test_helper' class MyLogSubscriber < Rails::LogSubscriber attr_reader :event def some_event(event) @event = event info event.name end def foo(event) debug "debug" info "info" warn "warn" end def bar(event) info "#{color("cool", :red)}, #{color("isn't it?", :blue, true)}" end def puke(event) raise "puke" end end class SyncLogSubscriberTest < ActiveSupport::TestCase include Rails::LogSubscriber::TestHelper def setup super @log_subscriber = MyLogSubscriber.new Rails::LogSubscriber.instance_variable_set(:@log_tailer, nil) end def teardown super Rails::LogSubscriber.log_subscribers.clear Rails::LogSubscriber.instance_variable_set(:@log_tailer, nil) end def instrument(*args, &block) ActiveSupport::Notifications.instrument(*args, &block) end def test_proxies_method_to_rails_logger @log_subscriber.foo(nil) assert_equal %w(debug), @logger.logged(:debug) assert_equal %w(info), @logger.logged(:info) assert_equal %w(warn), @logger.logged(:warn) end def test_set_color_for_messages Rails::LogSubscriber.colorize_logging = true @log_subscriber.bar(nil) assert_equal "\e[31mcool\e[0m, \e[1m\e[34misn't it?\e[0m", @logger.logged(:info).last end def test_does_not_set_color_if_colorize_logging_is_set_to_false @log_subscriber.bar(nil) assert_equal "cool, isn't it?", @logger.logged(:info).last end def test_event_is_sent_to_the_registered_class Rails::LogSubscriber.add :my_log_subscriber, @log_subscriber instrument "my_log_subscriber.some_event" wait assert_equal %w(my_log_subscriber.some_event), @logger.logged(:info) end def test_event_is_an_active_support_notifications_event Rails::LogSubscriber.add :my_log_subscriber, @log_subscriber instrument "my_log_subscriber.some_event" wait assert_kind_of ActiveSupport::Notifications::Event, @log_subscriber.event end def test_does_not_send_the_event_if_it_doesnt_match_the_class Rails::LogSubscriber.add :my_log_subscriber, @log_subscriber instrument "my_log_subscriber.unknown_event" wait # If we get here, it means that NoMethodError was not raised. end def test_does_not_send_the_event_if_logger_is_nil Rails.logger = nil @log_subscriber.expects(:some_event).never Rails::LogSubscriber.add :my_log_subscriber, @log_subscriber instrument "my_log_subscriber.some_event" wait end def test_does_not_fail_with_non_namespaced_events Rails::LogSubscriber.add :my_log_subscriber, @log_subscriber instrument "whatever" wait end def test_flushes_loggers Rails::LogSubscriber.add :my_log_subscriber, @log_subscriber Rails::LogSubscriber.flush_all! assert_equal 1, @logger.flush_count end def test_flushes_the_same_logger_just_once Rails::LogSubscriber.add :my_log_subscriber, @log_subscriber Rails::LogSubscriber.add :another, @log_subscriber Rails::LogSubscriber.flush_all! wait assert_equal 1, @logger.flush_count end def test_logging_does_not_die_on_failures Rails::LogSubscriber.add :my_log_subscriber, @log_subscriber instrument "my_log_subscriber.puke" instrument "my_log_subscriber.some_event" wait assert_equal 1, @logger.logged(:info).size assert_equal 'my_log_subscriber.some_event', @logger.logged(:info).last assert_equal 1, @logger.logged(:error).size assert_equal 'Could not log "my_log_subscriber.puke" event. RuntimeError: puke', @logger.logged(:error).last end end