ログ記録

この記事の英語版に更新があります。ご覧の翻訳には含まれていない変更点があるかもしれません。

最終更新日 2019年10月04日(金)

Heroku アプリのログは、実行中プロセス、システムコンポーネント、およびバッキングサービスすべての出力ストリームから集約されます。Heroku の ​Logplex​ は、これらのさまざまなソースすべてからのログストリームを単一のチャネルにルーティングし、包括的なログ記録の基盤を提供します。

​

ログのタイプ

​

​

ランタイムログ

​

Heroku は、デプロイされたアプリの次のカテゴリのログを集約します。

  • ​アプリログ​ - アプリケーション自体からのログ出力。これには、アプリのコードおよび依存関係によって生成されたログが含まれます。(フィルター: ​--source app​)
  • ​システムログ​ - クラッシュしたプロセスの再起動、Web dyno のスリープまたはスリープ解除、アプリでの問題によるエラーページの表示など、アプリの代わりに Heroku プラットフォームインフラストラクチャが行った操作に関するメッセージ。(フィルター: ​--source heroku​)
  • ​API ログ​ - 新しいコードのデプロイ、プロセス構成のスケーリング、メンテナンスモードの切り替えなど、ユーザーまたはアプリを操作している他の開発者が行った管理操作に関するメッセージ。 (フィルター: ​--source app --dyno api​)
  • ​アドオンログ​ - アドオンサービスからのメッセージ。詳細については、アドオンの Dev Center 記事を参照してください。(フィルターはアドオンによって異なります)

​

ビルドログ

​

アプリのビルドおよびデプロイ中に生成されたログは、アプリのランタイムログから切り離されます。成功および失敗した両方のビルドのログは、​Heroku Dashboard​ のアプリの ​Activity​ (アクティビティ) タブから使用できます。

​https://dashboard.heroku.com/apps/peaceful-falls-84709/activity のスクリーンショット​

アクティビティフィードでいずれのビルドイベントに対して ​View build log​ (ビルドログの表示) をクリックします。

​https://dashboard.heroku.com/apps/peaceful-falls-84709/activity/builds/b8a13d92-5bd8-4029-81de-ca970c095758 のスクリーンショット​

​

ログ履歴の制限

​

Logplex は、保管用ではなく、ログメッセージを照合してルーティングするように設計されています。最新の 1,500 行の統合されたログを保持し、1 週間後に期限切れになります。

ログの本番準備完了の持続性を高めるために、Heroku プラットフォームの使用可能な​ログ記録アドオン​のいずれかをアプリに追加できます。これらのアドオンのほとんどには、開始するための無料プランが用意されています。

または、ログに行われる操作を完全に制御するために、独自の​ログドレイン​を実装できます。

​

ログへの書き込み

​

アプリが標準出力 (​stdout​) または標準エラー (​stderr​) に書き込むものすべてがログに取得されます。したがって、単純な出力文で、アプリケーションコードのどこからでもログ記録できます。

Ruby では、次のように使用できます。

puts "Hello, logs!"

Java の場合は次のようになります。

System.err.println("Hello, logs!");
System.out.println("Hello, logs!");

Heroku がサポートする他のすべての言語に同じことが当てはまります。

リアルタイムのログ記録を利用するには、アプリケーションが実行しているすべてのログバッファリングを無効にすることが必要になる場合があります。たとえば、Ruby では、​config.ru​ ファイルに次のように追加します。

$stdout.sync = true

フレームワークによっては、デフォルトで、​stdout​ 以外の場所にログ出力を送信することがあります。この場合、追加の設定が必要になる可能性があります。たとえば、ActiveSupport により Rails TaggedLogger で Ruby を使用する場合、アプリの設定に次のように追加して、​stdout​ ログ記録を取得する必要があります。

config.logger = Logger.new(STDOUT)

​

ログ取得

​

​

ログ形式

​

​heroku logs​ コマンドの出力形式は次のようになります。

timestamp source[dyno]: message
  • ​タイムスタンプ​ - dyno またはコンポーネントによってログ行が生成された時点で記録された日時。タイムスタンプは、​RFC5424​ で指定された形式で、マイクロ秒精度を含みます。
  • ​ソース​ - アプリの dyno (Web dyno、バックグラウンド Worker、cron) すべてには、ソース (​app​) があります。 Heroku のシステムコンポーネント (HTTP ルーター、dyno マネージャ) すべてには、ソース (​heroku​) があります。
  • ​dyno​ - ログ行を書き込む dyno またはコンポーネントの名前。 たとえば、Worker #3 は ​worker.3​ と表示され、Heroku HTTP ルーターは ​router​ と表示されます。
  • ​メッセージ​ - ログ行の内容。10000 バイトを超える dyno によって生成される行は、末尾に新規行を追加せず、10000 バイトのチャンクに分割されます。チャンクはそれぞれ、個別のログ行として提示されます。

