From: Curt LeCaptain (lecaptaincinfinitytechnology.com)
Date: Tue Mar 06 2007 - 08:58:32 CST

• Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

-----Original Message-----
From: owner-postfix-userspostfix.org
[mailto:owner-postfix-userspostfix.org] On Behalf Of James Crow
Sent: Tuesday, March 06, 2007 8:44 AM
To: postfix-userspostfix.org
Subject: Mail Placed In Hold Queue After Server Replaced

Greetings all,

  I had a postfix server with MailScanner configured and working well. I
was in the process of configuring a new server to upgrade to, but the
old server had a disk failure. Instead of rebuilding with the old server
I decided to copy my config to the new server.

  I have postfix 2.3.8 installed on the new server. Old server was
2.1.15. I will not be using MailScanner on the new server. I copied over
my /etc/postfix directory and then started postfix on the new hardware.

  I am able to send email from a client, but postfix holds the email in
queue.
Can someone spot my problem from the attached logs?

emh1:/etc/postfix # postconf -n
alias_database = hash:/etc/mail/aliases
alias_maps = mysql:/etc/postfix/mysql-aliases.cf
always_bcc = adminstore-mail.ultratans.com broken_sasl_auth_clients =
yes command_directory = /usr/sbin config_directory = /etc/postfix
daemon_directory = /usr/lib/postfix debug_peer_level = 2
default_destination_concurrency_limit = 2 disable_vrfy_command = yes
header_checks = regexp:/etc/postfix/header_checks home_mailbox =
.maildir/ html_directory = no inet_interfaces = all
local_destination_concurrency_limit = 2 local_transport = local
mail_owner = postfix mailq_path = /usr/bin/mailq manpage_directory =
/usr/share/man message_size_limit = 22582912 mydestination =
$myhostname, localhost.$mydomain mydomain = emh1.ultratans.com
myhostname = emh1.ultratans.com mynetworks = /etc/postfix/mynetworks
mynetworks_style = host newaliases_path = /usr/bin/newaliases
owner_request_special = no queue_directory = /var/spool/postfix
readme_directory = /usr/share/doc/postfix-2.1.5-r2/readme
recipient_delimiter = +
relocated_maps = mysql:/etc/postfix/mysql-relocated.cf
sample_directory = /etc/postfix
sendmail_path = /usr/sbin/sendmail
setgid_group = maildrop
smtpd_banner = $myhostname ESMTP $mail_name
smtpd_client_restrictions = permit_mynetworks,
permit_sasl_authenticated,
reject_unauth_destination, reject_unknown_client
smtpd_delay_reject = yes
smtpd_helo_required = yes
smtpd_helo_restrictions = permit_mynetworks, check_helo_access

hash:/etc/postfix/helo_access, regexp:/etc/postfix/helo.regexp,
permit
smtpd_recipient_restrictions = permit_mynetworks,
check_client_access
hash:/etc/postfix/helo_client_exceptions, check_sender_access
hash:/etc/postfix/sender_checks, reject_unknown_sender_domain,
reject_unauth_pipelining, reject_non_fqdn_recipient,
reject_unknown_recipient_domain,permit_sasl_authenticated,
reject_unauth_destination, check_client_access
hash:/etc/postfix/rbl_client_exceptions, reject_rbl_client
cbl.abuseat.org, reject_rbl_client bl.spamcop.net,
reject_rhsbl_sender dsn.rfc-ignorant.org, reject_rbl_client
relays.ordb.org, reject_rbl_client list.dsbl.org, reject_rbl_client

sbl-xbl.spamhaus.org, permit
smtpd_sasl_auth_enable = no
smtpd_sasl_local_domain =
smtpd_sasl_security_options = noanonymous
smtpd_sender_restrictions = permit_mynetworks,
permit_sasl_authenticated,
permit
smtpd_tls_CAfile = /etc/postfix/cacert.pem smtpd_tls_cert_file =
/etc/postfix/newcert.pem smtpd_tls_key_file = /etc/postfix/newreq.pem
smtpd_tls_loglevel = 1 smtpd_tls_received_header = yes
smtpd_tls_session_cache_timeout = 3600s smtpd_use_tls = yes
strict_rfc821_envelopes = yes tls_random_source = dev:/dev/urandom
unknown_address_reject_code = 554 unknown_client_reject_code = 554
unknown_hostname_reject_code = 554 unknown_local_recipient_reject_code =
550 virtual_alias_maps = mysql:/etc/postfix/mysql-virtual.cf
virtual_gid_maps = static:1005
virtual_mailbox_base = /
virtual_mailbox_domains = store-mail.ultratans.com virtual_mailbox_maps
= mysql:/etc/postfix/mysql-virtual-maps.cf
virtual_transport = virtual
virtual_uid_maps = static:1015
emh1:/etc/postfix #

