[Rails]before_actionが効かない

Question

管理画面のログイン機能を実装しています。

コードは以下です。

1. PhotographersControllerでsession[:p_id]を取得し、ログインしてphotographer/distribution_controller.rbのscheduleメソッドに飛ばします。
2. Photographer::DistributionsControllerはPhotographer::ApplicationControllerを受け継いでいて、Photographer::ApplicationController内にcurrent_photographerメソッドを記述しています。
3. current_photographerメソッドをPhotographer::DistributionsController内でbefore_actionで記述しています。

routes.rb

namespace :photographer do
  resources :distributions do
    get 'schedule'
  end
end

get 'photographer_login' => 'photographers#login_form'
post 'photographer_login' => 'photographers#login'
get 'photographer_logout' => 'photographers#logout'

get '*path', controller: 'application', action: 'handle_404'

controllers/photographers_controller.rb

class PhotographersController < ApplicationController

  def login_form
    return redirect_to schedule_photographer_distribution_path(session[:p_id]) if session[:p_id]
  end

  def login
    @photographer = Photographer.find_by(email: params[:email])
    if @photographer && @photographer.authenticate(params[:password])
      forwarding_url = session[:previous]
      reset_session
      session[:p_id] = @photographer.id
      flash[:notice] = "ログインしました"
      return redirect_to (forwarding_url || photographer_distributions_url)
    else
      flash.now[:alert] = "エラーが発生しました"
      @email = params[:email]
      @password = params[:password]
      return render :login_form
    end
  end

  def logout
    reset_session
    flash[:notice] = "ログアウトしました"
    redirect_to("/photographer_login")
  end
end

controllers/photographer/application.controller.rb

class Photographer::ApplicationController < ApplicationController
  layout 'photographers_distribution'
  before_action :current_photographer
  rescue_from ActionController::RoutingError, with: :handle_404

  def current_photographer
    unless session[:p_id]
      reset_session
      store_location
      return redirect_to photographer_login_path
    end
    @current_photographer = Photographer.find(session[:p_id])
  end

  def check_user_agent_for_mobile
    if request.from_ipad?
      request.variant = :pc
    elsif request.from_smartphone?
      request.variant = :mobile
    else
      request.variant = :pc
    end
  end

  def handle_404
    respond_to do |format|
      format.html {
        return render template: 'errors/error_404', status: 404, layout: false, content_type: 'text/html'
      }
      format.all {
        return head :not_found
      }
    end
  end

  private
    def store_location(url = nil)
      session[:previous] = request.url if request.get?
      session[:previous] = url unless url.nil?
    end
end

photographer/distribution_controller.rb

class Photographer::DistributionsController < Photographer::ApplicationController
  before_action :check_user_agent_for_mobile

  def index
    @dists = PhotographersDistribution.where(display_flg: true).order(id: :desc)
  end
end

###ログ
check_user_agent_for_mobileメソッドとcurrent_photographerメソッドが呼ばれない場合のログ

