From: James Crow (jamesultratans.com)
Date: Tue Mar 06 2007 - 08:44:03 CST

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

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]

If there is some other info/logs I need to post I will be happy to provide
them

Thanks,
James

--
James Crow
IT Manager
ULTRATAN, Inc.

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