Postfixのログの”delays”について
2008/02/21 13:05 posted by kunkichi

今、仕事で短期的なメールサーバを構築しているんだけど、一通り作業が終わったのでテストでメールを送信してたんだけど、なーんか相手先に送信するまでに時間がかかるなぁと思って、別のターミナルでログを tail -f で流していたらこんなログが目に付きました（一部伏せてます）。

Feb 21 12:04:00 mail postfix/smtp[4938]: XXXXXXXXXXXX: to=<xxxxxxxx@example.com>, relay=mail.xxxxxxxx.com[XXX.XXX.XXX.XXX]:25, delay=56, delays=0.04/0.01/54/1.6, dsn=2.0.0, status=sent (250 2.0.0 XXXXXXXXXXXX Message accepted for delivery)

delayは当然大きいのだけど、その後の”delays“という項目、なんか数字が4つ並んでいて、全部足すと大体delayと同じになる。んー、これはもしかしてどこの処理で遅くなっているかがわかるのかなと推測して、ってことでググってみたら、

[postfix-jp: 2334] Re: delays

>delays=6.5/0/0/0.05　＜？？？
>
>意味か文献場所をご教授ねがいます

RELEASE_NOTES に書いてあるみたいですが。
—————————————————————–
- Better insight into the nature of performance bottle necks, with
detailed logging of delays in various stages of message delivery.
Postfix logs additional delay information as “delays=a/b/c/d”
where a=time before queue manager, including message transmission;
b=time in queue manager; c=connection setup time including DNS,
HELO and TLS; d=message transmission time.
—————————————————————–

postfix の src を展開したディレクトリィの README_FILES/ にあり
ます。

やっぱりそういうことですか。

a=time before queue manager, including message transmission;
b=time in queue manager
c=connection setup time including DNS,HELO and TLS
d=message transmission time.

ちなみに僕のテストの場合は、Cで時間がかかっていてなんでかなと思ってログを遡ってみると、1回送信しようとしたのだけどconnection timeoutして再送後成功してたからということでした。

何年もメールサーバ管理してたのに知らなかったよ、でもqmailが多かったし、と言い訳しておきます。

Category: Server |