Gary/Henrik, thanks for the responses. I set $log_level = 2 and it is
showing about 18 seconds to scan a message (see output below). It was
taking on average about 600ms before.


 Mar 15 09:26:48 genetics amavis[24863]: (24863-04-9) TIMING [total
18468 ms] - SMTP pre-DATA-flush: 1 (0%)0, SMTP DATA: 79 (0%)0,
check_init: 0 (0%)0, digest_hdr: 0 (0%)0, digest_body: 0 (0%)0,
gen_mail_id: 0 (0%)0, mime_decode: 13 (0%)1, get-file-type3: 10 (0%)1,
decompose_part: 1 (0%)1, parts_decode: 0 (0%)1, check_header: 1 (0%)1,
AV-scan-1: 16 (0%)1, spam-wb-list: 1 (0%)1, SA msg read: 2 (0%)1, SA
parse: 4 (0%)1, SA check: 18223 (99%)99, update_cache: 7 (0%)99,
decide_mail_destiny: 1 (0%)99, write-header: 4 (0%)99,
save-to-local-mailbox: 3 (0%)99, prepare-dsn: 11 (0%)100, fwd-connect:
1 (0%)100, fwd-mail-from: 1 (0%)100, fwd-rcpt-to: 1 (0%)100,
fwd-data-cmd: 0 (0%)100, write-header: 0 (0%)100, fwd-data-contents: 4
(0%)100, fwd-data-end: 77 (0%)100, fwd-rundown: 1 (0%)100,
main_log_entry: 4 (0%)100, update_snmp: 1 (0%)100, SMTP pre-response:
0 (0%)100, SMTP response: 0 (0%)100, unlink-3-files: 0 (0%)100,
rundown: 0 (0%)100

Here is an exerpt of the maillog before these problems started:

tail -n 1000 /var/log/maillog.2 | grep 7EB68D240A2 | grep amavis
Mar  4 04:02:27 genetics amavis[19076]: (19076-10) Passed CLEAN, [
80.128.19.203] [80.128.19.203] <[EMAIL PROTECTED]> -> <
[EMAIL PROTECTED]>, Message-ID:
<[EMAIL PROTECTED]>, mail_id: T8wgwXAM2JD7,
Hits: 4.02, queued_s: 7EB68D240A2,  913 ms

Also, here is the output from top. The machine is a Dual 2.3Ghz XEON
dual-core with 4GB of RAM.

top - 09:52:35 up 1 day, 22:58,  2 users,  load average:  0.36, 0.38, 0.26
Tasks: 229 total,   1 running, 228 sleeping,   0 stopped,   0 zombie
 Cpu(s):  5.2% us,  0.6% sy,  0.0% ni, 91.4% id,  2.8% wa,  0.0% hi,  0.0% si
Mem:   4149144k total,  3909596k used,   239548k free,    62768k buffers
Swap:  2031608k total,      264k used,  2031344k free,  3394436k cached

  PID USER      PR  NI  RES  VIRT  SHR S %CPU %MEM    TIME+  COMMAND
26590 amavis    15   0  48m 62256 3072 S   11  1.2   0:03.78 amavisd
 26755 amavis    16   0  46m 59980 3048 S    0  1.1   0:01.61 amavisd
 24860 amavis    16   0  42m 55968 2520 S    0  1.1   0:00.40 amavisd
23533 hskim     16   0  29m 35280 2492 S    0   0.7   0:26.15 spamd
26553 rtidwell  16   0  26m 32152 2452 S    9  0.7    0:02.73 spamd
 2336 amavis    16   0  26m 53860 1128 S    0  0.7   0: 59.05 clamd
 2273 root      15   0  24m 30332 2448 S    0  0.6   0:02.56  spamd

Thanks,
Kyle

On 3/15/07, Gary V <[EMAIL PROTECTED]> wrote:
> Henrik wrote:
>
> > On Wed, Mar 14, 2007 at 05:12:45PM -0500, Kyle Kniepkamp wrote:
> >> I am running into the problem, after an upgrade, that delivery of my
> >> messages is slow. Hence, my queue is getting backed up and messages are
> >> taking about an hour to get delivered.
> >>
> >> tail -f /var/log/maillog | grep amavis
> >> Mar 14 17:09:24 genetics amavis[2615]: (02615-01-6) Blocked SPAM, [
> >> 70.22.199.128] [192.89.52.117] <[EMAIL PROTECTED]> -> <
> >> [EMAIL PROTECTED]>, quarantine: spam-gT4FrZEUbl5B.gz, Message-ID: <
> >> [EMAIL PROTECTED]>, mail_id: gT4FrZEUbl5B, Hits:
> >> 13.41, 17456
> >> ms
>
> > Set $log_level = 2. What does the Timing log line say? How long did it take
> > before?
>
> > Cheers,
> > Henrik
>
> Also, what CPU, how much RAM and how many messages per day?
> It would be good if you could show partial output of 'top'
> command - sorted by descending memory usage - with your
> amavisd processes showing, e.g.:
>
> top - 07:48:20 up 50 days, 14:53,  1 user,  load average: 0.00, 0.02, 0.00
> Tasks:  82 total,   2 running,  80 sleeping,   0 stopped,   0 zombie
> Cpu(s):  0.0% us,  0.0% sy,  0.0% ni, 100.0% id,  0.0% wa,  0.0% hi,  0.0% si
> Mem:    774912k total,   741640k used,    33272k free,    59512k buffers
> Swap:  1510068k total,        0k used,  1510068k free,   357260k cached
>
>   PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM    TIME+  COMMAND
>  2817 amavis    16   0 78656  71m  12m S  0.0  9.4   0:01.19 amavisd-new
>  2742 amavis    16   0 77056  69m  12m S  0.0  9.2   0:00.99 amavisd-new
>  2922 amavis    16   0 76056  68m  12m S  0.0  9.1   0:01.00 amavisd-new
> 26945 amavis    16   0 74172  67m  11m S  0.0  8.9   0:02.17 amavisd-new
>  2479 mysql     16   0  133m  41m 9000 S  0.0  5.5   0:02.47 mysqld
>
> Gary V
>
>
>
> -------------------------------------------------------------------------
> Take Surveys. Earn Cash. Influence the Future of IT
> Join SourceForge.net's Techsay panel and you'll get the chance to share your
> opinions on IT & business topics through brief surveys-and earn cash
> http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
> _______________________________________________
> AMaViS-user mailing list
> [email protected]
> https://lists.sourceforge.net/lists/listinfo/amavis-user
> AMaViS-FAQ:http://www.amavis.org/amavis-faq.php3
> AMaViS-HowTos:http://www.amavis.org/howto/
>

-------------------------------------------------------------------------
Take Surveys. Earn Cash. Influence the Future of IT
Join SourceForge.net's Techsay panel and you'll get the chance to share your
opinions on IT & business topics through brief surveys-and earn cash
http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
_______________________________________________
AMaViS-user mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/amavis-user
AMaViS-FAQ:http://www.amavis.org/amavis-faq.php3
AMaViS-HowTos:http://www.amavis.org/howto/

Reply via email to