postfixのログの見方について

質問

postfixで転送しているとき、下記のログがでます。
----------------------------------------------------
①Jul 16 17:35:27 ns1 postfix/pipe[20167]: 1540F40A5F12: to=<宛先のメールアドレス>, relay=procmail, delay=0.02, delays=0.01/0.01/0/0, dsn=2.0.0, status=sent (delivered via procmail service)
②Jul 16 17:35:27 ns1 postfix/pipe[20167]: 1540F40A5F12: to=<転送先のメールアドレス>, orig_to=<宛先のメールアドレス>, relay=procmail, delay=0.02, delays=0.01/0.01/0/0, dsn=2.0.0, status=sent (delivered via procmail service)
③Jul 16 17:35:27 ns1 postfix/pipe[20167]: 1540F40A5F12: to=<転送先のメールアドレス>, relay=procmail, delay=0.02, delays=0.01/0.01/0/0, dsn=2.0.0, status=sent (delivered via procmail service)
----------------------------------------------------
①ではprocmailで元々の宛先のメールアドレスに配送しています。
②③では転送先のメールアドレスにメールを転送しているように見受けられます。
1通のみ転送すればよいのですが、2通転送しているという意味でしょうか。
実際は、転送先には1通のみ届ています。
②と③の違い、2つログがでる理由がわかれば、教えていただけないでしょうか。

回答No.2

Ultra-Hetare · ベストアンサー

postfixは様々な小さなプログラムの集合体が
1つのMTAを構成しています。

ログを直接書き込んでいるのは「pipe」
deamonでしょう。

①でprocmailに渡しているのは解りますが、
そのままではダメだったので突き返されて、

②はオリジナルの転送先を書き換えたという、
多分「trivial-rewrite」あたりから受けたたログでしょう。

③はその後でメールを実際に転送した「smtp」あたりから無事に
procmailに渡せた、出力ログと見受けられます。

その他、このMTAは実に多くの小さなdaemonが協力して
仕事をしていますので、そのあたりを調べてからログを見ると
楽しいかも知れません。

まあ、詳しく調べたわけではなく、私がpostfix運用上で
得た知識による、推察ですが・・

お礼

他の方の話が聞けて、とても勉強になりました。
ありがとうございます。

回答No.1

akira1720

そうですね、ログを目で読む側の立場からすれば、2 があれば 3 はいらないんじゃないかなーと思います。

推測ですが、メールを実際に宛先サーバに届けるときのログは必ず 1, 3 のシンプルな形式のログが出力されることが決まっていて、自動転送の場合はそれに加えて 2 の形式のログが出力されることに(後付けで)なったのではないでしょうか。それなら 1, 3 の形式の行だけ処理するスクリプトがあったとしても転送を見逃すことはないです。2, 3 が重複しているように見えますが、セッションIDを見れば同一のメールについての記述であることはわかります。

お礼

回答ありがとうございます。
実際は1通しか転送されていませんし、確かに同一のメールであることはわかるので、重複しているわけではなさそうです。
勉強になります。