​

ログの表示

​

アプリの最新のログを取得するには、​heroku logs​ コマンドを使用します。

$ heroku logs
2010-09-16T15:13:46.677020+00:00 app[web.1]: Processing PostController#list (for 208.39.138.12 at 2010-09-16 15:13:46) [GET]
2010-09-16T15:13:46.677023+00:00 app[web.1]: Rendering template within layouts/application
2010-09-16T15:13:46.677902+00:00 app[web.1]: Rendering post/list
2010-09-16T15:13:46.678990+00:00 app[web.1]: Rendered includes/_header (0.1ms)
2010-09-16T15:13:46.698234+00:00 app[web.1]: Completed in 74ms (View: 31, DB: 40) | 200 OK [http://myapp.heroku.com/]
2010-09-16T15:13:46.723498+00:00 heroku[router]: at=info method=GET path="/posts" host=myapp.herokuapp.com" fwd="204.204.204.204" dyno=web.1 connect=1ms service=18ms status=200 bytes=975
2010-09-16T15:13:47.893472+00:00 app[worker.1]: 2 jobs processed at 16.6761 j/s, 0 failed ...

この例では、出力には、アプリの Web dyno のいずれか、Heroku HTTP ルーター、アプリの Worker のいずれかからのログ行が含まれます。

​logs​ コマンドは、デフォルトで 100 ログ行を返します。​--num​ (または ​-n​) オプションを使用して、取得するログ行数を指定できます (最大 1,500 行)。

$ heroku logs -n 200

​

リアルタイム tail

​

​tail -f​ と同様に、リアルタイム tail は、最新のログを表示し、リアルタイムログが流入できるようにセッションを開いたままにします。アプリからログのライブストリームを表示することにより、ライブアプリケーションの動作に対する洞察が得られ、現在の問題をデバッグできます。

​--tail​ (または ​-t​) を使用してログを末尾監視できます。

$ heroku logs --tail

終了したら、Ctrl+C を押してプロンプトに戻ります。

アイドル状態が 1 時間続いた後、リアルタイム tail セッションは自動的に終了します。

​

フィルタリング

​

特定のソースまたは特定の dyno、あるいはその両方のログだけを取得する場合は、​--source​ (または ​-s​) と ​--dyno​ (または ​-d​) フィルタリング引数を使用できます。

$ heroku logs --dyno router
2012-02-07T09:43:06.123456+00:00 heroku[router]: at=info method=GET path="/stylesheets/dev-center/library.css" host=devcenter.heroku.com fwd="204.204.204.204" dyno=web.5 connect=1ms service=18ms status=200 bytes=13
2012-02-07T09:43:06.123456+00:00 heroku[router]: at=info method=GET path="/articles/bundler" host=devcenter.heroku.com fwd="204.204.204.204" dyno=web.6 connect=1ms service=18ms status=200 bytes=20375

$ heroku logs --source app
2012-02-07T09:45:47.123456+00:00 app[web.1]: Rendered shared/_search.html.erb (1.0ms)
2012-02-07T09:45:47.123456+00:00 app[web.1]: Completed 200 OK in 83ms (Views: 48.7ms | ActiveRecord: 32.2ms)
2012-02-07T09:45:47.123456+00:00 app[worker.1]: [Worker(host:465cf64e-61c8-46d3-b480-362bfd4ecff9 pid:1)] 1 jobs processed at 23.0330 j/s, 0 failed ...
2012-02-07T09:46:01.123456+00:00 app[web.6]: Started GET "/articles/buildpacks" for 4.1.81.209 at 2012-02-07 09:46:01 +0000

$ heroku logs --source app --dyno worker
2012-02-07T09:47:59.123456+00:00 app[worker.1]: [Worker(host:260cf64e-61c8-46d3-b480-362bfd4ecff9 pid:1)] Article#record_view_without_delay completed after 0.0221
2012-02-07T09:47:59.123456+00:00 app[worker.1]: [Worker(host:260cf64e-61c8-46d3-b480-362bfd4ecff9 pid:1)] 5 jobs processed at 31.6842 j/s, 0 failed ...

dyno でフィルタリングするときには、ベース名 (​--dyno web​ など) とフルネーム (​--dyno web.1​ など) のどちらでも使用できます。

フィルタリングスイッチを、​--tail​ と組み合わせて、フィルタリング出力のリアルタイムストリームを取得することもできます。

$ heroku logs --source app --tail

​

ログメッセージの順序付け

​

ログを取得する場合、特に複数のコンポーネントが含まれるときには、常に正確な発生順に取得されるわけではありません。ログは、多くのソース (ルーターノード、dyno など) から生成され、Logplex により単一のログストリームにアセンブルされます。Logplex 自体は、分散アーキテクチャを使用して高可用性を確保するので、ログメッセージは複数の Logplex ノード間で収集され、その結果バラバラの順序で配信されることがあります。