Hi everyone, we use Mac OS X Server 10.6, it's been a while, every sent email goes to queue and waits there. When i restart the server all emails send.
How can i prevent this situation? And what may the problem be?
P.S: I try "postfix flush" and email stil wait in queue.
Mac OS X (10.6.8), open directory master, replica
Do you have the content filter enabled?
Sounds like its failing.
What is output of (tells us if its ON)
sudo postconf content_filter
When SMTP receives mail, you should see it in the log. Then its passed to amavis, scored, then sent back to postfix. You can follow this process in the mail.log (that why, if the content filter is enabled) you see mail sent more than once (once locally, then once to destination)
A few things to check when its in the failed state:
- send mail, get entries for that time frame from mail.log and amavisd.log
- what is results of
sudo lsof -i :10024
- what is result of
sudo cat /etc/postfix/master.cf
Does the problem go away when you
sudo launchctl unload /System/Library/LaunchDaemons/org.amavisd.plist
sudo launchctl load /System/Library/LaunchDaemons/org.amavisd.plist
postsuper -r ALL
(wait a few minutes)
Sometimes amavis will get stuck and not process new threads.
Try this
sudo launchctl unload /System/Library/LaunchDaemons/org.amavisd.plist
Wait a minute, then check to see if it's still running
sudo lsof -i :10025
If you get a result, then amavis isn't shutting down.
You can force-quite it, then restart via launchctl again, but thats just a temp fix.
Firstly, thank you for your reply. We still have the same problem, according to your reply
By typing "sudo postconf content_filter" i see result like this:
content_filter = smtp-amavis:[127.0.0.1]:10024
*****************
By typing "sudo lsof -i:10024 i get following text:
COMMAND PID USER FD TYPE DEVICE SIZE/OFF NODE NAME
perl5.10. 60 _amavisd 6u IPv4 0xffffff8036ef3da8 0t0 TCP localhost:10024 (LISTEN)
perl5.10. 353 _amavisd 6u IPv4 0xffffff8036ef3da8 0t0 TCP localhost:10024 (LISTEN)
perl5.10. 354 _amavisd 6u IPv4 0xffffff8036ef3da8 0t0 TCP localhost:10024 (LISTEN)
*****************
And by typing "sudo cat /etc/postfix/master.cf" i see following text;
#
# Postfix master process configuration file. For details on the format
# of the file, see the master(5) manual page (command: "man 5 master").
#
# ==========================================================================
# service type private unpriv chroot wakeup maxproc command + args
# (yes) (yes) (yes) (never) (100)
# ==========================================================================
# ==== Begin auto-generated section ========================================
# This section of the master.cf file is auto-generated by the Server Admin
# Mail backend plugin whenever mails settings are modified.
smtp inet n - n - - smtpd
submission inet n - n - - smtpd
-o smtpd_tls_security_level=encrypt
smtp unix - - n - - smtp
# === End auto-generated section ===========================================
# Modern SMTP clients communicate securely over port 25 using the STARTTLS command.
# Some older clients, such as Outlook 2000 and its predecessors, do not properly
# support this command and instead assume a preconfigured secure connection
# on port 465. This was sometimes called "smtps", but such usage was never
# approved by the IANA and therefore conflicts with another, legitimate assignment.
# For more details about managing secure SMTP connections with postfix, please see:
# http://www.postfix.org/TLS_README.html
# To read more about configuring secure connections with Outlook 2000, please read:
# http://support.microsoft.com/default.aspx?scid=kb;en-us;Q307772
# Apple does not support the use of port 465 for this purpose.
# After determining that connecting clients do require this behavior, you may choose
# to manually enable support for these older clients by uncommenting the following
# four lines.
#465 inet n - n - - smtpd
# -o smtpd_tls_wrappermode=yes
# -o smtpd_sasl_auth_enable=yes
# -o smtpd_client_restrictions=permit_sasl_authenticated,reject
#628 inet n - n - - qmqpd
pickup fifo n - n 60 1 pickup
-o content_filter=
cleanup unix n - n - 0 cleanup
qmgr fifo n - n 300 1 qmgr
#qmgr fifo n - n 300 1 oqmgr
tlsmgr unix - - n 1000? 1 tlsmgr
rewrite unix - - n - - trivial-rewrite
bounce unix - - n - 0 bounce
defer unix - - n - 0 bounce
trace unix - - n - 0 bounce
verify unix - - n - 1 verify
flush unix n - n 1000? 0 flush
proxymap unix - - n - - proxymap
# When relaying mail as backup MX, disable fallback_relay to avoid MX loops
relay unix - - n - - smtp
-o fallback_relay=
# -o smtp_helo_timeout=5 -o smtp_connect_timeout=5
showq unix n - n - - showq
error unix - - n - - error
retry unix - - n - - error
discard unix - - n - - discard
local unix - n n - - local
virtual unix - n n - - virtual
lmtp unix - - n - - lmtp
anvil unix - - n - 1 anvil
scache unix - - n - 1 scache
#
# ====================================================================
# Interfaces to non-Postfix software. Be sure to examine the manual
# pages of the non-Postfix software to find out what options it wants.
#
# Many of the following services use the Postfix pipe(8) delivery
# agent. See the pipe(8) man page for information about ${recipient}
# and other message envelope options.
# ====================================================================
#
# maildrop. See the Postfix MAILDROP_README file for details.
# Also specify in main.cf: maildrop_destination_recipient_limit=1
#
#maildrop unix - n n - - pipe
# flags=DRhu user=vmail argv=/usr/local/bin/maildrop -d ${recipient}
#
# ====================================================================
#
# The Cyrus deliver program has changed incompatibly, multiple times.
#
#old-cyrus unix - n n - - pipe
# flags=R user=cyrus argv=/cyrus/bin/deliver -e -m ${extension} ${user}
#
# ====================================================================
#
# Cyrus 2.1.5 (Amos Gouaux)
# Also specify in main.cf: cyrus_destination_recipient_limit=1
#
#cyrus unix - n n - - pipe
# user=_cyrus argv=/usr/bin/cyrus/bin/deliver -e -r ${sender} -m ${extension} ${user}
#
# Dovecot version 1.1.3
#
dovecot unix - n n - 25 pipe
flags=DRhu user=_dovecot:mail argv=/usr/libexec/dovecot/deliver -d ${user}
#
# Greylist policy server
#
policy unix - n n - - spawn
user=nobody:mail argv=/usr/bin/perl /usr/libexec/postfix/greylist.pl
#
# Use these settings to enable +addressing (user+mailbox@example.com)
# flags=DRhu user=_dovecot:mail argv=/usr/libexec/dovecot/deliver -d ${user} -a ${recipient} -m ${extension}
#
# ====================================================================
#
# See the Postfix UUCP_README file for configuration details.
#
#uucp unix - n n - - pipe
# flags=Fqhu user=uucp argv=uux -r -n -z -a$sender - $nexthop!rmail ($recipient)
#
# ====================================================================
#
# Other external delivery methods.
#
#ifmail unix - n n - - pipe
# flags=F user=ftn argv=/usr/lib/ifmail/ifmail -r $nexthop ($recipient)
#
#bsmtp unix - n n - - pipe
# flags=Fq. user=bsmtp argv=/usr/local/sbin/bsmtp -f $sender $nexthop $recipient
#
#scalemail-backend unix - n n - 2 pipe
# flags=R user=scalemail argv=/usr/lib/scalemail/bin/scalemail-store
# ${nexthop} ${user} ${extension}
#
#mailman unix - n n - - pipe
# flags=FR user=list argv=/usr/lib/mailman/bin/postfix-to-mailman.py
# ${nexthop} ${user}
smtp-amavis unix - - y - 2 smtp
-o smtp_data_done_timeout=1200
-o smtp_send_xforward_command=yes
-o disable_dns_lookups=yes
127.0.0.1:10025 inet n - y - - smtpd
-o content_filter=
-o local_recipient_maps=
-o relay_recipient_maps=
-o smtpd_restriction_classes=
-o smtpd_client_restrictions=
-o smtpd_helo_restrictions=
-o smtpd_sender_restrictions=
-o smtpd_recipient_restrictions=permit_mynetworks,reject
-o mynetworks=127.0.0.0/8
-o smtpd_enforce_tls=no
-o strict_rfc821_envelopes=yes
-o smtpd_error_sleep_time=0
-o smtpd_soft_error_limit=1001
-o smtpd_hard_error_limit=1000
-o receive_override_options=no_header_body_checks
*****************
But when i type "sudo launchctl unload /System/Library/LaunchDaemons/org.amavisd.plist" i get following error:
sudo launchctl unload /System/Library/LaunchDaemons/org.amavisd.plist
launchctl: Couldn't stat("/System/Library/LaunchDaemons/org.amavisd.plist"): No such file or directory
nothing found to unload
*****************
And here is the logs of Junk Mail / Virus Scanning (amavisd);
mail.DOMAIN.COM ESMTP Postfix
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp cmd> EHLO localhost
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: needline=0, flush=1, wr=1, timeout=300
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: sending
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop sent 16> EHLO localhost\r\n
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: needline=1, flush=0, wr=0, timeout=300
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: receiving
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop read 178 chars< 250-mail.DOMAIN.COM\r\n250-PIPELINING\r\n250-SIZE 20971520\r\n250-VRFY\r\n250-ETRN\r\n250-AUTH LOGIN PLAIN CRAM-MD5 GSSAPI\r\n250-STARTTLS\r\n250-ENHANCEDSTATUSCODES\r\n250-8BITMIME\r\n250 DSN\r\n
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp resp to EHLO: 250 mail.DOMAIN.COM\nPIPELINING\nSIZE 20971520\nVRFY\nETRN\nAUTH LOGIN PLAIN CRAM-MD5 GSSAPI\nSTARTTLS\nENHANCEDSTATUSCODES\n8BITMIME\nDSN
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) Remote host presents itself as: mail.DOMAIN.COM, handles DSN, handles PIPELINING
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) AUTH not needed, user='', MTA offers 'LOGIN PLAIN CRAM-MD5 GSSAPI'
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp cmd> MAIL FROM:<SENDER_MAIL@DOMAIN.COM> BODY=7BIT
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp cmd> RCPT TO:<TO_MAIL@DOMAIN.COM> ORCPT=rfc822;TO_MAIL@DOMAIN.COM
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp cmd> DATA
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: needline=0, flush=1, wr=1, timeout=120
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: sending
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop sent 131> MAIL FROM:<SENDER_MAIL@DOMAIN.COM> BODY=7BIT\r\nRCPT TO:<TO_MAIL@DOMAIN.COM> ORCPT=rfc822;TO_MAIL@DOMAIN.COM\r\nDATA\r\n
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: needline=1, flush=0, wr=0, timeout=300
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: receiving
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop read 65 chars< 250 2.1.0 Ok\r\n250 2.1.5 Ok\r\n354 End data with <CR><LF>.<CR><LF>\r\n
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp resp to MAIL (pip): 250 2.1.0 Ok
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp resp to RCPT (pip) (<TO_MAIL@DOMAIN.COM>): 250 2.1.5 Ok
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp resp to DATA: 354 End data with <CR><LF>.<CR><LF>
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) write_header: 0, Amavis::Out::SMTP::Protocol=HASH(0x104e9aa40)
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp cmd> QUIT
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: needline=0, flush=1, wr=1, timeout=480
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: sending
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop sent 3649> X-Virus-Scanned: amavisd-new at DOMAIN.COM\r\nReceived: from mail.DOMAIN.COM ([127.0.0.1])\r\n\tby localhost (mail.DOMAIN.COM [127.0.0.1]) (amavisd-new, port 10024)\r\n\twith ESMTP id rZira16j3myF for < [...]
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: needline=1, flush=0, wr=0, timeout=480
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop: receiving
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rw_loop read 51 chars< 250 2.0.0 Ok: queued as BF59A7EBE6\r\n221 2.0.0 Bye\r\n
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) smtp resp to data-dot (<TO_MAIL@DOMAIN.COM>): 250 2.0.0 Ok: queued as BF59A7EBE6
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) Amavis::Out::SMTP::Session close, disconnecting
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) prolong_timer fwd-end-chkpnt: timer set to 480 s
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) FWD via SMTP: <SENDER_MAIL@DOMAIN.COM> -> <TO_MAIL@DOMAIN.COM>,BODY=7BIT 250 2.0.0 Ok, id=12140-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as BF59A7EBE6
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) prolong_timer forwarding: remaining time = 480 s
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) DSN: sender is credible (orig), SA: -1.042, <SENDER_MAIL@DOMAIN.COM>
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) lookup => undef, "SENDER_MAIL@DOMAIN.COM" does not match
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) dsn: from MTA 250 NonBlocking:Clean <SENDER_MAIL@DOMAIN.COM> -> <TO_MAIL@DOMAIN.COM>: on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=1, mta_resp: "250 2.0.0 Ok, id=12140-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as BF59A7EBE6"
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) DSN: SUCC from MTA 250 NonBlocking:Clean, no DSN requested: <SENDER_MAIL@DOMAIN.COM> -> <TO_MAIL@DOMAIN.COM>
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) one_response_for_all <SENDER_MAIL@DOMAIN.COM>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.0.0 Ok, id=12140-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as BF59A7EBE6'
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) notif=N, suppressed=0, ndn_needed=0, exit=0, 250 2.0.0 Ok, id=12140-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as BF59A7EBE6
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) prolong_timer delivery-notification: remaining time = 480 s
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) fish_out_ip_from_received: 10.14.32.64
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) lookup_ip_acl (publicnetworks): key="10.14.32.64" matches "!10.0.0.0/8", result=0
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) parse_ip_address_from_received: 10.14.32.64
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) Passed CLEAN, MYNETS LOCAL [10.14.32.64] [10.14.32.64] <SENDER_MAIL@DOMAIN.COM> -> <TO_MAIL@DOMAIN.COM>, Message-ID: <FA393FE2-787E-4F10-8C17-B3B6B973949A@DOMAIN.COM>, mail_id: rZira16j3myF, Hits: -1.042, size: 3371, queued_as: BF59A7EBE6, 136 ms
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) prolong_timer main_log_entry: remaining time = 480 s
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) updating snmp variables
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) prolong_timer check done: remaining time = 480 snmp
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) sending SMTP response: "250 2.0.0 Ok, id=12140-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as BF59A7EBE6"
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) ESMTP> 250 2.0.0 Ok, id=12140-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as BF59A7EBE6
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) switch_to_client_time 480 s, smtp response sent
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) TempDir::strip: /var/amavis/tmp/amavis-20130214T115306-12140
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) rmdir_recursively: /var/amavis/tmp/amavis-20130214T115306-12140/parts, excl=1
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) TIMING [total 140 ms] - SMTP greeting: 2 (1%)1, SMTP EHLO: 1 (1%)2, SMTP pre-MAIL: 2 (1%)3, SMTP pre-DATA-flush: 3 (2%)5, SMTP DATA: 1 (0%)6, check_init: 0 (0%)6, digest_hdr: 0 (0%)6, digest_body: 0 (0%)6, gen_mail_id: 1 (1%)7, mime_decode: 11 (8%)15, get-file-type2: 16 (11%)26, decompose_part: 1 (1%)27, parts_decode: 0 (0%)27, check_header: 1 (1%)28, AV-scan-1: 6 (4%)32, spam-wb-list: 2 (2%)34, SA parse: 3 (2%)36, SA check: 61 (44%)80, update_cache: 5 (4%)83, decide_mail_destiny: 1 (1%)84, fwd-connect: 6 (5%)89, fwd-mail-pip: 2 (1%)90, fwd-rcpt-pip: 0 (0%)90, fwd-data-chkpnt: 0 (0%)90, write-header: 1 (0%)91, fwd-data-contents: 0 (0%)91, fwd-end-chkpnt: 3 (2%)93, prepare-dsn: 1 (1%)93, main_log_entry: 6 (4%)98, update_snmp: 2 (1%)99, SMTP pre-response: 0 (0%)99, SMTP response: 0 (0%)99, unlink-2-files: 0 (0%)100, rundown: 0 (0%)100
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) idle_proc, 6: was busy, 134.0 ms, total idle 75.328 s, busy 9.937 s
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) idle_proc, 5: was idle, 0.1 ms, total idle 75.328 s, busy 9.937 s
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) ESMTP< QUIT\r\n
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) switch_to_my_time 480 s, SMTP QUIT received
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-new closing transmission channel
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) switch_to_client_time 480 s, smtp response sent
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) SMTP session over, timer stopped
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) exiting process_request
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) post_process_request_hook: timer was not running
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) idle_proc, bye: was busy, 1.8 ms, total idle 75.328 s, busy 9.939 s
Feb 14 11:54:31 mail.DOMAIN.COM /usr/bin/amavisd[12140]: (12140-04) load: 12 %, total idle 75.328 s, busy 9.939 s
I solved this problem.
due to none of the accounts on ldap. I removed the directories from mail db.....
Mail queue problem
