メールトラブル、特に 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
|
コメント