【メール】【Postfix】メールトラブル、Postfix のトラブルシューティング方法

メールトラブル、特に Linux 系のメールサーバー(MTA)を利用している場合は、Postfix がほとんどだと思いますが、メールサーバーの構築や設定変更などでのメール送信トラブルや Postfix のトラブルシューティング方法について解説します。

 

こちらは Amazon EC2 インスタンスの Linux(Red Hat)から Postfix で Gmail 経由でインターネットにメールを送信する(メールサーバー)設定手順です。

 

【AWS】【メール】EC2 インスタンスから Postfix で Gmail 経由でインターネットにメールを送信する(メールサーバー)設定手順

 

 

こちらは自宅にてメールサーバーを構築し、Postfix で Gmail 経由でインターネットにメールを送信する(メールサーバー)設定手順です。

 

【Linux】 CentOS7環境で自宅メールサーバーの構築(Postfix と Dovecot と OP25B問題)

 

 

こちらは SMTP-AUTH や SASL などセキュリティ周りについて記述しています。

 

【SMTP】【Linux】外部の SMTP サービスを利用してメールを送信するための Linux の設定&コマンド

 

 

 

Postfix のデバッグを有効にする

Postfix にはデバッグ機能があります。

「/etc/postfix/main.cf」ファイルでデバッグを有効化できます。

[root@redhat7 log]# vi /etc/postfix/main.cf

 

~ 省略 ~

 

# DEBUGGING CONTROL 

# The debug_peer_level parameter specifies the increment in verbose 
# logging level when an SMTP client or server host name or address 
# matches a pattern in the debug_peer_list parameter. 

debug_peer_level = 2 
 
# The debug_peer_list parameter specifies an optional list of domain 
# or network patterns, /file/name patterns or type:name tables. When 
# an SMTP client or server host name or address matches a pattern, 
# increase the verbose logging level by the amount specified in the 
# debug_peer_level parameter. 

#debug_peer_list = 127.0.0.1 
#debug_peer_list = some.domain 
debug_peer_list = smtp.gmail.com

 

~ 省略 ~

 

[root@redhat7 log]#

 

 

デバッグの設定をする箇所は、以下の 2 つのパラメーターです。

debug_peer_level

debug_peer_level パラメーターで、SMTP サーバー、ホスト名、クライアント名、IP アドレスがマッチした際のログレベルを指定します。

何とマッチした場合化は、以下の「debug_peer_list」で設定します。

通常はデフォルトの 2 で充分です。

 

debug_peer_list

debug_peer_list パラメーターで、debug_peer_level で設定したデバッグレベルのログを取得する SMTP サーバー、ホスト名、クライアント名、IP アドレスを指定します。

 

 

 

デバッグログの参考例

「debug_peer_level = 2」の場合のデバッグログの参考例です。

