SQLクエリからアプリケーションコードへのバックトレース？

Question

Rails開発ログにMySQLステートメントを生成したコード行を見つける方法はありますか？

パフォーマンスの最適化を行うために、私のアプリケーションのどの部分がどのMySQLクエリを作成しているのかを知りたいと思います。私のログを見てみると、私がやるWebリクエストごとに、hundresのクエリが点滅しているのがわかります。どこから来たのかを知る必要があります。

____FILE____と____LINE____のような変数をログ出力に追加することを考えています。

これは可能ですか？

Answer 1

は、すべてのSQLクエリと有用な統計情報のバックトレースを提供します。

Answer 2

あなたはいくつかの選択肢があります。これらのオプションはどれも、エラー時にRubyバックトレースを取得するのと同じ方法でバックトレースを提供するものではありませんが、SQLクエリがどこから来ているかを正確に追跡するために必要なツールと情報を提供します。

1. あなたの典型的なRailsはエントリは次のようなものになりますログ：SQLクエリのあなたの何百も起こっているところ、私はこれを推測している

   Started GET "/login" for 127.0.0.1 at Thu Sep 27 18:59:08 -0500 2012 
       Processing by PublicController#index as HTML 
       (40.9ms) SELECT COUNT(*) FROM "studies" 
       (49.6ms) SELECT COUNT(DISTINCT "activities"."whodiddit") FROM "activities" 
       (35.3ms) SELECT COUNT(*) FROM "involvements" 
   

   はありますか？

   Rendered partials/_faq.haml (6.1ms) 
   Rendered public/index.html.haml within layouts/public (114.3ms) 
   Completed 200 OK in 595ms (Views: 276.7ms | ActiveRecord: 151.6ms) 
   

   ので、上部には、HTTP動詞GETとルート（私の例では/login）、およびそれが来たホストを持っています。その後、どのコントローラ+アクションがトリガされるかをRailsが指示します。

   注：Rendered...で始まる行が表示された場合、その表示はレンダリングされます。レンダリングの後に来るすべてのSQLクエリは、ビュー内でSQLクエリがトリガされていることを示します（コントローラ外など）。考えてみると少しです。ビューが呼び出すコードに応じて、SQLクエリをトリガする実際の行は、ヘルパー、モデル、またはビューコードが呼び出す他の場所に埋め込むことができます。最後に

   Processing by PublicController#index as HTML 
   

   as HTMLビットは、要求が来たようで、そのためのビュー/フォーマットはおそらく応答で使用されるフォーマットかを表示します。

   これは、基本的に、PublicControllerのindexアクションの一部として、またはこのアクションの結果としてコントローラがレンダリングするビューの1つとしてSQLクエリがトリガーされたことを示しています。

構文解析が多すぎる場合は、debuggerコマンドを使用して、さまざまな時点で出力を停止し、何が起こっているのかを調べることができます。 debugger hereの詳細

2. Rails.logger.info "My info message"またはRails.logger.error "My error message"（デフォルトまたはエラーログなどで使用するかどうかによって異なります）を使用してアプリログに直接出力することができます。