ログ出力時のログをgrepしてActiveRecordの値だけを出力したい

下記　リンク先を参考に処理の時間をgrepして出力しました。
http://blog.tnantoka.com/posts/81

Completed 200 OK in 38ms (Views: 0.4ms | ActiveRecord: 6.0ms)

grep "Completed 200 OK in" production.log | awk '{ print $11 }' | sort -n

この時に、38msの38を出力でなく、ActiveRecord: 6.0msの6.0だけを出力するように上記のgrepを修正したいのですが、どう修正して打てば良いでしょうか？

行動規範の内容に同意します

回答1件

ベストアンサー

こんにちは。

以下、説明のため、引用元のブログ記事に載っているログの１行目を、以下のようにLOG_LINEという変数に入れ、ここから326.1 を取り出す方法を考えます。

bash
1LOG_LINE='I, [2015-07-02T03:47:07.832647 #1439]  INFO -- : Completed 200 OK in 374ms (Views: 35.2ms | ActiveRecord: 326.1ms)'

　　
1. awk を使う方法

$16で 326.1ms) が取れますので、これの数値部分 326.1だけを取得するためには、一例として以下のようにすればよいでしょう。( http://tpcg.io/kQR1k0 にも上げておきました。左上の[Execute]をクリックすると実行結果が右側に表示されます。)

bash
1#!/bin/bash
2
3LOG_LINE='I, [2015-07-02T03:47:07.832647 #1439]  INFO -- : Completed 200 OK in 374ms (Views: 35.2ms | ActiveRecord: 326.1ms)'
4
5echo ${LOG_LINE} | awk '{ print substr($16, 1, index($16, "m")-1) }'

上記を使うとすれば、ご質問にあるgrepから始まるコマンドは以下にすればよいです。

bash
1grep "Completed 200 OK in" production.log | awk '{ print substr($16, 1, index($16, "m")-1) }' | sort -n

または、以下のようにsubを使って、ms) を削除する（空文字列に置き換える）こともできます。(http://tpcg.io/gkHQYJ にも上げておきました。)

bash
1#!/bin/bash
2
3LOG_LINE='I, [2015-07-02T03:47:07.832647 #1439]  INFO -- : Completed 200 OK in 374ms (Views: 35.2ms | ActiveRecord: 326.1ms)'
4
5echo ${LOG_LINE} | awk '{ t=$16; sub(/ms)/, "", t); print t }'

　　
2. sed を使う方法

以下のように、ActiveRecord: の後に続く数値部分で、テキスト全体を置き換えます。

bash
1#!/bin/bash
2
3LOG_LINE='I, [2015-07-02T03:47:07.832647 #1439]  INFO -- : Completed 200 OK in 374ms (Views: 35.2ms | ActiveRecord: 326.1ms)'
4
5echo ${LOG_LINE} | sed -E "s/^.*ActiveRecord: ([0-9.]+).*$/\1/"