回答編集履歴

2

ログの確認内容を追記

2018/01/17 08:00

投稿

CHERRY
CHERRY

スコア25171

test CHANGED
@@ -29,3 +29,105 @@
29
29
 
30
30
 
31
31
  > postfix/smtpd[14621]: > mrsts405p01c.softbank.ne.jp[123.108.236.71]: 220 【自メールサーバー】 ESMTP smtpd_client_restrictions = permit_mynetworks, reject_rbl_client spamcop.net, reject_rbl_client dynablock.wirehub.net, reject_rbl_client opm.blitzed.org, reject_rbl_client relays.visi.com, reject_rbl_client sbl.spamhaus.org, check_client_access hash:/etc/postfix/reject_client, permit
32
+
33
+
34
+
35
+ ----
36
+
37
+ 追記(2018-01-17 17:00)
38
+
39
+
40
+
41
+ 追記いただいたログから 主要部分を抜き出すと、以下になるのですが、データをやり取りするコネクションが、全部記録されていませんね。
42
+
43
+
44
+
45
+ ```
46
+
47
+ postfix/smtpd[14621]: send attr request = connect
48
+
49
+ postfix/smtpd[14621]: > mrsts405p01c.softbank.ne.jp[123.108.236.71]: 220 【自メールサーバー】 ESMTP
50
+
51
+ postfix/smtpd[14621]: send attr request = disconnect
52
+
53
+ ```
54
+
55
+  
56
+
57
+  
58
+
59
+ おそらく、このログだけだとわかりにくいと思うので、比較するために softbank のメールアドレスから、うちのサーバーに実際にメールを送って実際にログを取ってみました。
60
+
61
+ その際のログから主要部分を抜き出すと以下のような記録になります。 (1)〜(13) の数字は、説明のために追加しています。(なお、中央付近の「>」 は、 postfix が送信 / 「<」 は、受信です。)
62
+
63
+
64
+
65
+ ```
66
+
67
+ (1) postfix/smtpd[14621]: send attr request = connect
68
+
69
+
70
+
71
+ (2) postfix/smtpd[6471]: > softbank.ne.jp[117.46.14.xxx]: 220 mail.example.com ESMTP Postfix
72
+
73
+
74
+
75
+ (3) postfix/smtpd[6471]: < softbank.ne.jp[117.46.14.xxx]: HELO softbank.ne.jp
76
+
77
+
78
+
79
+ (4) postfix/smtpd[6471]: < softbank.ne.jp[117.46.14.xxx]: MAIL FROM:<...example-user...@softbank.ne.jp>
80
+
81
+
82
+
83
+ (5) postfix/smtpd[6471]: > softbank.ne.jp[117.46.14.xxx]: 250 2.1.0 Ok
84
+
85
+
86
+
87
+ (6) postfix/smtpd[6471]: < softbank.ne.jp[117.46.14.xxx]: RCPT TO:<user@example.com>
88
+
89
+
90
+
91
+ (7) postfix/smtpd[6471]: > softbank.ne.jp[117.46.14.xxx]: 250 2.1.5 Ok
92
+
93
+
94
+
95
+ (8) postfix/smtpd[6471]: < softbank.ne.jp[117.46.14.xxx]: DATA
96
+
97
+
98
+
99
+ (9) postfix/smtpd[6471]: > softbank.ne.jp[117.46.14.xxx]: 354 End data with <CR><LF>.<CR><LF>
100
+
101
+
102
+
103
+ (10) postfix/smtpd[6471]: > softbank.ne.jp[117.46.14.xxx]: 250 2.0.0 Ok: queued as 073FA48031E40
104
+
105
+
106
+
107
+ (11) postfix/smtpd[6471]: < softbank.ne.jp[117.46.14.xxx]: QUIT
108
+
109
+
110
+
111
+ (12) postfix/smtpd[6471]: > softbank.ne.jp[117.46.14.xxx]: 221 2.0.0 Bye
112
+
113
+
114
+
115
+ (13) postfix/smtpd[6471]: send attr request = disconnect
116
+
117
+ ```
118
+
119
+
120
+
121
+ 質問に記載されているログから抜き出した分と比較してみるとわかると思うのですが、データをやり取りする通信部分( (3) 〜 (12) )のログが、質問のログには、記録されていません。
122
+
123
+
124
+
125
+
126
+
127
+ どのような状況なのか詳細は断言できませんが、「lost connection after CONNECT」というメッセージと「データをやり取りする通信部分( (3) 〜 (12) )」が欠落していることから Postfix が smtp 接続してきたコネクションに対して、応答メッセージを返す前に コネクションが切断されているのではないかと思われます。
128
+
129
+
130
+
131
+ Postfix のサーバとインターネットの間にあるルータやファイアウォール等ネットワーク機器のログを確認したら何か記録されていないでしょうか?
132
+
133
+ (例: メールのエラー時刻前後にエラーが出ている等)

1

追記

2018/01/17 08:00

投稿

CHERRY
CHERRY

スコア25171

test CHANGED
@@ -9,3 +9,23 @@
9
9
 
10
10
 
11
11
  ログを見てもよくわからない場合は、質問を編集して、詳細ログの追加をお願いします。
12
+
13
+
14
+
15
+ ----
16
+
17
+
18
+
19
+ 追記(2018-01-17 16:50)
20
+
21
+
22
+
23
+ まず、確認ですが...
24
+
25
+ ログで、以下の行がありますが、整形・編集前もこのような状態だったのしょうか?
26
+
27
+ それとも ログを整形・編集した際に繋がってしまったのでしょうか?
28
+
29
+
30
+
31
+ > postfix/smtpd[14621]: > mrsts405p01c.softbank.ne.jp[123.108.236.71]: 220 【自メールサーバー】 ESMTP smtpd_client_restrictions = permit_mynetworks, reject_rbl_client spamcop.net, reject_rbl_client dynablock.wirehub.net, reject_rbl_client opm.blitzed.org, reject_rbl_client relays.visi.com, reject_rbl_client sbl.spamhaus.org, check_client_access hash:/etc/postfix/reject_client, permit