postfix-policyd-spf-perl

Connection timed out

Asked by M. Rodrigo Monteiro

Hi. I'm getting lots of warning message like these. What could be the problem? Even thoug I got these warning, it's working fine.

# grep 'warning: problem talking to server private/policyd-spf-perl: Connection timed out' /var/log/maillog | wc -l
8841

May 23 12:40:18 XX postfix/smtpd[15417]: warning: problem talking to server private/policyd-spf-perl: Connection timed out
May 23 12:40:18 XX postfix/smtpd[11785]: warning: problem talking to server private/policyd-spf-perl: Connection timed out
May 23 12:40:19 XX postfix/smtpd[7925]: warning: problem talking to server private/policyd-spf-perl: Connection timed out

Configuration

# postconf -d | grep mail_version
mail_version = 2.6.6

postfix-policyd-spf-perl-2.010

main.cf
policyd-spf-perl_time_limit = 3600

master.cf
policyd-spf-perl unix - n n - 0 spawn
  user=nobody argv=/opt/postfix-policyd-spf-perl/postfix-policyd-spf-perl

Question information

Language:
English Edit question
Status:
Solved
For:
postfix-policyd-spf-perl Edit question
Assignee:
No assignee Edit question
Solved by:
M. Rodrigo Monteiro
Solved:
Last query:
Last reply:
Revision history for this message
Scott Kitterman (kitterman) said : 		#1

It would help if you didn't snip the log so narrowly.

If you can send me (via email if you don't want it in the public question) and unredacted log snippet that shows all the postfix log entries for the emails in question, I can see what I can figure out.

Revision history for this message
M. Rodrigo Monteiro (mrodrigom) said : 		#2

Scott, as I said, it's working. The only problem is the number of lines in maillog with that warning. If you see below, in just 20 minutes grow from 8841 to 10667.

# grep 'warning: problem talking to server private/policyd-spf-perl: Connection timed out' /var/log/maillog | wc -l
10667

What log do you want? The full maillog?

# tail -f /var/log/maillog
May 23 13:19:22 XX postfix/policy-spf[24398]: Policy action=DEFER_IF_PERMIT SPF-Result=parceiros.lmlmgv.com.br ... customspf.gvarev.com.br: Unknown error on DNS 'TXT' lookup of 'customspf.gvarev.com.br'
May 23 13:19:22 XX postfix/policy-spf[25106]: Policy action=PREPEND Received-SPF: pass (hotmail.com: Sender is authorized to use '<email address hidden>' in 'mfrom' identity (mechanism 'include:spf-a.hotmail.com' matched)) receiver=intsrv037; identity=mailfrom; <email address hidden>"; helo=blu0-omc4-s20.blu0.hotmail.com; client-ip=65.55.111.159
May 23 13:19:23 XX postfix/policy-spf[24403]: Policy action=DEFER_IF_PERMIT SPF-Result=mx.sailthru.com ... aspmx.sailthru.com: Unknown error on DNS 'TXT' lookup of 'aspmx.sailthru.com'
May 23 13:19:23 XX postfix/policy-spf[25110]: Policy action=550 Please see http://www.openspf.net/Why?s=mfrom;id=caebtour%40uol.com.br;ip=74.63.255.171;r=intsrv037
May 23 13:19:23 XX postfix/policy-spf[24129]: Policy action=DEFER_IF_PERMIT SPF-Result=parceiros.lmlmgv.com.br ... customspf.gvarev.com.br: Unknown error on DNS 'TXT' lookup of 'customspf.gvarev.com.br'
May 23 13:19:24 XX postfix/policy-spf[24982]: Policy action=550 Please see http://www.openspf.net/Why?s=mfrom;id=caebtour%40uol.com.br;ip=74.63.255.174;r=intsrv037
May 23 13:19:25 XX postfix/policy-spf[24406]: Policy action=DEFER_IF_PERMIT SPF-Result=parceiros.ececgv.com.br ... customspf.gvarev.com.br: Unknown error on DNS 'TXT' lookup of 'customspf.gvarev.com.br'

Revision history for this message
M. Rodrigo Monteiro (mrodrigom) said : 		#3

Because of this problem, I had to disable the SPF verification...

Revision history for this message
Scott Kitterman (kitterman) said : 		#4

I still need the actual logs to try and understand this. Feel free to send them to me directly.

Revision history for this message
M. Rodrigo Monteiro (mrodrigom) said : 		#5

What logs do you need?

Revision history for this message
M. Rodrigo Monteiro (mrodrigom) said : 		#6

After upgraded the Mail::SPF perl module from CPAN, it seems that the problem has gone away.
Before I was using the version from CentOS 6.5 rpm.