Hello,

I have recently spun up a Postfix server that is currently in a testing
phase. It is currently not being used at the moment - so there is zero load
on this server.

I am actively using Postfix 2.11, SpamAssassin 3.3.1 and Dovecot 2.0.9 for
POP3.

When I try sending a basic test e-mail through PowerShell using my Postfix
box as the SMTP server - I'm seeing 20-40+ second delays in the
/var/log/maillog per e-mail.

Here is what I see in the logs:

Mar 12 07:59:36 postfix/smtpd[21189]: connect from unknown[10.1.10.45]
Mar 12 07:59:36 postfix/smtpd[21189]: 2E69C1E0203:
client=unknown[10.1.10.45]
Mar 12 07:59:36 postfix/cleanup[21191]: 2E69C1E0203: message-id=<>
Mar 12 07:59:36 postfix/qmgr[20944]: 2E69C1E0203: from=<me@workdomain>,
size=414, nrcpt=1 (queue active)
Mar 12 07:59:36 spamd[15544]: spamd: connection from localhost [127.0.0.1]
at port 56378
Mar 12 07:59:36 spamd[15544]: spamd: setuid to spamfilter succeeded
Mar 12 07:59:36 spamd[15544]: spamd: processing message (unknown) for
spamfilter:5001
Mar 12 07:59:36 spamd[15544]: spamd: clean message (-0.9/5.0) for
spamfilter:5001 in 0.1 seconds, 439 bytes.
Mar 12 07:59:36 spamd[15544]: spamd: result: . 0 - ALL_TRUSTED,MISSING_MID
scantime=0.1,size=439,user=spamfilter,uid=5001,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=56378,mid=(unknown),autolearn=no
Mar 12 07:59:36 postfix/postdrop[21196]: warning: unable to look up
public/pickup: Permission denied
Mar 12 07:59:36 postfix/pipe[21192]: 2E69C1E0203: to=<me@workdomain>,
relay=spamfilter, delay=0.17, delays=0.02/0.02/0/0.13, dsn=2.0.0,
status=sent (delivered via spamfilter service)
Mar 12 07:59:36 postfix/qmgr[20944]: 2E69C1E0203: removed
Mar 12 *07:59:36* spamd[15542]: prefork: child states: II
Mar 12 *08:00:06* postfix/pickup[20942]: 5B5A81E01ED: uid=5001
from=<me@workdomain>
Mar 12 08:00:06 postfix/cleanup[21191]: 5B5A81E01ED:
message-id=<20140312130006.5B5A81E01ED@localhost>
Mar 12 08:00:06 postfix/qmgr[20944]: 5B5A81E01ED: from=<m...@workdomain.com>,
size=772, nrcpt=1 (queue active)
Mar 12 08:00:08 postfix/smtp[21200]: 5B5A81E01ED: to=<me@workdomain>,
relay=smtp.workdomain[10.10.106.10]:25, delay=32, delays=30/0.02/0.14/1.8,
dsn=2.6.0, status=sent (250 2.6.0 <20140312130006.5B5A81E01ED@localhost>
[InternalId=10592664] Queued mail for delivery)
Mar 12 08:00:08 postfix/qmgr[20944]: 5B5A81E01ED: removed

Mail originating from the Postfix server and being sent out are near
instantaneous - it's just the relay portion that appears to be taking some
time. Is the above normal? Or is there something I can tweak to have these
go out quicker? I'm completely open to suggestions.

Here is my Postfix configuration:

>>postconf -n
alias_database = hash:/etc/aliases
alias_maps = hash:/etc/aliases
command_directory = /usr/sbin
config_directory = /etc/postfix
daemon_directory = /usr/libexec/postfix
data_directory = /var/lib/postfix
debug_peer_level = 2
debugger_command = PATH=/bin:/usr/bin:/usr/local/bin:/usr/X11R6/bin ddd
$daemon_directory/$process_name $process_id & sleep 5
home_mailbox = Maildir/
html_directory = no
inet_interfaces = all
inet_protocols = ipv4
local_recipient_maps =
mail_owner = postfix
mailq_path = /usr/bin/mailq.postfix
manpage_directory = /usr/share/man
mime_header_checks = regexp:/etc/postfix/mime_header_checks
mydestination = $myhostname, localhost.$mydomain, localhost, $mydomain
mydomain = mydomain
myhostname = localhost
mynetworks = 10.1.1.0/24, 127.0.0.0/24, 10.1.10.0/24
mynetworks_style = subnet
myorigin = $mydomain
newaliases_path = /usr/bin/newaliases.postfix
notify_classes = resource, software, bounce
queue_directory = /var/spool/postfix
readme_directory = /usr/share/doc/postfix-2.11.0/README_FILES
relay_domains = $mydomain
relayhost =
sample_directory = /usr/share/doc/postfix-2.11.0/samples
sendmail_path = /usr/sbin/sendmail.postfix
setgid_group = postdrop
unknown_local_recipient_reject_code = 550
virtual_gid_maps = static:5000
virtual_mailbox_base = /home/vmail
virtual_mailbox_domains = /etc/postfix/vhosts
virtual_mailbox_maps = hash:/etc/postfix/vmaps
virtual_minimum_uid = 1000
virtual_uid_maps = static:5000'

Any help is very much appreciated!

Thanks,

Joey



--
View this message in context: 
http://postfix.1071664.n5.nabble.com/20-40-second-delays-Is-this-normal-tp65951.html
Sent from the Postfix Users mailing list archive at Nabble.com.

Reply via email to