> At 07:03 PM 9/12/2007, Justin Kim wrote: > >For 12 connection on amavis, I setup postfix master.cf to have the same > >process number. And postfix, amavis everything is localhost. > >For 20 max_server numbers on separate amavis1 server, I didn't > do anything > >on postfix master.cf side. Is that causing the problem? Should I > match that > >number too? > > Postfix won't make more than the configured number of connections, in > this case 12. It's OK for amavisd-new to have a greater number of > servers, but they won't be used. > > Beware the opposite situation, where postfix is making more > connections than amavisd-new has servers. Such as situation will > lead to all kinds of problems. I don't think this is your trouble. > > >And I don't know where I setup 1200 seconds. > > That's the -o smtp_data_done_timeout you specified in the postfix > master.cf amavisd-new entry. > > > > Does amavisd-nanny show more than 12 connections in use at a > > > time? If not, good. You may need to reduce the number of > > > connections allowed in the postfix master.cf amavisd entry to get the > > > processing time down to some reasonable number. > > > > > > I see you have amavisd-new configured for 20 servers. > > > Are you running out of memory? CPU stuck at 100%? > > > > > > You might try running "amavisd debug" or "amavisd debug-sa" to help > > > find the bottleneck. > > > > > > Good luck. > > > > > > -- > > > Noel Jones > > > > > >Can you help me to pin point the problem? > >Can this be a DNS related problem? Does amavis server need to have the > >correct DNS server setup? (the same one as the postfix server is using?) > > Yes, fast DNS is a requirement!! SpamAssassin can trigger dozens of > DNS lookups for a single message. To test this, in amavisd.conf set: > $sa_local_tests_only = 1; > to disable all network tests. If throughput goes way up, you've > probably found the problem. > > >On my postfix localhost where 12 max_server is configured amavisd-nanny > >shows: > >PID 02500: . 0:00:01 . > >PID 02607: 02607-06-4 0:00:03 === > >PID 02627: . 0:00:01 . > >PID 02856: 02856-04-8 0:00:01 = > >PID 03134: 0:00:03 ... > >PID 03244: 0:00:05 ..... > >PID 03327: 03327-04-2 0:00:04 ==== > >PID 03450: 03450-02 0:00:02 == > >PID 03463: 03463-02-2 0:00:01 = > >PID 03682: 0:00:03 ... > >PID 04076: . 0:00:01 . > >PID 04331: 0:00:03 ... > > OK, looks like messages are processed in just a few seconds. > > >PID 14849: 14849-01 0:00:49 =====SSSS:SSSSSSSSS:SSSSSSSSS:SSSS> > >PID 14850: 14850-01-10 0:00:03 =SSS > >PID 14851: 14851-01-5 0:00:00 S > >PID 14852: 0:00:47 .........:.........:.........:..... > >PID 14853: 14853-01-6 0:00:00 S > >PID 14854: 0:00:46 .........:.........:.........:..... > >PID 14855: 0:00:49 .........:.........:.........:..... > >PID 14856: 14856-01-8 0:00:03 SSS > >PID 14857: 14857-02-6 0:00:03 =SSS > >PID 14858: 0:00:49 .........:.........:.........:..... > >PID 14859: 0:00:49 .........:.........:.........:..... > >PID 14860: 14860-01-10 0:00:08 SSSSSSSSS > >PID 14861: 14861-01-6 0:00:10 SSSSSSSSS:S > >PID 14862: 14862-01-6 0:00:11 =SSSSSSSS:SS > >PID 14863: 14863-01-8 0:00:01 S > >PID 14864: 14864-01-7 0:00:07 SSSSSSS > >PID 14865: 0:00:49 .........:.........:.........:..... > >PID 14866: 14866-01-5 0:00:00 F > >PID 14867: 0:00:48 .........:.........:.........:..... > >PID 14868: 0:00:48 .........:.........:.........:..... > > Looks like messages are taking longer to be processed. Notice that > only 12 PID's are active. > > -- > Noel Jones > Hi Noel, Thank you for your kind explanation. As you suggested I changed log level = 2 and looked at the timing. And this looks little longer than what it should be I think. I don't know what is the ideal timing for amavis. Could this be because my hardware especially CPU? When I do top and monitor system, I see some available RAM but I see CPU is peaking. I got Xeon 3.0 Ghz and 4 GB of RAM. (2 core) And can you tell me what are the last few lines of maillog. (Tempdir is to be preserved!) Can you suggest me the best possible scenario to get this one working? Thank you, Justin
maillog: ---------------------------------------------------------------------------- ------------------------------------------ Sep 12 22:33:55 amavis1 amavis[15775]: (15775-01-6) TIMING [total 15190 ms] - lookup_sql: 4 (0%)0, SMTP pre-DATA-flush: 2 (0%)0, SMTP DATA: 35 (0%)0, check_init: 1 (0%)0, digest_hdr: 1 (0%)0, digest_body: 0 (0%)0, gen_mail_id: 1 (0%)0, check_header: 3 (0%)0, spam-wb-list: 4 (0%)0, SA parse: 3 (0%)0, SA check: 15086 (99%)100, update_cache: 10 (0%)100, decide_mail_destiny: 3 (0%)100, fwd-connect: 14 (0%)100, fwd-xforward: 1 (0%)100, fwd-mail-pip: 3 (0%)100, fwd-rcpt-pip: 0 (0%)100, fwd-data-chkpnt: 0 (0%)100, write-header: 2 (0%)100, fwd-data-contents: 0 (0%)100, fwd-end-chkpnt: 4 (0%)100, prepare-dsn: 1 (0%)100, main_log_entry: 11 (0%)100, update_snmp: 2 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 0 (0%)100, unlink-0-files: 0 (0%)100, rundown: 0 (0%)100 Sep 12 22:33:58 amavis1 amavis[15765]: (15765-01-10) TIMING [total 6550 ms] - lookup_sql: 60 (1%)1, SMTP pre-DATA-flush: 2 (0%)1, SMTP DATA: 1 (0%)1, check_init: 1 (0%)1, digest_hdr: 1 (0%)1, digest_body: 0 (0%)1, gen_mail_id: 1 (0%)1, check_header: 3 (0%)1, spam-wb-list: 4 (0%)1, SA parse: 4 (0%)1, SA check: 6366 (97%)98, update_cache: 28 (0%)99, decide_mail_destiny: 3 (0%)99, fwd-connect: 32 (0%)99, fwd-xforward: 20 (0%)100, fwd-mail-pip: 4 (0%)100, fwd-rcpt-pip: 0 (0%)100, fwd-data-chkpnt: 0 (0%)100, write-header: 2 (0%)100, fwd-data-contents: 0 (0%)100, fwd-end-chkpnt: 4 (0%)100, prepare-dsn: 1 (0%)100, main_log_entry: 11 (0%)100, update_snmp: 2 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 0 (0%)100, unlink-0-files: 0 (0%)100, rundown: 0 (0%)100 Sep 12 22:34:00 amavis1 amavis[15774]: (15774-01-6) TIMING [total 14662 ms] - lookup_sql: 4 (0%)0, SMTP pre-DATA-flush: 2 (0%)0, SMTP DATA: 35 (0%)0, check_init: 1 (0%)0, digest_hdr: 1 (0%)0, digest_body: 0 (0%)0, gen_mail_id: 1 (0%)0, check_header: 3 (0%)0, spam-wb-list: 4 (0%)0, SA parse: 4 (0%)0, SA check: 14556 (99%)100, update_cache: 11 (0%)100, decide_mail_destiny: 3 (0%)100, fwd-connect: 13 (0%)100, fwd-xforward: 1 (0%)100, fwd-mail-pip: 4 (0%)100, fwd-rcpt-pip: 0 (0%)100, fwd-data-chkpnt: 0 (0%)100, write-header: 2 (0%)100, fwd-data-contents: 0 (0%)100, fwd-end-chkpnt: 4 (0%)100, prepare-dsn: 1 (0%)100, main_log_entry: 10 (0%)100, update_snmp: 2 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 0 (0%)100, unlink-0-files: 0 (0%)100, rundown: 0 (0%)100 Sep 12 22:34:00 amavis1 amavis[15774]: (15774-01-7) lookup_sql_field(id) (WARN: no such field in the SQL table), "[EMAIL PROTECTED]" result=undef Sep 12 22:34:01 amavis1 amavis[15758]: Net::Server: 2007/09/12-22:34:01 Server closing! Sep 12 22:34:01 amavis1 amavis[15765]: (15765-01-11) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223232-15765 Sep 12 22:34:01 amavis1 amavis[15763]: (15763-01-7) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223231-15763 Sep 12 22:34:01 amavis1 amavis[15764]: (15764-01-8) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223231-15764 Sep 12 22:34:01 amavis1 amavis[15762]: (15762-01) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223231-15762 Sep 12 22:34:02 amavis1 amavis[15774]: (15774-01-7) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223241-15774 Sep 12 22:34:02 amavis1 amavis[15772]: (15772-01-8) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223242-15772 Sep 12 22:34:02 amavis1 amavis[15773]: (15773-01-6) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223249-15773 Sep 12 22:34:02 amavis1 amavis[15775]: (15775-01-7) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223242-15775 Sep 12 22:34:02 amavis1 amavis[15767]: (15767-01-8) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223234-15767 Sep 12 22:34:02 amavis1 amavis[15769]: (15769-01-10) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223233-15769 Sep 12 22:34:02 amavis1 amavis[15766]: (15766-01-9) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223233-15766 Sep 12 22:34:02 amavis1 amavis[15768]: (15768-01-10) (!)TempDir removal: tempdir is to be PRESERVED: /var/amavis/tmp/amavis-20070912T223232-15768 ------------------------------------------------------------------------- This SF.net email is sponsored by: Microsoft Defy all challenges. Microsoft(R) Visual Studio 2005. http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/ _______________________________________________ AMaViS-user mailing list AMaViS-user@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/amavis-user AMaViS-FAQ:http://www.amavis.org/amavis-faq.php3 AMaViS-HowTos:http://www.amavis.org/howto/
