require "spec_helper"

describe Timber::Probes::ActionControllerLogSubscriber do
  if defined?(described_class::LogSubscriber)
    describe described_class::LogSubscriber do
      let(:time) { Time.utc(2016, 9, 1, 12, 0, 0) }
      let(:io) { StringIO.new }
      let(:logger) do
        logger = Timber::Logger.new(io)
        logger.level = ::Logger::WARN
        logger
      end

      around(:each) do |example|
        class LogSubscriberController < ActionController::Base
          layout nil

          def index
            render json: {}
          end

          def method_for_action(action_name)
            action_name
          end
        end

        ::RailsApp.routes.draw do
          get 'log_subscriber' => 'log_subscriber#index'
        end

        old_logger = ::ActionController::Base.logger
        ::ActionController::Base.logger = logger

        Timecop.freeze(time) { example.run }

        Object.send(:remove_const, :LogSubscriberController)
        ::ActionController::Base.logger = old_logger
      end

      describe "#start_processing, #process_action" do
        it "should not log if the level is not sufficient" do
          dispatch_rails_request("/log_subscriber")
          expect(io.string).to eq("")
        end

        context "with an info level" do
          around(:each) do |example|
            old_level = logger.level
            logger.level = ::Logger::INFO
            example.run
            logger.level = old_level
          end

          it "should log the controller call event" do
            # Rails uses this to calculate the view runtime below
            allow(Benchmark).to receive(:ms).and_return(1).and_yield
            dispatch_rails_request("/log_subscriber")
            message1 = <<-MSG
              Processing by LogSubscriberController#index as HTML @timber.io {"level":"info","dt":"2016-09-01T12:00:00.000000Z","event":{"controller_call":{"controller":"LogSubscriberController","action":"index"}},"context":{"http":{"method":"GET","path":"/log_subscriber","remote_addr":"123.456.789.10","request_id":"unique-request-id-1234"}}}
            MSG
            message2 = <<-MSG
              Completed 200 OK in 0.0ms (Views: 1.0ms) @timber.io {"level":"info","dt":"2016-09-01T12:00:00.000000Z","event":{"http_response":{"status":200,"time_ms":0.0}},"context":{"http":{"method":"GET","path":"/log_subscriber","remote_addr":"123.456.789.10","request_id":"unique-request-id-1234"}}}
            MSG
            expect(io.string).to eq(message1.strip + "\n" + message2.strip + "\n")
          end
        end
      end
    end
  end
end