May  1 06:02:22 redhat7 postfix/pickup[6695]: 4A273C78D95: uid=0 from=<root>
May  1 06:02:22 redhat7 postfix/cleanup[6705]: 4A273C78D95: message-id=<20190501060222.4A273C78D95@xxxxxxxxxx.com> ←  xxxxxxxxxx.com は、送信元のドメイン名です。
May  1 06:02:22 redhat7 postfix/qmgr[6696]: 4A273C78D95: from=<root@xxxxxxxxxx.com>, size=436, nrcpt=1 (queue active) 
May  1 06:02:22 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=300 enable_deadline=0 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 220 smtp.gmail.com ESMTP 15sm17689253pfo.117 - gsmtp 
May  1 06:02:22 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: EHLO xxxxxxxxxx.com 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-smtp.gmail.com at your service, [54.xxx.xxx.xxx] ←  [54.xxx.xxx.xxx] は、送信元のグローバル IP です。 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-SIZE 35882577 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-8BITMIME 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-STARTTLS 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-ENHANCEDSTATUSCODES 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-PIPELINING 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-CHUNKING 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250 SMTPUTF8 
May  1 06:02:22 redhat7 postfix/smtp[6698]: server features: 0x101f size 35882577 
May  1 06:02:22 redhat7 postfix/smtp[6698]: Using ESMTP PIPELINING, TCP send buffer size is 46080, PIPELINING buffer size is 4096 
May  1 06:02:22 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=300 enable_deadline=0 
May  1 06:02:22 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: STARTTLS 
May  1 06:02:22 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 220 2.0.0 Ready to start TLS 
May  1 06:02:22 redhat7 postfix/smtp[6698]: auto_clnt_open: connected to private/tlsmgr 
May  1 06:02:22 redhat7 postfix/smtp[6698]: send attr request = seed 
May  1 06:02:22 redhat7 postfix/smtp[6698]: send attr size = 32 
May  1 06:02:22 redhat7 postfix/smtp[6698]: private/tlsmgr: wanted attribute: status 
May  1 06:02:22 redhat7 postfix/smtp[6698]: input attribute name: status 
May  1 06:02:22 redhat7 postfix/smtp[6698]: input attribute value: 0 
May  1 06:02:22 redhat7 postfix/smtp[6698]: private/tlsmgr: wanted attribute: seed 
May  1 06:02:22 redhat7 postfix/smtp[6698]: input attribute name: seed 
May  1 06:02:22 redhat7 postfix/smtp[6698]: input attribute value: hKtHcRxMoitODtwytU1kFzKEwvOZ91+G8L7tqTiZ9lc= 
May  1 06:02:22 redhat7 postfix/smtp[6698]: private/tlsmgr: wanted attribute: (list terminator) 
May  1 06:02:22 redhat7 postfix/smtp[6698]: input attribute name: (end) 
May  1 06:02:22 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=300 enable_deadline=0 
May  1 06:02:22 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: EHLO xxxxxxxxxx.com 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-smtp.gmail.com at your service, [54.xxx.xxx.xxx] 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-SIZE 35882577 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-8BITMIME 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-AUTH LOGIN PLAIN XOAUTH2 PLAIN-CLIENTTOKEN OAUTHBEARER XOAUTH 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-ENHANCEDSTATUSCODES 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-PIPELINING 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250-CHUNKING 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250 SMTPUTF8 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_string: LOGIN ~? plain 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_list_match: LOGIN: no match 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_string: PLAIN ~? plain 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_string: XOAUTH2 ~? plain 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_list_match: XOAUTH2: no match 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_string: PLAIN-CLIENTTOKEN ~? plain 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_list_match: PLAIN-CLIENTTOKEN: no match 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_string: OAUTHBEARER ~? plain 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_list_match: OAUTHBEARER: no match 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_string: XOAUTH ~? plain 
May  1 06:02:23 redhat7 postfix/smtp[6698]: match_list_match: XOAUTH: no match 
May  1 06:02:23 redhat7 postfix/smtp[6698]: server features: 0x102f size 35882577 
May  1 06:02:23 redhat7 postfix/smtp[6698]: Using ESMTP PIPELINING, TCP send buffer size is 46080, PIPELINING buffer size is 4096 
May  1 06:02:23 redhat7 postfix/smtp[6698]: maps_find: smtp_sasl_passwd: smtp.gmail.com: not found 
May  1 06:02:23 redhat7 postfix/smtp[6698]: maps_find: smtp_sasl_passwd: hash:/etc/postfix/sasl_passwd(0,lock|fold_fix): [smtp.gmail.com]:587 = xxxxxx@gmail.com:Password001 ← SASL のログイン認証(SMTP-AUTH で、/etc/postfix/sasl_passwd ファイルから Gmail の SMTP サーバーへ接続するための Gmail のアカウントとパスワードを取得しています。パスワード(例:Password001)は平文でログに残るので注意です。) 
May  1 06:02:23 redhat7 postfix/smtp[6698]: smtp_sasl_passwd_lookup: host `smtp.gmail.com' user `xxxxxx@gmail.com' pass `com:Password001' 
May  1 06:02:23 redhat7 postfix/smtp[6698]: starting new SASL client 
May  1 06:02:23 redhat7 postfix/smtp[6698]: name_mask: noanonymous 
May  1 06:02:23 redhat7 postfix/smtp[6698]: smtp_sasl_authenticate: smtp.gmail.com[74.125.203.109]:587: SASL mechanisms PLAIN ← SASL の PLAIN を利用しています。 
May  1 06:02:23 redhat7 postfix/smtp[6698]: xsasl_cyrus_client_get_user: xxxxxxx@gmail.com 
May  1 06:02:23 redhat7 postfix/smtp[6698]: xsasl_cyrus_client_get_passwd: Password001 
May  1 06:02:23 redhat7 postfix/smtp[6698]: xsasl_cyrus_client_first: uncoded initial reply: \0xxxxxxxxx@gmail.com\000Password001 
May  1 06:02:23 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: AUTH PLAIN AGFjdGl2ZXZpc0BxxxxxxxxxxxxxxNhc2FnYXdh 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 235 2.7.0 Accepted 
May  1 06:02:23 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=300 enable_deadline=0 
May  1 06:02:23 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: MAIL FROM:<root@xxxxxxxxxx.com> SIZE=436 BODY=8BITMIME 
May  1 06:02:23 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: RCPT TO:<to_mail@gmail.com>  ← to_mail@gmail.com は宛先(送信先)のメールアドレスです。
May  1 06:02:23 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: DATA 
May  1 06:02:23 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=300 enable_deadline=0 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250 2.1.0 OK 15sm17689253pfo.117 - gsmtp 
May  1 06:02:23 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=300 enable_deadline=0 
May  1 06:02:23 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250 2.1.5 OK 15sm17689253pfo.117 - gsmtp 
May  1 06:02:23 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=120 enable_deadline=0 
May  1 06:02:24 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 354  Go ahead 15sm17689253pfo.117 - gsmtp 
May  1 06:02:24 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=180 enable_deadline=0 
May  1 06:02:24 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: . 
May  1 06:02:24 redhat7 postfix/smtp[6698]: > smtp.gmail.com[74.125.203.109]:587: QUIT 
May  1 06:02:24 redhat7 postfix/smtp[6698]: smtp_stream_setup: maxtime=600 enable_deadline=0 
May  1 06:02:24 redhat7 postfix/smtp[6698]: < smtp.gmail.com[74.125.203.109]:587: 250 2.0.0 OK  1556690544 15sm17689253pfo.117 - gsmtp 
May  1 06:02:24 redhat7 postfix/smtp[6698]: 4A273C78D95: to=<to_mail@gmail.com>, relay=smtp.gmail.com[74.125.203.109]:587, delay=2.7, delays=0/0/1.3/1.4, dsn=2.0.0, status=sent (250 2.0.0 OK  1556690544 15sm17689253pfo.117 - gsmtp) ← status=sent 250 2.0.0 OK のログが出力されているのでメール送信は成功しています。
May  1 06:02:24 redhat7 postfix/smtp[6698]: name_mask: resource 
May  1 06:02:24 redhat7 postfix/smtp[6698]: name_mask: software 
May  1 06:02:24 redhat7 postfix/smtp[6698]: disposing SASL state information 
May  1 06:02:24 redhat7 postfix/qmgr[6696]: 4A273C78D95: removed

 

 

 

Posted by 100%レンタルサーバーを使いこなすサイト管理人

コメントを残す

メールアドレスが公開されることはありません。

このサイトはスパムを低減するために Akismet を使っています。コメントデータの処理方法の詳細はこちらをご覧ください