I, [2019-06-21T14:15:36.562217 #61286]  INFO -- : Started POST "/photographer_login" for 127.0.0.1 at 2019-06-21 14:15:36 +0900
I, [2019-06-21T14:15:36.580546 #61286]  INFO -- : Processing by PhotographersController#login as HTML
I, [2019-06-21T14:15:36.580672 #61286]  INFO -- :   Parameters: {"utf8"=>"✓", "authenticity_token"=>"XEHv55kFs+XFYq5I3ZH+xE/6CSJLSd2PNK/UTOeP+C3EF0j4VIFZ4Ii4eXMQDihLZQ+Z6P3rOY94lp+8TBzJDA==", "email"=>"[e-mail]", "password"=>"[FILTERED]"}
D, [2019-06-21T14:15:36.593618 #61286] DEBUG -- :   Photographer Load (3.1ms)  SELECT  `photographers`.* FROM `photographers` WHERE `photographers`.`email` = '[e-mail]' LIMIT 1
I, [2019-06-21T14:15:36.658521 #61286]  INFO -- : Redirected to http://localhost:3000/photographer/distributions
I, [2019-06-21T14:15:36.658770 #61286]  INFO -- : Completed 302 Found in 78ms (ActiveRecord: 8.6ms
I, [2019-06-21T14:15:36.664076 #61286]  INFO -- : Started GET "/photographer/distributions" for 127.0.0.1 at 2019-06-21 14:15:36 +0900
/Users/livesearch/Desktop/job/contents-store/app/controllers/photographer/distributions_controller.rb:1: warning: toplevel constant ApplicationController referenced by Photographer::ApplicationController
I, [2019-06-21T14:15:36.691243 #61286]  INFO -- : Processing by Photographer::DistributionsController#index as HTML
D, [2019-06-21T14:15:36.709322 #61286] DEBUG -- :   PhotographersDistribution Load (2.8ms)  SELECT `photographers_distributions`.* FROM `photographers_distributions` WHERE `photographers_distributions`.`status_id` = 2  ORDER BY `photographers_distributions`.`id` DESC
D, [2019-06-21T14:15:36.757196 #61286] DEBUG -- :   Request Load (3.7ms)  SELECT  `requests`.* FROM `requests` WHERE `requests`.`id` = 121 LIMIT 1
I, [2019-06-21T14:15:36.839963 #61286]  INFO -- : Completed 500 Internal Server Error in 148ms (ActiveRecord: 17.8ms)
F, [2019-06-21T14:15:36.847954 #61286] FATAL -- : 
NoMethodError - undefined method `latitude' for nil:NilClass:
  app/views/photographer/distributions/index.html+mobile.erb:31:in `_app_views_photographer_distributions_index_html_mobile_erb__30980350848312437_70305103253380'

D, [2019-06-21T14:15:37.026341 #61286] DEBUG -- : 
D, [2019-06-21T14:15:37.026457 #61286] DEBUG -- : 
I, [2019-06-21T14:15:37.026764 #61286]  INFO -- : Started POST "/__better_errors/d07df247e6337ae9/variables" for 127.0.0.1 at 2019-06-21 14:15:37 +0900

photographers/distributions/index.html+mobile.erb

        <script type="text/javascript">
          function allMap() {
            handler = Gmaps.build('Google');
            handler.buildMap({ provider: {}, internal: {id: 'map'}}, function(){
              markers = handler.addMarkers(<%=raw @hash.to_json %>);
              handler.bounds.extendWith(markers);
              handler.fitMapToBounds();
              handler.getMap().setZoom(12);
              google.maps.event.trigger(map,'resize');
              handler.map.centerOn([<%= @current_photographer.latitude %>,<%= @current_photographer.longitude %>]); ⬅この行で@current_photographerがnilだと言われる。 // 
            });
          }
        </script>

current_photographerメソッドが呼ばれる場合のログ（class PhotographersController内のコードにスペースを入れたりすると、呼ばれる）

D, [2019-06-21T14:19:45.543022 #61286] DEBUG -- : 
D, [2019-06-21T14:19:45.544650 #61286] DEBUG -- : 
I, [2019-06-21T14:19:45.545663 #61286]  INFO -- : Started POST "/__better_errors/c1bb543e52e1faf2/variables" for 127.0.0.1 at 2019-06-21 14:19:45 +0900
D, [2019-06-21T14:19:51.656377 #61286] DEBUG -- : 
D, [2019-06-21T14:19:51.656729 #61286] DEBUG -- : 
I, [2019-06-21T14:19:51.656884 #61286]  INFO -- : Started GET "/photographer/distributions" for 127.0.0.1 at 2019-06-21 14:19:51 +0900
I, [2019-06-21T14:19:52.545131 #61286]  INFO -- : Processing by Photographer::DistributionsController#index as HTML
D, [2019-06-21T14:19:52.589319 #61286] DEBUG -- :   Photographer Load (0.9ms)  SELECT  `photographers`.* FROM `photographers` WHERE `photographers`.`id` = 1 LIMIT 1
D, [2019-06-21T14:19:52.608170 #61286] DEBUG -- :   PhotographersDistribution Load (0.3ms)  SELECT `photographers_distributions`.* FROM `photographers_distributions` WHERE `photographers_distributions`.`status_id` = 2  ORDER BY `photographers_distributions`.`id` DESC
D, [2019-06-21T14:19:52.636882 #61286] DEBUG -- :   Request Load (1.1ms)  SELECT  `requests`.* FROM `requests` WHERE `requests`.`id` = 121 LIMIT 1
I, [2019-06-21T14:19:52.665693 #61286]  INFO -- :   Rendered layouts/_infowindow_photographer.html.erb (0.6ms)
D, [2019-06-21T14:19:52.666663 #61286] DEBUG -- :   Request Load (0.3ms)  SELECT  `requests`.* FROM `requests` WHERE `requests`.`id` = 119 LIMIT 1
I, [2019-06-21T14:19:52.673087 #61286]  INFO -- :   Rendered layouts/_infowindow_photographer.html.erb (0.5ms)
D, [2019-06-21T14:19:52.675698 #61286] DEBUG -- :   Request Load (0.7ms)  SELECT  `requests`.* FROM `requests` WHERE `requests`.`id` = 135 LIMIT 1
I, [2019-06-21T14:19:52.680291 #61286]  INFO -- :   Rendered layouts/_infowindow_photographer.html.erb (0.4ms)
I, [2019-06-21T14:19:52.691623 #61286]  INFO -- :   Rendered photographer/distributions/index.html+mobile.erb within layouts/photographers_distribution (1.3ms)
I, [2019-06-21T14:19:52.843714 #61286]  INFO -- : Completed 200 OK in 298ms (Views: 163.1ms | ActiveRecord: 27.2ms)

###問題点
ログインしてビューにリダイレクトした際、
class Photographer::ApplicationControllerのcurrent_photographerメソッドが呼ばれません。

ページを更新しても変化はないのですが、photographer::DistributionsControllerのコードの一部を変更（スペースを入れるなど）すると、schedule.html+mobile.erbが呼ばれます。

実現したいこと

ログインした際にclass Photographer::ApplicationControllerのcurrent_photographerメソッドが呼ばれるようにしたいです。

何かご教示いただけることがあれば、なにとぞよろしくお願いいたします。

Comments

[rhiroe]

こんにちは、ログをさらっと見てみたんですが、以下部分

Completed 500 Internal Server Error in 10ms (ActiveRecord: 1.9ms)
F, [2019-06-14T09:04:44.173953 #29691] FATAL -- :
NoMethodError - undefined method id' for nil:NilClass:
app/controllers/photographer/distributions_controller.rb:45:in schedule'

エラーが起きているようなので、まずはそこから直すべきではないでしょうか？

[s_diff]

Mugheartさん、ありがとうございます。
そのとおりなのですが、そのエラーが起こる理由がわからないのです。
photographer/distributions_controller.rbのscheduleメソッド内の
@current_photographer.idで、そのエラーが起こっています。
ですが、photographer/distributions_controller.rbはPhotographer::ApplicationControllerクラスを継承していて、この継承元クラスの中でbefore_action :current_photographerを実行しているので、このエラーがなぜ起こっているのかわからないのです。

[rhiroe]

Photographer::DistributionsController#scheduleへのパスは
photographer_distribution_schedule_path だと思うのですが
リダイレクトしている先は photographer_schedule_url(session[:p_id]) になってます。
本来不要なはずの引数まで付いているのが気になりますね。
なので NoMethodError - undefined method id' for nil:NilClass
が実際に起きている箇所は別のところのような気がしています。
app/controllers/photographer/distributions_controller.rb:45
とあるようにdistributions_controller.rbの45行目なのですがそこに間違いないでしょうか...？

[s_diff]

リダイレクト先の記述ミスでした。 photographer_distributions_urlになおしております。
このコードでログインしたところ、photographer/distributions/index.html+mobile.erb内で@current_photographerがないというエラーが出ています。

[rhiroe]

「挙動がおかしい」部分は解決したということでよろしいでしょうか。

> photographer/distributions/index.html+mobile.erb内で
> @current_photographerがないというエラーが出ています。

これに関してはエラーメッセージやコードがないのでコメントできません。
質問内容もごちゃごちゃしてきているので一旦質問を整理することをお勧めします。

[s_diff]

ご指摘ありがとうございます。
質問を整理いたしました。
モバイルページに飛ばないエラーをでなくなりましたが、@current_photographerがないエラーは未だに続いています。

[s_diff]

とても不思議なのですが、このエラーはローカルでは起きるのですが、AWSのEC2で構築しているstaging環境では起きません。

[rhiroe]

ログが読みづらいので「```」で囲ってくれると助かります

[s_diff]

ご指摘ありがとうございます。
修正しました。

[rhiroe]

before_actionを通っていないというのは確実に検証しましたか？
具体的にはブレイクポイントを作って止める、とかです。

[s_diff]

Photographer::ApplicationControllerのcurrent_photographerメソッド内の最後にブレイクポイントを作ったところ、
メソッド内で止まったのでbefore_actionが通っているのだと思います。
また、ブレイクポイントでインスタンスの@current_photographerにはレコードが存在しました。
なぜ@current_photographerがPhotographer::DistributionsControllerに渡っていかないのでしょうか？

[rhiroe]

原因かどうかわかりませんが
def login_form
return redirect_to photographer_schedule_path(session[:p_id]) if session[:p_id]
end
この部分をはじめ、リダイレクト先がおかしなところがあります。
その辺りが影響しているのではないでしょうか。

[s_diff]

Mugheartさん、返信が遅れて大変申し訳ありません！
リダイレクト先の記述を書き間違えておりました。
正しくはschedule_photographer_distribution_pathです。すでに自分の手元では修正しておりました。
質問文の方も修正しております。

Answer 1

問題はbefore_actionでcheck_user_agent_for_mobileが実行されないことですか？
それともvariantが効いていないことですか？

操作をした内容とログをもらえれば詳細な回答ができると思いますが、routesが独特なのでコードだけでは判断できないです。

Comments

[s_diff]

odyu様、ご回答ありがとうございます。
ログを追記しました。
何卒よろしくお願いいたします。

[odyu]

全体的に少し直した方がいいところがあります。
例えば

@current_photographer = Photographer.find_by(id: session[:p_id])

curret_photographer内の上記。

インスタンス変数がなくてもいいなら良いのですが、ろぐを見るとnilクラスでエラーになってますね。

ないといけないなら、ちゃんとfindメソッドを使って404エラーにしましょう。

[s_diff]

ご指摘ありがとうございます。
ご指摘の通り修正いたしました。