amavisd-new-2.7.0-rc1 release candidate is available

Mon Jun 6 11:25:58 CEST 2011

* Mark Martinec <Mark.Martinec+amavis at ijs.si>:
> A release candidate of the amavisd-new-2.7.0 is available at:

I'm getting a strange error with 2.7.0-rc1 starting today:

503 5.5.1 Error: MAIL transaction in progress
and 
503 5.5.1 Error: nested MAIL command

* root <root at charite.de>:
> Jun  6 11:15:44 mail2 amavis[28025]: (28025-02) ESMTP::10025 /var/amavis/amavis-20110606T111136-28025-8an6GgAI: <sentto-18594769-1835-1307350702-kirsten.igler=charite.de at returns.groups.yahoo.com> -> <kirsten.igler at charite.de> Received: from mail2.charite.de ([127.0.0.1]) by localhost (mail2.charite.de [127.0.0.1]) (amavisd-new, port 10025) with ESMTP for <kirsten.igler at charite.de>; Mon,  6 Jun 2011 11:15:44 +0200 (CEST)
> Jun  6 11:15:44 mail2 amavis[28025]: (28025-02) dkim: VALID Sender signature by d=yahoogroups.de, From: <theokom at gmx.net>, a=rsa-sha256, c=relaxed/relaxed, s=kilo, i=@yahoogroups.de, m.list(ml:cubws07.yahoogroups.de)
> Jun  6 11:15:44 mail2 amavis[28025]: (28025-02) dkim: VALID Sender signature by d=yahoogroups.de, From: <theokom at gmx.net>, a=rsa-sha1, c=nofws, s=kilo, i=cubws07 at yahoogroups.de, m.list(ml:cubws07.yahoogroups.de)
> Jun  6 11:15:44 mail2 amavis[28025]: (28025-02) Checking: KRtOZ7ffSZ1G [67.195.134.218] <sentto-18594769-1835-1307350702-kirsten.igler=charite.de at returns.groups.yahoo.com> -> <kirsten.igler at charite.de>
> Jun  6 11:15:44 mail2 amavis[28025]: (28025-02) p003 1 Content-Type: multipart/alternative
> Jun  6 11:15:44 mail2 amavis[28025]: (28025-02) p001 1/1 Content-Type: text/plain, size: 1133 B, name: 
> Jun  6 11:15:44 mail2 amavis[28025]: (28025-02) p002 1/2 Content-Type: text/html, size: 10775 B, name: 
> Jun  6 11:15:45 mail2 amavis[28025]: (28025-02) wbl: soft-whitelisted (-3) sender <sentto-18594769-1835-1307350702-kirsten.igler=charite.de at returns.groups.yahoo.com> => <kirsten.igler at charite.de>, recip_key="."
> Jun  6 11:15:52 mail2 amavis[28025]: (28025-02) (!)Negative SMTP resp. to XFORWARD: 503 5.5.1 Error: MAIL transaction in progress\nContact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:15:52), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.
> Jun  6 11:15:52 mail2 amavis[28025]: (28025-02) smtp resp to MAIL (pip): 503 5.5.1 Error: nested MAIL command\nContact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:15:52), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.
> Jun  6 11:15:52 mail2 amavis[28025]: (28025-02) Too late, DATA accepted but no valid recips, send dummy
> Jun  6 11:17:53 mail2 amavis[28025]: (28025-02) Negative SMTP response to data-dot (<kirsten.igler at charite.de>): 451 4.3.5 Server configuration problem\nContact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:17:53), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.
> Jun  6 11:17:53 mail2 amavis[28025]: (28025-02) (!)FWD from <sentto-18594769-1835-1307350702-kirsten.igler=charite.de at returns.groups.yahoo.com> -> <kirsten.igler at charite.de>,BODY=7BIT 503 5.5.1 from MTA(smtp:[127.0.0.1]:10026): 503 5.5.1 Error: nested MAIL command\nContact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:15:52), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.
> Jun  6 11:17:53 mail2 amavis[28025]: (28025-02) Blocked MTA-BLOCKED {RejectedInbound}, [67.195.134.218]:33976 [98.137.35.158] <sentto-18594769-1835-1307350702-kirsten.igler=charite.de at returns.groups.yahoo.com> -> <kirsten.igler at charite.de>, Message-ID: <283E27D9540E4722913F86A867D0AE83 at Odilein>, mail_id: KRtOZ7ffSZ1G, Hits: 0.011, size: 16557, dkim_sd=kilo:yahoogroups.de, 128748 ms
> Jun  6 11:17:53 mail2 amavis[28025]: (28025-02) OS_fingerprint: 67.195.134.218 0.0110000000000001 ham.FreeBSD - FreeBSD 6.x (1) (up: 8207 hrs), (distance 10, link: ethernet/modem)
> Jun  6 11:17:53 mail2 amavis[28025]: (28025-02) TIMING-SA total 7700 ms - parse: 9 (0.1%), extract_message_metadata: 516 (6.7%), get_uri_detail_list: 80 (1.0%), tests_pri_-1000: 481 (6.2%), tests_pri_-950: 3 (0.0%), tests_pri_-900: 9 (0.1%), tests_pri_-400: 3 (0.0%), tests_pri_0: 6381 (82.9%), check_dkim_adsp: 47 (0.6%), check_spf: 60 (0.8%), poll_dns_idle: 0.26 (0.0%), tests_pri_500: 19 (0.3%), tests_pri_1000: 48 (0.6%), total_awl: 44 (0.6%), check_awl: 2 (0.0%), update_awl: 0.13 (0.0%), get_report: 16 (0.2%)
> Jun  6 11:17:53 mail2 postfix/smtpd[19082]: proxy-reject: END-OF-MESSAGE: 503-5.5.1 id=28025-02 - Rejected by next-hop MTA on relaying, from MTA(smtp:[127.0.0.1]:10026): 503 5.5.1 Error: nested MAIL command?503 5.5.1 Contact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:15:52), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.; from=<sentto-18594769-1835-1307350702-kirsten.igler=charite.de at returns.groups.yahoo.com> to=<kirsten.igler at charite.de> proto=SMTP helo=<n3-vm0.bullet.mail.sp2.yahoo.com>
> Jun  6 11:17:53 mail2 amavis[28025]: (28025-02) TIMING [total 128779 ms] - SMTP greeting: 6 (0%)0, SMTP EHLO: 2 (0%)0, SMTP pre-MAIL: 1 (0%)0, SMTP pre-DATA-flush: 18 (0%)0, SMTP DATA: 1 (0%)0, check_init: 1 (0%)0, digest_hdr: 44 (0%)0, digest_body_dkim: 42 (0%)0, mime_decode: 126 (0%)0, get-file-type2: 126 (0%)0, parts_decode: 0 (0%)0, check_header: 2 (0%)0, AV-scan-1: 48 (0%)0, AV-scan-2: 64 (0%)0, spam-wb-list: 5 (0%)0, SA parse: 11 (0%)0, SA check: 7642 (6%)6, decide_mail_destiny: 59 (0%)6, notif-quar: 2 (0%)6, fwd-connect: 9 (0%)6, fwd-xforward: 13 (0%)6, fwd-mail-pip: 8 (0%)6, fwd-rcpt-pip: 0 (0%)6, fwd-data-chkpnt: 0 (0%)6, fwd-end-chkpnt: 120497 (94%)100, prepare-dsn: 3 (0%)100, main_log_entry: 20 (0%)100, update_snmp: 19 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 8 (0%)100, unlink-3-files: 0 (0%)100, rundown: 2 (0%)100

