actionpack/test/controller/logging_test.rb


1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81

require 'abstract_unit'

module Another
  class LoggingController < ActionController::Base
    layout "layouts/standard"

    def show
      render :nothing => true
    end

    def with_layout
      render :template => "test/hello_world", :layout => true
    end
  end
end

class LoggingTest < ActionController::TestCase
  tests Another::LoggingController

  def setup
    super
    wait # Wait pending notifications to be published
    set_logger
  end

  def get(*args)
    super
    wait
  end

  def wait
    ActiveSupport::Notifications.notifier.wait
  end

  def test_logging_without_parameters
    get :show
    assert_equal 4, logs.size
    assert_nil logs.detect {|l| l =~ /Parameters/ }
  end

  def test_logging_with_parameters
    get :show, :id => '10'
    assert_equal 5, logs.size

    params = logs.detect {|l| l =~ /Parameters/ }
    assert_equal 'Parameters: {"id"=>"10"}', params
  end

  def test_log_controller_with_namespace_and_action
    get :show
    assert_match /Processed\sAnother::LoggingController#show/, logs[1]
  end

  def test_log_view_runtime
     get :show
     assert_match /View runtime/, logs[2]
   end

  def test_log_completed_status_and_request_uri
    get :show
    last = logs.last
    assert_match /Completed/, last
    assert_match /200/, last
    assert_match /another\/logging\/show/, last
  end

  def test_logger_prints_layout_and_template_rendering_info
    get :with_layout
    logged = logs.find {|l| l =~ /render/i }
    assert_match /Rendered (.*)test\/hello_world.erb within (.*)layouts\/standard.html.erb/, logged
  end

  private
    def set_logger
      @controller.logger = MockLogger.new
    end

    def logs
      @logs ||= @controller.logger.logged
    end
end