Hi, I have just got a problem about a message with multi recipients.
the message has 18 recipients, and amavisd-new forwards it to my dspam using pipeline. But the first recipient got a negative SMTP response to data-dot, and which caused the message be delivered again and again. But when I test it manually, pipeline was not used any more:( But just connection reuse. So I cannot reappear the problem. Any help? Thanks very much! Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp session: setting up a new session Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp creating socket by IO::Socket::INET to [127.0.0.1]:2502 Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to greeting: 220 DSPAM LMTP GIT Ready Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> LHLO localhost Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to LHLO: 250 localhost.localdomain\nPIPELINING\nENHANCEDSTATUSCODES\n8BITMIME\nSIZE Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) AUTH not needed, user='', MTA offers '' Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> MAIL FROM:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> RCPT TO:<[email protected]> Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> DATA Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to MAIL (pip): 250 2.1.0 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp resp to DATA: 354 Enter mail, end with "." on a line by itself Aug 31 14:57:07 pe6850b amavis[2587]: (02587-14) smtp cmd> QUIT Aug 31 15:04:53 pe6850b amavis[2587]: (02587-14) (!)rw_loop: leaving rw loop, no progress, last event (select) 465.938 s ago Aug 31 15:04:53 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): Aug 31 15:04:53 pe6850b amavis[2587]: (02587-14) Negative SMTP response to data-dot (<[email protected]>): Aug 31 15:12:39 pe6850b amavis[2587]: (02587-14) (!)rw_loop: leaving rw loop, no progress, last event (select) 465.937 s ago Aug 31 15:12:39 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): Aug 31 15:12:39 pe6850b amavis[2587]: (02587-14) Negative SMTP response to data-dot (<[email protected]>): Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) Amavis::Out::SMTP::Session close, disconnecting Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) (!)FWD via LMTP: <[email protected]> -> <[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<liub [email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<zbc...@h ongta.com>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>, 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) DSN: sender is credible (orig), SA: -1994.041, <[email protected]> Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) lookup => true, "[email protected]" matches, result="18", matching_key="(constant:18)" Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) DSN: TMPFAIL from MTA 451 MtaTempFailed, need not be reported: <[email protected]> -> <[email protected]> Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) DSN: TMPFAIL from MTA 451 MtaTempFailed, need not be reported: <[email protected]> -> <[email protected]> Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) notif=N, suppressed=0, ndn_needed=, exit=75, 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) lookup_ip_acl (publicnetworks): key="10.96.10.59" matches "!10.0.0.0/8", result=0 Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) Passed MTA-BLOCKED, MYNETS LOCAL [10.96.10.59] [10.96.10.59] <[email protected]> -> <[email protected]>,<[email protected] om>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>, <[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>,<[email protected]>, quarantine: B/badh-BRJjBqgK8A+g, Message-ID: <001c01ca2a1a$aad1c170$3b0a6 0...@g2z8b2x>, mail_id: BRJjBqgK8A+g, ip: 10.96.10.59, ptr: unknown, from: <[email protected]>, to: [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], zbc...@hongt a.com, [email protected], [email protected], [email protected], [email protected], subject: =?gb2312?B?0/HPqr7t0cyzp7Wzvai53MDtzOXPtaOo0Pu54bDmo6kyMDA5LjIuMj... Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) ...AuZG8=?=\t=?gb2312?B?YyAw0/HPqr7t0cyzp7Wzvai53MDtzOXPtaOo0Pu54bDmo6kyMDA5Lg==?=\t=?gb2312?B?Mi4yMC5kb2MgWzIvMl0=?=, viru s: , hits: -1994.041, size: 2627651, queued_as: /250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6. 0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery/2 50 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery/2 50 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for deliv ery/250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Me... Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) ...ssage accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery, 1023077 ms Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) Blocked MTA-BLOCKED, MYNETS LOCAL [10.96.10.59] [10.96.10.59] <[email protected]> -> <[email protected]>,<[email protected]> , quarantine: B/badh-BRJjBqgK8A+g, Message-ID: <001c01ca2a1a$aad1c170$3b0a6...@g2z8b2x>, mail_id: BRJjBqgK8A+g, ip: 10.96.10.59, ptr: unknown, from: <[email protected]> , to: [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected] om, [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], [email protected], subject:protected, virus: , hits: -1994.041, size: 2627651, 1023077 ms Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) save_info_final BRJjBqgK8A+g, ?, F, B/badh-BRJjBqgK8A+g, N, -1994.041, Message-ID: <001c01ca2a1a$aad1c170$3b0a6...@g2z8b2x> , From: 'abcd <[email protected]>',Subject:'aaaa.doc[2/2]' Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) ESMTP> 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) sending SMTP response: "451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): " Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) TempDir::strip: /home/amos/var/amavis/tmp/amavis-20090831T135932-02587 Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) rmdir_recursively: /home/amos/var/amavis/tmp/amavis-20090831T135932-02587/parts, excl=1 Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) TIMING [total 1023109 ms] - SMTP greeting: 4 (0%)0, SMTP EHLO: 3 (0%)0, SMTP pre-MAIL: 2 (0%)0, lookup_sql: 5 (0%)0, lookup _sql: 3 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 5 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 5 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 5 (0%)0, lookup_sql: 4 (0%)0, lookup_sql : 5 (0%)0, lookup_sql: 3 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 4 (0%)0, lookup_sql: 4 (0%)0, SMTP pre-DATA-flush: 3 (0%)0, SMTP DATA: 225 (0%)0, check_init: 1 (0%)0, digest_hdr: 3 (0%)0, digest_body_dkim: 31 (0%)0, sql-enter: 18 (0%)0, mime_decode: 255 (0%)0 , get-file-type1: 30 (0%)0, decompose_part: 316 (0%)0, get-file-type1: 23 (0%)0, parts_decode: 0 (0%)0, check_header: 24 (0%)0, AV-scan-1: 1429 (0%)0, spam-wb-list: 51 (0%) 0, SA parse: 113 (0%)0, SA check: 12021 (1%)1, update_cache: 20 (0%)1, decide_mail_destiny: 32 (0%)1, notif-quar: 18 (0%)1, stat-mbx: 6 (0%)1, open-mbx: 0 ... Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) ...(0%)1, write-header: 1 (0%)1, save-to-local-mailbox: 21 (0%)1, fwd-connect: 96 (0%)1, fwd-mail-pip: 48 (0%)1, fwd-rcpt-p ip: 5 (0%)1, fwd-data-chkpnt: 0 (0%)1, write-header: 1 (0%)1, fwd-data-contents: 315 (0%)1, fwd-end-chkpnt: 1007871 (99%)100, prepare-dsn: 4 (0%)100, main_log_entry: 19 (0% )100, sql-update: 15 (0%)100, update_snmp: 7 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 1 (0%)100, unlink-3-files: 3 (0%)100, rundown: 1 (0%)100 Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=de ferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=d eferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status= deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status =deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status =deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=de ferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=de ferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=d eferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=de ferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=de ferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, sta tus=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status =deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status= deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status =deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, st atus=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=d eferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status=de ferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b postfix-in/smtp[4145]: 6563B1468004: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=1027, delays=4/0.02/0.01/1023, dsn=4.5.0, status= deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=02587-14, from MTA([127.0.0.1]:2502): (in reply to end of DATA command)) Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) ESMTP< QUIT\r\n Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) ESMTP> 221 2.0.0 [127.0.0.1] HitSpam closing transmission channel Aug 31 15:13:55 pe6850b amavis[2587]: (02587-14) SMTP session over, timer stopped Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp session: setting up a new session Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp creating socket by IO::Socket::INET to [127.0.0.1]:2502 Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp resp to greeting: 220 DSPAM LMTP GIT Ready Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp cmd> LHLO localhost Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp resp to LHLO: 250 localhost.localdomain\nPIPELINING\nENHANCEDSTATUSCODES\n8BITMIME\nSIZE Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) AUTH not needed, user='', MTA offers '' Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp cmd> MAIL FROM:<[email protected]> BODY=7BIT Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp cmd> RCPT TO:<[email protected]> Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp cmd> DATA Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp resp to MAIL (pip): 250 2.1.0 OK Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Sep 2 10:04:56 pe6850b amavis[7079]: (07079-01) smtp resp to DATA: 354 Enter mail, end with "." on a line by itself Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) Amavis::Out::SMTP::Session close, keeping connection Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) FWD via LMTP: <[email protected]> -> <[email protected]>,BODY=7BIT 250 2.6.0 Ok, id=07079-01, from MTA([127.0.0.1]:2502): 250 2.6.0 <[email protected]> Message accepted for delivery Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) lookup [spam_tag_level] => true, "[email protected]" matches, result="5", matching_key="(constant:5)" Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) lookup [spam_tag2_level] => true, "[email protected]" matches, result="20", matching_key="(constant:20)" Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) lookup [spam_modifies_subj] => true, "[email protected]" matches, result="1", matching_key="(constant:1)" Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) headers CLUSTERING: NEW CLUSTER <[email protected]>: score=2.896-0.20883912592428, tag=0, tag2=0, local=1, bl=, s=, mangle= Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) dkim: candidate originators: 2822.From:<[email protected]>, 2821.mail_from:<[email protected]> Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) lookup => undef, "[email protected]", no lookup tables Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) dkim: not signing, no applicable private key for domains gzhit.net, xueron.com, s=, From: <[email protected]> Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) (about to connect to [127.0.0.1]:2502) FWD via LMTP: <[email protected]> -> <[email protected]> Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp session reuse, 1 transactions so far Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp session most likely still valid (short idle 0.0 s) Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) AUTH not needed, user='', MTA offers '' Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp cmd> MAIL FROM:<[email protected]> BODY=7BIT Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp cmd> RCPT TO:<[email protected]> Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp cmd> DATA Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp resp to MAIL (pip): 250 2.1.0 OK Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 OK Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp resp to DATA: 354 Enter mail, end with "." on a line by itself Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) smtp resp to data-dot (<[email protected]>): 250 2.6.0 <[email protected]> Message accepted for delivery Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) Amavis::Out::SMTP::Session close, keeping connection Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) FWD via LMTP: <[email protected]> -> <[email protected]>,BODY=7BIT 250 2.6.0 Ok, id=07079-01, from MTA([127.0.0.1]:2502): 250 2.6.0 <[email protected]> Message accepted for delivery Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) DSN: sender is credible (orig), SA: 2.896, <[email protected]> Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) lookup => true, "[email protected]" matches, result="18", matching_key="(constant:18)" Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) notif=N, suppressed=0, ndn_needed=0, exit=0, 250 2.6.0 Ok, id=07079-01, from MTA([127.0.0.1]:2502): 250 2.6.0 <[email protected]> Message accepted for delivery Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) lookup_ip_acl (publicnetworks): key="203.114.253.85" matches "::FFFF:0:0/96", result=1 Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) Passed CLEAN, MYNETS LOCAL [203.114.253.85] [203.114.253.85] <[email protected]> -> <[email protected]>,<[email protected]>, Message-ID: <[email protected]>, mail_id: crU4PmOmDnJC, ip: 203.114.253.85, ptr: unknown, from: <[email protected]>, to: [email protected], [email protected], subject: test message from xueron, virus: , hits: 2.687, size: 2079, queued_as: 250 2.6.0 <[email protected]> Message accepted for delivery/250 2.6.0 <[email protected]> Message accepted for delivery, 5386 ms Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) sql: preparing and executing: INSERT INTO msgrcpt (partition_tag, mail_id, rid, ds, rs, bl, wl, bspam_level, smtp_resp) VALUES (?,?,?,?,?,?,?,?,?) Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) save_info_final crU4PmOmDnJC, C, , , N, 2.68716087407572, Message-ID: <[email protected]>, From: '\345\200\252\350\277\260\350\215\243 <[email protected]>', Subject: 'test message from xueron' Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) sql: preparing and executing: UPDATE msgs SET content=?, quar_type=?, quar_loc=?, dsn_sent=?, spam_level=?, message_id=?, from_addr=?, subject=?, client_addr=? WHERE partition_tag=? AND mail_id=? Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) sending SMTP response: "250 2.6.0 Ok, id=07079-01, from MTA([127.0.0.1]:2502): 250 2.6.0 <[email protected]> Message accepted for delivery" Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) ESMTP> 250 2.6.0 Ok, id=07079-01, from MTA([127.0.0.1]:2502): 250 2.6.0 <[email protected]> Message accepted for delivery Sep 2 10:04:57 pe6850b postfix-in/smtp[6948]: 689ED1468002: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=21, delays=16/0/0.01/5.4, dsn=2.6.0, status=sent (250 2.6.0 Ok, id=07079-01, from MTA([127.0.0.1]:2502): 250 2.6.0 <[email protected]> Message accepted for delivery) Sep 2 10:04:57 pe6850b postfix-in/smtp[6948]: 689ED1468002: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:2501, delay=21, delays=16/0/0.01/5.4, dsn=2.6.0, status=sent (250 2.6.0 Ok, id=07079-01, from MTA([127.0.0.1]:2502): 250 2.6.0 <[email protected]> Message accepted for delivery) Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) TempDir::strip: /home/amos/var/amavis/tmp/amavis-20090902T100452-07079 Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) rmdir_recursively: /home/amos/var/amavis/tmp/amavis-20090902T100452-07079/parts, excl=1 Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) TIMING [total 5401 ms] - sql-prepare: 4 (0%)0, SMTP greeting: 3 (0%)0, SMTP EHLO: 2 (0%)0, SMTP pre-MAIL: 2 (0%)0, mkdir tempdir: 1 (0%)0, create email.txt: 1 (0%)0, sql-connect: 12 (0%)0, lookup_sql: 2 (0%)0, lookup_sql: 3 (0%)1, SMTP pre-DATA-flush: 2 (0%)1, SMTP DATA: 19 (0%)1, check_init: 1 (0%)1, digest_hdr: 2 (0%)1, digest_body_dkim: 1 (0%)1, sql-enter: 8 (0%)1, mkdir parts: 5 (0%)1, mime_decode: 13 (0%)1, get-file-type1: 19 (0%)2, parts_decode: 0 (0%)2, check_header: 3 (0%)2, AV-scan-1: 12 (0%)2, spam-wb-list: 10 (0%)2, SA parse: 6 (0%)2, SA check: 4301 (80%)82, update_cache: 9 (0%)82, penpals_check: 5 (0%)82, decide_mail_destiny: 3 (0%)82, fwd-connect: 52 (1%)83, fwd-mail-pip: 42 (1%)84, fwd-rcpt-pip: 0 (0%)84, fwd-data-chkpnt: 0 (0%)84, write-header: 1 (0%)84, fwd-data-contents: 0 (0%)84, fwd-end-chkpnt: 396 (7%)91, fwd-connect: 5 (0%)92, fwd-mail-pip: 41 (1%)92, fwd-rcpt-pip: 0 (0%)92, fwd-data-chkpnt: 0 (0%)92, write -header: 1 (0%)92, fwd-da... Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) ...ta-contents: 0 (0%)92, fwd-end-chkpnt: 384 (7%)99, prepare-dsn: 2 (0%)99, main_log_entry: 13 (0%)100, sql-update: 6 (0%)100, update_snmp: 5 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 1 (0%)100, unlink-2-files: 1 (0%)100, rundown: 1 (0%)100 Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) ESMTP< QUIT\r\n Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) ESMTP> 221 2.0.0 [127.0.0.1] HitSpam closing transmission channel Sep 2 10:04:57 pe6850b amavis[7079]: (07079-01) SMTP session over, timer stopped -- Xueron Nee http://www.xueron.com ------------------------------------------------------------------------------ Let Crystal Reports handle the reporting - Free Crystal Reports 2008 30-Day trial. Simplify your report design, integration and deployment - and focus on what you do best, core application coding. Discover what's new with Crystal Reports now. http://p.sf.net/sfu/bobj-july _______________________________________________ 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/