* root <root at charite.de>:
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) ESMTP::10025 /var/amavis/amavis-20110606T110641-25000-hbzMy5oF: <sentto-73711328-184-1307350963-Katja.Majewski=charite.de at returns.groups.yahoo.com> -> <Katja.Majewski at charite.de> Received: from mail2.charite.de ([127.0.0.1]) by localhost (mail2.charite.de [127.0.0.1]) (amavisd-new, port 10025) with ESMTP for <Katja.Majewski at charite.de>; Mon,  6 Jun 2011 11:15:26 +0200 (CEST)
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) dkim: VALID Sender signature by d=yahoogroups.de, From: <theokom at gmx.net>, a=rsa-sha256, c=relaxed/relaxed, s=kilo, i=@yahoogroups.de, m.list(ml:cubss11.yahoogroups.de)
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) dkim: VALID Sender signature by d=yahoogroups.de, From: <theokom at gmx.net>, a=rsa-sha1, c=nofws, s=kilo, i=cubss11 at yahoogroups.de, m.list(ml:cubss11.yahoogroups.de)
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) Checking: s6-Q3CqJ5TXr [69.147.103.217] <sentto-73711328-184-1307350963-Katja.Majewski=charite.de at returns.groups.yahoo.com> -> <Katja.Majewski at charite.de>
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) p005 1 Content-Type: multipart/mixed
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) p006 1/1 Content-Type: multipart/related
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) p007 1/1/1 Content-Type: multipart/alternative
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) p001 1/1/1/1 Content-Type: text/plain, size: 1251 B, name: 
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) p002 1/1/1/2 Content-Type: text/html, size: 8434 B, name: 
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) p003 1/1/2 Content-Type: image/gif, size: 2587 B, name: image001.gif
> Jun  6 11:15:26 mail2 amavis[25000]: (25000-19) p004 1/2 Content-Type: application/pdf, size: 239606 B, name: Presse_Einladung_BBRG.pdf
> Jun  6 11:15:27 mail2 amavis[25000]: (25000-19) wbl: soft-whitelisted (-3) sender <sentto-73711328-184-1307350963-Katja.Majewski=charite.de at returns.groups.yahoo.com> => <Katja.Majewski at charite.de>, recip_key="."
> Jun  6 11:15:27 mail2 amavis[25000]: (25000-19) truncating a message passed to SA at 208467 bytes, orig 346768
> Jun  6 11:15:33 mail2 amavis[25000]: (25000-19) (!)Negative SMTP resp. to XFORWARD: 503 5.5.1 Error: MAIL transaction in progress\nContact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:15:33), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.
> Jun  6 11:15:34 mail2 amavis[25000]: (25000-19) smtp resp to MAIL (pip): 503 5.5.1 Error: nested MAIL command\nContact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:15:34), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.
> Jun  6 11:15:36 mail2 amavis[25000]: (25000-19) Too late, DATA accepted but no valid recips, send dummy
> Jun  6 11:17:54 mail2 amavis[25000]: (25000-19) Negative SMTP response to data-dot (<Katja.Majewski at charite.de>): 451 4.3.5 Server configuration problem\nContact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:17:54), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.
> Jun  6 11:17:54 mail2 amavis[25000]: (25000-19) (!)FWD from <sentto-73711328-184-1307350963-Katja.Majewski=charite.de at returns.groups.yahoo.com> -> <Katja.Majewski at charite.de>,BODY=7BIT 503 5.5.1 from MTA(smtp:[127.0.0.1]:10026): 503 5.5.1 Error: nested MAIL command\nContact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:15:34), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.
> Jun  6 11:17:54 mail2 amavis[25000]: (25000-19) Blocked MTA-BLOCKED {RejectedInbound}, [69.147.103.217]:43348 [66.196.95.32] <sentto-73711328-184-1307350963-Katja.Majewski=charite.de at returns.groups.yahoo.com> -> <Katja.Majewski at charite.de>, Message-ID: <3A6A70FAD72046C28C796695DAF024E4 at Odilein>, mail_id: s6-Q3CqJ5TXr, Hits: -0.267, size: 346768, dkim_sd=kilo:yahoogroups.de, 148177 ms
> Jun  6 11:17:54 mail2 amavis[25000]: (25000-19) OS_fingerprint: 69.147.103.217 -0.267 ham.FreeBSD - FreeBSD 4.7-5.2 (or MacOS X 10.2-10.4) (2) (up: 883 hrs), (distance 8, link: ethernet/modem)
> Jun  6 11:17:54 mail2 amavis[25000]: (25000-19) TIMING-SA total 5458 ms - parse: 133 (2.4%), extract_message_metadata: 413 (7.6%), get_uri_detail_list: 7 (0.1%), tests_pri_-1000: 305 (5.6%), tests_pri_-950: 3 (0.0%), tests_pri_-900: 31 (0.6%), tests_pri_-400: 7 (0.1%), tests_pri_0: 4193 (76.8%), check_dkim_adsp: 29 (0.5%), check_spf: 37 (0.7%), poll_dns_idle: 0.19 (0.0%), tests_pri_500: 7 (0.1%), tests_pri_1000: 19 (0.4%), total_awl: 16 (0.3%), check_awl: 0.39 (0.0%), update_awl: 0.15 (0.0%), get_report: 9 (0.2%)
> Jun  6 11:17:54 mail2 postfix/smtpd[26045]: proxy-reject: END-OF-MESSAGE: 503-5.5.1 id=25000-19 - Rejected by next-hop MTA on relaying, from MTA(smtp:[127.0.0.1]:10026): 503 5.5.1 Error: nested MAIL command?503 5.5.1 Contact postmaster at charite.de (using a different email address!)  for technical assistance. Please provide the following information in your problem report: This error message, time (Jun 06 11:15:34), client (127.0.0.1) and server (mail2.charite.de). We speak both English and German.; from=<sentto-73711328-184-1307350963-Katja.Majewski=charite.de at returns.groups.yahoo.com> to=<Katja.Majewski at charite.de> proto=SMTP helo=<n6b.bullet.mail.re1.yahoo.com>
> Jun  6 11:17:54 mail2 amavis[25000]: (25000-19) TIMING [total 148203 ms] - SMTP greeting: 12 (0%)0, SMTP EHLO: 1 (0%)0, SMTP pre-MAIL: 1 (0%)0, SMTP pre-DATA-flush: 21 (0%)0, SMTP DATA: 14 (0%)0, check_init: 1 (0%)0, digest_hdr: 36 (0%)0, digest_body_dkim: 131 (0%)0, mime_decode: 279 (0%)0, get-file-type4: 75 (0%)0, parts_decode: 1 (0%)0, check_header: 7 (0%)0, AV-scan-1: 262 (0%)1, AV-scan-2: 336 (0%)1, spam-wb-list: 6 (0%)1, SA parse: 136 (0%)1, SA check: 5257 (4%)4, decide_mail_destiny: 93 (0%)4, notif-quar: 3 (0%)5, fwd-connect: 22 (0%)5, fwd-xforward: 1002 (1%)5, fwd-mail-pip: 1002 (1%)6, fwd-rcpt-pip: 1002 (1%)7, fwd-data-chkpnt: 1001 (1%)7, fwd-end-chkpnt: 137437 (93%)100, prepare-dsn: 4 (0%)100, main_log_entry: 38 (0%)100, update_snmp: 22 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 0 (0%)100, unlink-5-files: 1 (0%)100, rundown: 2 (0%)100

-- 
Ralf Hildebrandt
  Geschäftsbereich IT | Abteilung Netzwerk
  Charité - Universitätsmedizin Berlin
  Campus Benjamin Franklin
  Hindenburgdamm 30 | D-12203 Berlin
  Tel. +49 30 450 570 155 | Fax: +49 30 450 570 962
  ralf.hildebrandt at charite.de | http://www.charite.de