I have the cleanup daemon running inverbose mode. Here is the log from
an email received by postfix :

Mar 6 09:42:59 emh1 postfix/smtpd[9792]: warning: 68.115.197.46:
address not listed for hostname
68-115-197-046.static.spbg.sc.charter.com
Mar 6 09:42:59 emh1 postfix/smtpd[9792]: connect from
unknown[68.115.197.46] Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
connection established Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
master_notify: status 0 Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
mail_flow_get: 1 1 Mar 6 09:43:00 emh1 postfix/cleanup[9693]: open
incoming/32464E3A Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
cleanup_open: open incoming/32464E3A Mar 6 09:43:00 emh1
postfix/cleanup[9693]: send attr queue_id = 32464E3A Mar 6 09:43:00
emh1 postfix/cleanup[9693]: cleanup socket: wanted attribute:
flags
Mar 6 09:43:00 emh1 postfix/smtpd[9792]: 32464E3A:
client=unknown[68.115.197.46]
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name: flags
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute value: 50
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: cleanup socket: wanted
attribute:
(list terminator)
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name: (end)
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: cleanup flags =
enable_header_body_filter enable_automatic_bcc enable_address_mapping
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope T
1173192180
101705
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope A
rewrite_context=remote Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
initial envelope S greerstore-mail.ultratans.com Mar 6 09:43:00 emh1
postfix/cleanup[9693]: send attr request = rewrite Mar 6 09:43:00 emh1
postfix/cleanup[9693]: send attr rule = local Mar 6 09:43:00 emh1
postfix/cleanup[9693]: send attr address =
greerstore-mail.ultratans.com Mar 6 09:43:00 emh1
postfix/cleanup[9693]: private/rewrite socket: wanted
attribute: flags
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name: flags
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute value: 0 Mar
6 09:43:00 emh1 postfix/cleanup[9693]: private/rewrite socket: wanted
attribute: address
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name:
address Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute
value:
greerstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: private/rewrite socket:
wanted
attribute: (list terminator)
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name: (end)
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: rewrite_clnt: local:
greerstore-mail.ultratans.com -> greerstore-mail.ultratans.com Mar 6
09:43:00 emh1 postfix/cleanup[9693]: initial envelope A
log_client_address=68.115.197.46
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope A
log_message_origin=unknown[68.115.197.46]
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope A
log_helo_name=greer-srvr Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
initial envelope A log_protocol_name=SMTP Mar 6 09:43:00 emh1
postfix/cleanup[9693]: initial envelope A client_name=unknown Mar 6
09:43:00 emh1 postfix/cleanup[9693]: initial envelope A
reverse_client_name=68-115-197-046.static.spbg.sc.charter.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope A
client_address=68.115.197.46
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope A
helo_name=greer-srvr Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial
envelope A
client_address_type=2
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope A
dsn_orig_rcpt=rfc822;cancellationsstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope R
cancellationsstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: send attr request = rewrite
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: send attr rule = local Mar
6 09:43:00 emh1 postfix/cleanup[9693]: send attr address =
cancellationsstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: private/rewrite socket:
wanted
attribute: flags
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name: flags
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute value: 0 Mar
6 09:43:00 emh1 postfix/cleanup[9693]: private/rewrite socket: wanted
attribute: address
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name:
address Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute
value:
cancellationsstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: private/rewrite socket:
wanted
attribute: (list terminator)
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name: (end)
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: rewrite_clnt: local:
cancellationsstore-mail.ultratans.com ->
cancellationsstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: been_here_check:
cancellationsstore-mail.ultratans.com: 0 Mar 6 09:43:00 emh1
postfix/cleanup[9693]: dict_mysql_get_active: found active connection to
host unix:/var/lib/mysql/mysql.sock Mar 6 09:43:00 emh1
postfix/cleanup[9693]: dict_mysql: successful query from host
unix:/var/lib/mysql/mysql.sock Mar 6 09:43:00 emh1
postfix/cleanup[9693]: dict_mysql_lookup: retrieved 0 rows Mar 6
09:43:00 emh1 postfix/cleanup[9693]: maps_find: virtual_alias_maps:
cancellationsstore-mail.ultratans.com: not found Mar 6 09:43:00 emh1
postfix/cleanup[9693]: match_string:
store-mail.ultratans.com ~? emh1.ultratans.com Mar 6 09:43:00 emh1
postfix/cleanup[9693]: match_string:
store-mail.ultratans.com ~? localhost.emh1.ultratans.com Mar 6 09:43:00
emh1 postfix/cleanup[9693]: match_list_match:
store-mail.ultratans.com: no match
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: dict_mysql_get_active: found
active connection to host unix:/var/lib/mysql/mysql.sock Mar 6 09:43:00
emh1 postfix/cleanup[9693]: dict_mysql: successful query from host
unix:/var/lib/mysql/mysql.sock Mar 6 09:43:00 emh1
postfix/cleanup[9693]: dict_mysql_lookup: retrieved 0 rows Mar 6
09:43:00 emh1 postfix/cleanup[9693]: maps_find: virtual_alias_maps:
store-mail.ultratans.com: not found
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: mail_addr_find:
cancellationsstore-mail.ultratans.com -> (not found) Mar 6 09:43:00
emh1 postfix/cleanup[9693]: mail_addr_map:
cancellationsstore-mail.ultratans.com -> (not found) Mar 6 09:43:00
emh1 postfix/cleanup[9693]: been_here:
rfc822;cancellationsstore-mail.ultratans.com?0?cancellationsstore-mail
.ultratans.com?cancellationsstore-mail.ultratans.com:
0
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: initial envelope M Mar 6
09:43:00 emh1 postfix/cleanup[9693]:
cleanup_header_callback: 'Received: from greer-srvr (unknown
[68.115.197.46])??by emh1.ultratans.com (Postfix) with SMTP id
32464E3A??for <cancellationsstore-mail.ultratans.com>; Tue, 6 Mar 2007
09:43:00 -0500 (EST)'
Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
dict_regexp_lookup: /etc/postfix/header_checks: Received: from
greer-srvr (unknown [68.115.197.46])??by emh1.ultratans.com (Postfix)
with SMTP id 32464E3A??for <cancellationsstore-mail.ultratans.com>;
Tue, 6 Mar 2007 09:43:00 -0500 (EST) Mar 6 09:43:00 emh1
postfix/cleanup[9693]: maps_find: header_checks:
regexp:/etc/postfix/header_checks(0,lock): Received: from greer-srvr
(unknown [68.115.197.46])??by emh1.ultratans.com (Postfix) with SMTP id
32464E3A??for <cancellationsstore-mail.ultratans.com>; Tue, 6 Mar 2007
09:43:00 -0500
(EST) = HOLD
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: 32464E3A: hold: header
Received:
from greer-srvr (unknown [68.115.197.46])??by emh1.ultratans.com
(Postfix) with SMTP id 32464E3A??for
<cancellationsstore-mail.ultratans.com>; Tue, 6 Mar 2007 09:43:00
-0500 (EST) from unknown[68.115.197.46];
from=<greerstore-mail.ultratans.com>
to=<cancellationsstore-mail.ultratans.com> proto=SMTP helo=<greer-srvr>
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: cleanup_header_callback:
'Date:
Tue, 06 Mar 2007 09:43:02 -0500'
Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
dict_regexp_lookup: /etc/postfix/header_checks: Date: Tue, 06 Mar 2007
09:43:02 -0500
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: maps_find: header_checks:
Date:
Tue, 06 Mar 2007 09:43:02 -0500: not found Mar 6 09:43:00 emh1
postfix/cleanup[9693]: cleanup_header_callback: 'From:
greerstore-mail.ultratans.com'
Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
dict_regexp_lookup: /etc/postfix/header_checks: From:
greerstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: maps_find: header_checks:
From:
greerstore-mail.ultratans.com: not found Mar 6 09:43:00 emh1
postfix/cleanup[9693]: cleanup_header_callback: 'Subject:
Un-Freeze - Control Number: 030620079006037 '
Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
dict_regexp_lookup: /etc/postfix/header_checks: Subject: Un-Freeze -
Control
Number: 030620079006037
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: maps_find: header_checks:
Subject:
Un-Freeze - Control Number: 030620079006037 : not found Mar 6 09:43:00
emh1 postfix/cleanup[9693]: cleanup_header_callback: 'To:
cancellationsstore-mail.ultratans.com'
Mar 6 09:43:00 emh1 postfix/cleanup[9693]:
dict_regexp_lookup: /etc/postfix/header_checks: To:
cancellationsstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: maps_find: header_checks:
To:
cancellationsstore-mail.ultratans.com: not found Mar 6 09:43:00 emh1
postfix/cleanup[9693]: 32464E3A:
message-id=<20070306144300.32464E3Aemh1.ultratans.com>
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: extracted envelope E Mar 6
09:43:00 emh1 postfix/cleanup[9693]: send attr request = rewrite Mar 6
09:43:00 emh1 postfix/cleanup[9693]: send attr rule = local Mar 6
09:43:00 emh1 postfix/cleanup[9693]: send attr address =
adminstore-mail.ultratans.com Mar 6 09:43:00 emh1
postfix/cleanup[9693]: private/rewrite socket: wanted
attribute: flags
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name: flags
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute value: 0 Mar
6 09:43:00 emh1 postfix/cleanup[9693]: private/rewrite socket: wanted
attribute: address
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name:
address Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute
value:
adminstore-mail.ultratans.com
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: private/rewrite socket:
wanted
attribute: (list terminator)
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: input attribute name: (end)
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: rewrite_clnt: local:
adminstore-mail.ultratans.com -> adminstore-mail.ultratans.com Mar 6
09:43:00 emh1 postfix/cleanup[9693]: been_here_check:
adminstore-mail.ultratans.com: 0
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: dict_mysql_get_active: found
active connection to host unix:/var/lib/mysql/mysql.sock Mar 6 09:43:00
emh1 postfix/cleanup[9693]: dict_mysql: successful query from host
unix:/var/lib/mysql/mysql.sock Mar 6 09:43:00 emh1
postfix/cleanup[9693]: dict_mysql_lookup: retrieved 0 rows Mar 6
09:43:00 emh1 postfix/cleanup[9693]: maps_find: virtual_alias_maps:
adminstore-mail.ultratans.com: not found Mar 6 09:43:00 emh1
postfix/cleanup[9693]: match_string:
store-mail.ultratans.com ~? emh1.ultratans.com Mar 6 09:43:00 emh1
postfix/cleanup[9693]: match_string:
store-mail.ultratans.com ~? localhost.emh1.ultratans.com Mar 6 09:43:00
emh1 postfix/cleanup[9693]: match_list_match:
store-mail.ultratans.com: no match
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: dict_mysql_get_active: found
active connection to host unix:/var/lib/mysql/mysql.sock Mar 6 09:43:00
emh1 postfix/cleanup[9693]: dict_mysql: successful query from host
unix:/var/lib/mysql/mysql.sock Mar 6 09:43:00 emh1
postfix/cleanup[9693]: dict_mysql_lookup: retrieved 0 rows Mar 6
09:43:00 emh1 postfix/cleanup[9693]: maps_find: virtual_alias_maps:
store-mail.ultratans.com: not found
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: mail_addr_find:
adminstore-mail.ultratans.com -> (not found) Mar 6 09:43:00 emh1
postfix/cleanup[9693]: mail_addr_map:
adminstore-mail.ultratans.com -> (not found) Mar 6 09:43:00 emh1
postfix/cleanup[9693]:
been_here:
?1?adminstore-mail.ultratans.com?adminstore-mail.ultratans.com:
0
Mar 6 09:43:00 emh1 postfix/cleanup[9693]: 32464E3A: placed in hold
queue Mar 6 09:43:00 emh1 postfix/cleanup[9693]: mail_flow_put: 1 1 Mar
6 09:43:00 emh1 postfix/cleanup[9693]: cleanup_flush: status 0 Mar 6
09:43:00 emh1 postfix/cleanup[9693]: send attr status = 0 Mar 6
09:43:00 emh1 postfix/cleanup[9693]: send attr reason = Mar 6 09:43:00
emh1 postfix/cleanup[9693]: master_notify: status 1 Mar 6 09:43:00 emh1
postfix/cleanup[9693]: connection closed Mar 6 09:43:00 emh1
postfix/smtpd[9792]: disconnect from unknown[68.115.197.46]

Your problem is here more than likely with how MailScanner works:

header_checks = regexp:/etc/postfix/header_checks

More than likely you have a regexp looking for "Received" as per
MailScanner setup. Try commenting that line out and see if things work.

Also, you may want to look into removing relays.ordb.org from your RBL
checking, as ordb is no longer in service.

-Curt

• Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]