∀Programmer ∃Python

最近社内でにわかに Slack ブームが来ている。 今まで Google Hangouts を使っていたけど、Slack の方がなんだか便利そうじゃない? というぐらいの話。

Slack の方が便利そう、という根拠の一つが外部のものと連携できる機能にある。 じゃあ何かやってみよう。 AWS Lambda で、とりあえず Slack のスラッシュコマンドを追加してみる。 もちろん Python で。

やり方は基本的に 【新機能】AWS LambdaにSlack連携のBluePrintが登場。ChatOpsがより手軽に という記事を読んで slack-echo-command の代わりに slack-echo-command-python を選べば Python で書いたコマンドが追加できる。

何を書いたか、とかはおいておくことにして。

あとは、そこに書いていなかった(そして AWS Official Blog の New – Slack Integration Blueprints for AWS Lambda にも書いていなかった)細かい点をいくつか。

1. 返すのは辞書
2. text はひとまとめに来る

サンプルコードは文字列を返す形で書いてあるので、結果も文字列で返せばいいのかと思いきや、{"text": "foo bar"} という辞書で返す方が良い。 正しくは Slack API 文書の Slash Commands に書いてある JSON ペイロードに相当するものということになるのだろうが、 ともかく、文字列を返してしまうと、前後に引用符が付いて改行も \n と表示されてしまうので注意。

text というのはスラッシュコマンド /spam の後ろの引数として使う部分。 サンプルコードで params['text'][0] とか書いていたので空白で分割して送ってくれたりするのかと思ったが、そんなことはなかった。 必要なら自分でパースすることになる。

Python 実行スクリプトのプロファイルを取るには cProfile モジュールを -m を使って実行する。

$ python -m cProfile -s tottime spam.py 42

すると、こんな出力が出る。

  1794060 function calls (1791259 primitive calls) in 7.057 seconds

   Ordered by: internal time

   ncalls  tottime  percall  cumtime  percall filename:lineno(function)
     9715    3.492    0.000    5.249    0.000 hoge.py:7(heavy_function)
    30910    0.413    0.000    0.413    0.000 {method 'fuga' of '_foo.bar' objects}
    30910    0.282    0.000    5.509    0.000 foo.py:365(execute)
(以下略)

最初の行は総計でどれだけ関数呼び出しがあったか、何秒掛かったかが表示されている。 -s tottime を指定したので、下の表は tottime の大きい順にソートされて表示される。 tottime はその関数自体で何秒費やしたかという合計時間(total time)。 ここでは hoge.py の7行目にある heavy_function が9715回呼ばれて3.492秒掛かったのが一番。 heavy_function というわりには1回あたりはせいぜい0.3ミリ秒だが、まあここが速くなればプログラムの実行が早くなるだろうという部分ではある。 ちなみに上の「1回あたりはせいぜい0.3ミリ秒」という情報は tottime の隣の percall に表示されていることになっているのだが、単位が秒のため表示桁の制約上 0.000 としか表示されていない。 大概の場合この percall 欄は役に立たない。

2行目の関数名の欄が {method 'fuga' of '_foo.bar' objects} という表示で行番号が出ていないのは、C のライブラリが呼び出されている部分。 3行目と呼び出し回数が同じなので、おそらく3行目の foo.py にある execute 関数がこのライブラリを呼び出しているのだろう。

これで大体出力の読み方も解ったと思うが、もう一つ cumtime が気になる人もいるかもしれない。 これは cumulative time つまり累積時間のことで関数から更に呼ばれた関数での時間もこみこみで出した消費時間のことを意味する。 場合によっては tottime より cumtime を見たいこともあるかもしれない。 そうしたときは -s cumtime を -s tottime の代わりに指定すれば cumtime 順で出力される。

参考:

• Python プロファイラ (Python2)
• Python プロファイラ (Python3)