From: Patrick B. K. <p...@st...> - 2011-03-02 08:26:08
|
I'm trying to locate a bottleneck in a mailsystem. The TIMING in amavis logging shows that amavis spends most time with "SMTP DATA": Mar 2 08:37:20 mail amavis[12218]: (12218-03) TIMING [total 4034 ms] - SMTP greeting: 0 (0%)0, SMTP EHLO: 0 (0%)0, SMTP pre-MAIL: 0 (0%)0, SMTP pre-DATA-flush: 24 (1%)1, SMTP DATA: 3882 (96%)97, check_init: 0 (0%)97, digest_hdr: 0 (0%)97, digest_body: 0 (0%)97, custom-new: 0 (0%)97, mime_decode: 16 (0%)97, get-file-type2: 16 (0%)98, parts_decode: 0 (0%)98, AV-scan-1: 16 (0%)98, custom-checks: 0 (0%)98, decide_mail_destiny: 0 (0%)98, notif-quar: 0 (0%)98, gen_mail_id: 4 (0%)98, quar-hdrs: 0 (0%)98, fwd-connect: 0 (0%)98, fwd-mail-pip: 4 (0%)98, fwd-rcpt-pip: 0 (0%)98, fwd-data-chkpnt: 0 (0%)98, write-header: 0 (0%)98, fwd-data-contents: 4 (0%)98, fwd-end-chkpnt: 44 (1%)99, custom-before_send: 0 (0%)99, fwd-connect: 0 (0%)99, fwd-xforward: 0 (0%)99, fwd-mail-pip: 4 (0%)100, fwd-rcpt-pip: 0 (0%)100, fwd-data-chkpnt: 0 (0%)100, write-header: 4 (0%)100, fwd-data-contents: 0 (0%)100, fwd-end-chkpnt: 8 (0%)100, custom-after_send: 0 (0%)100, prepare-dsn: 0 (0%)100, main_log_entry: 4 (0%)100, sql-update: 4 (0%)100, update_snmp: 0 (0%)100, custom-mail_done: 0 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100, unlink-2-files: 0 (0%)100, rundown: 0 (0%)100 Mar 2 08:37:41 mail amavis[12416]: (12416-05) TIMING [total 7679 ms] - SMTP greeting: 4 (0%)0, SMTP EHLO: 0 (0%)0, SMTP pre-MAIL: 0 (0%)0, SMTP pre-DATA-flush: 0 (0%)0, SMTP DATA: 7275 (95%)95, check_init: 0 (0%)95, digest_hdr: 4 (0%)95, digest_body: 0 (0%)95, custom-new: 0 (0%)95, mime_decode: 28 (0%)95, get-file-type3: 8 (0%)95, parts_decode: 0 (0%)95, AV-scan-1: 28 (0%)96, custom-checks: 0 (0%)96, decide_mail_destiny: 0 (0%)96, notif-quar: 0 (0%)96, gen_mail_id: 4 (0%)96, quar-hdrs: 0 (0%)96, fwd-connect: 0 (0%)96, fwd-mail-pip: 8 (0%)96, fwd-rcpt-pip: 0 (0%)96, fwd-data-chkpnt: 0 (0%)96, write-header: 0 (0%)96, fwd-data-contents: 8 (0%)96, fwd-end-chkpnt: 244 (3%)99, custom-before_send: 0 (0%)99, fwd-connect: 0 (0%)99, fwd-xforward: 0 (0%)99, fwd-mail-pip: 4 (0%)99, fwd-rcpt-pip: 0 (0%)99, fwd-data-chkpnt: 0 (0%)99, write-header: 0 (0%)99, fwd-data-contents: 4 (0%)99, fwd-end-chkpnt: 48 (1%)100, custom-after_send: 0 (0%)100, prepare-dsn: 0 (0%)100, main_log_entry: 8 (0%)100, sql-update: 0 (0%)100, update_snmp: 4 (0%)100, custom-mail_done: 0 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100, unlink-3-files: 0 (0%)100, rundown: 0 (0%)100 Mar 2 08:42:35 mail amavis[14617]: (14617-05) TIMING [total 3702 ms] - SMTP greeting: 0 (0%)0, SMTP EHLO: 0 (0%)0, SMTP pre-MAIL: 0 (0%)0, SMTP pre-DATA-flush: 4 (0%)0, SMTP DATA: 1709 (46%)46, check_init: 0 (0%)46, digest_hdr: 0 (0%)46, digest_body: 80 (2%)48, custom-new: 0 (0%)48, mime_decode: 652 (18%)66, get-file-type3: 8 (0%)66, parts_decode: 0 (0%)66, AV-scan-1: 32 (1%)67, custom-checks: 0 (0%)67, decide_mail_destiny: 0 (0%)67, notif-quar: 4 (0%)67, gen_mail_id: 8 (0%)67, quar-hdrs: 0 (0%)67, fwd-connect: 4 (0%)68, fwd-mail-pip: 0 (0%)68, fwd-rcpt-pip: 0 (0%)68, fwd-data-chkpnt: 0 (0%)68, write-header: 4 (0%)68, fwd-data-contents: 288 (8%)75, fwd-end-chkpnt: 448 (12%)88, custom-before_send: 0 (0%)88, fwd-connect: 0 (0%)88, fwd-xforward: 0 (0%)88, fwd-mail-pip: 4 (0%)88, fwd-rcpt-pip: 0 (0%)88, fwd-data-chkpnt: 0 (0%)88, write-header: 0 (0%)88, fwd-data-contents: 344 (9%)97, fwd-end-chkpnt: 96 (3%)100, custom-after_send: 0 (0%)100, prepare-dsn: 0 (0%)100, main_log_entry: 8 (0%)100, sql-update: 4 (0%)100, update_snmp: 0 (0%)100, custom-mail_done: 0 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100, unlink-3-files: 4 (0%)100, rundown: 0 (0%)100 What does SMTP DATA refer to? The system uses amavis archive method to copy messages. Could it be related to that? p@rick -- state of mind Digitale Kommunikation http://www.state-of-mind.de Franziskanerstraße 15 Telefon +49 89 3090 4664 81669 München Telefax +49 89 3090 4666 Amtsgericht München Partnerschaftsregister PR 563 |