amavisd-new 2.4 seems to have hosed my mail server

Used BuildSmart this morning to update ClamAV and amavisd-new and now all mail is stuck in the queue with this message:

host 127.0.0.1[127.0.0.1] said: 451 4.5.0 Error in processing, id=01286-15, parts decodeext FAILED: parsing file(1

Killing the process does not work. I have tried to replace amavisd and amavisd.conf from a backup to no avail...

Help, please!

Xserve Dual 1 GHz G4, Mac OS X (10.4.5)

Posted on Apr 7, 2006 12:50 PM

Reply
14 replies

Apr 7, 2006 1:19 PM in response to Scott Slater

Replacing amavisd and amavisd.conf from a backup, should sort it.
After restoring from the backup, you may need to reboot your server. Re-Starting mail services is probably not good enough.
Once amavisd is running again issue "sudo postsuper -r ALL" to restart the queue.

Also, check /etc/postfix/main.cf if by any chance you have:
inet_interfaces = localhost
instead of:
inet_interfaces = all
If, yes fix it back to "inet_interfaces = all" and refresh postfix.

Alex

Apr 8, 2006 11:11 AM in response to pterobyte

Here you go. Thanks for your help.

-----------------------------------------------------------------

03 1 Content-Type: multipart/mixed
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-05) p001 1/1 Content-Type: text/plain, size: 27 B, name:
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-05) p002 1/2 Content-Type: application/plain, size: 1256 B, name: 5860.3668.csv
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[567]: (00559-05) run_command: child process [567]: Error closing main::stdin: Bad file descriptor at /usr/bin/amavisd line 1881.\n
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-05) TROUBLE in check_mail: parts decodeext FAILED: parsing file(1) results - missing last 2 results at (eval 43) line 154.
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-05) PRESERVING EVIDENCE in /var/amavis/tmp/amavis-20060407T164530-00559
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-05) TIMING [total 200 ms] - SMTP EHLO: 8 (4%)4, SMTP pre-MAIL: 2 (1%)5, mkdir tempdir: 1 (1%)6, create email.txt: 1 (0%)6, SMTP pre-DATA-flush: 6 (3%)9, SMTP DATA: 102 (51%)60, body_digest: 3 (1%)62, gen mailid: 1 (1%)62, mkdir parts: 1 (1%)63, mime_decode: 40 (20%)83, rundown: 34 (17%)100
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-06) ESMTP::10024 /var/amavis/tmp/amavis-20060407T164530-00559: <admin67@secure7.shoppingcartserver.com> -> <wizard@smartexhibits.com>,<scott@vengra.com> Received: SIZE=13463 from mail.smartexhibits.com ([127.0.0.1]) by localhost (mail.smartexhibits.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 00559-06; Fri, 7 Apr 2006 16:45:30 -0500 (CDT)
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-06) Checking: LWDblprK+XdI [66.116.100.195] <admin67@secure7.shoppingcartserver.com> -> <wizard@smartexhibits.com>,<scott@vengra.com>
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-06) p001 1 Content-Type: text/html, size: 8824 B, name:
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[568]: (00559-06) run_command: child process [568]: Error closing main::stdin: Bad file descriptor at /usr/bin/amavisd line 1881.\n
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-06) TROUBLE in check_mail: parts decodeext FAILED: parsing file(1) results - missing last 1 results at (eval 43) line 154.
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-06) PRESERVING EVIDENCE in /var/amavis/tmp/amavis-20060407T164530-00559
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-06) TIMING [total 369 ms] - SMTP EHLO: 7 (2%)2, SMTP pre-MAIL: 2 (1%)3, create email.txt: 2 (0%)3, SMTP pre-DATA-flush: 5 (1%)4, SMTP DATA: 290 (79%)83, body_digest: 3 (1%)84, gen mailid: 1 (0%)84, mime_decode: 26 (7%)91, rundown: 33 (9%)100
Apr 7 16:45:30 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-07) ESMTP::10024 /var/amavis/tmp/amavis-20060407T164530-00559: <jeff@smartexhibits.com> -> <wizard@smartexhibits.com>,<mom@webmetro.com> Received: SIZE=3297 from mail.smartexhibits.com ([127.0.0.1]) by localhost (mail.smartexhibits.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 00559-07; Fri, 7 Apr 2006 16:45:30 -0500 (CDT)
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-07) Checking: p9yuJ2GHgBkg [192.168.254.4] <jeff@smartexhibits.com> -> <wizard@smartexhibits.com>,<mom@webmetro.com>
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-07) p004 1 Content-Type: multipart/alternative
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-07) p001 1/1 Content-Type: text/plain, size: 566 B, name:
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-07) p002 1/2 Content-Type: text/html, size: 1123 B, name:
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[569]: (00559-07) run_command: child process [569]: Error closing main::stdin: Bad file descriptor at /usr/bin/amavisd line 1881.\n
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-07) TROUBLE in check_mail: parts decodeext FAILED: parsing file(1) results - missing last 3 results at (eval 43) line 154.
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-07) PRESERVING EVIDENCE in /var/amavis/tmp/amavis-20060407T164530-00559
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-07) TIMING [total 199 ms] - SMTP EHLO: 7 (4%)4, SMTP pre-MAIL: 2 (1%)5, create email.txt: 1 (1%)6, SMTP pre-DATA-flush: 5 (2%)8, SMTP DATA: 103 (52%)60, body_digest: 2 (1%)61, gen mailid: 1 (1%)61, mime_decode: 43 (22%)83, rundown: 34 (17%)100
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-08) ESMTP::10024 /var/amavis/tmp/amavis-20060407T164531-00559: <admin67@secure7.shoppingcartserver.com> -> <wizard@smartexhibits.com>,<orders@vengra.com> Received: SIZE=4289 BODY=8BITMIME from mail.smartexhibits.com ([127.0.0.1]) by localhost (mail.smartexhibits.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 00559-08; Fri, 7 Apr 2006 16:45:31 -0500 (CDT)
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) Checking: XWoJMhUAX+qN [64.203.188.4] <mkeelin@arnoldagency.com> -> <graphics@smartexhibits.com>,<wizard@smartexhibits.com>
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-08) Checking: rY5f9qjjBY7Q [66.116.100.195] <admin67@secure7.shoppingcartserver.com> -> <wizard@smartexhibits.com>,<orders@vengra.com>
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-08) p003 1 Content-Type: multipart/mixed
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-08) p001 1/1 Content-Type: text/plain, size: 27 B, name:
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-08) p002 1/2 Content-Type: application/plain, size: 1848 B, name: 5865.3673.csv
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[570]: (00559-08) run_command: child process [570]: Error closing main::stdin: Bad file descriptor at /usr/bin/amavisd line 1881.\n
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-08) TROUBLE in check_mail: parts decodeext FAILED: parsing file(1) results - missing last 2 results at (eval 43) line 154.
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-08) PRESERVING EVIDENCE in /var/amavis/tmp/amavis-20060407T164531-00559
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-08) TIMING [total 384 ms] - SMTP EHLO: 8 (2%)2, SMTP pre-MAIL: 2 (1%)3, mkdir tempdir: 1 (0%)3, create email.txt: 1 (0%)3, SMTP pre-DATA-flush: 6 (2%)5, SMTP DATA: 281 (73%)78, body_digest: 3 (1%)79, gen mailid: 1 (0%)79, mkdir parts: 1 (0%)79, mime_decode: 40 (10%)90, rundown: 39 (10%)100
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-09) ESMTP::10024 /var/amavis/tmp/amavis-20060407T164531-00559: <mailer@mailer.ibsys.com> -> <wizard@smartexhibits.com>,<jeff@vengra.com> Received: SIZE=3899 from mail.smartexhibits.com ([127.0.0.1]) by localhost (mail.smartexhibits.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 00559-09; Fri, 7 Apr 2006 16:45:31 -0500 (CDT)
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-09) ESMTP ABORTING: Connection broken during DATA: Connection reset by peer at (eval 40) line 588, <GEN43> chunk 7.
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-09) TIMING [total 70 ms] - SMTP EHLO: 12 (18%)18, SMTP pre-MAIL: 3 (4%)22, create email.txt: 2 (2%)24, SMTP pre-DATA-flush: 9 (12%)37, unlink-2-files: 43 (62%)98, rundown: 1 (2%)100
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-09) ESMTP: NOTICE: ABORTING the session: Connection broken during DATA: Connection reset by peer at (eval 40) line 588, <GEN43> chunk 7.
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-09) TROUBLE in process_request: Error writing a SMTP response to the socket: Broken pipe at (eval 40) line 813.
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[559]: (00559-09) Requesting process rundown after fatal error
Apr 7 16:45:31 mail.smartexhibits.com /usr/bin/amavisd[576]: TIMING [total 28 ms] - bdb-open: 28 (100%)100, rundown: 0 (0%)100
Apr 7 16:45:32 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) p003 1 Content-Type: multipart/mixed
Apr 7 16:45:32 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) p001 1/1 Content-Type: text/plain, size: 904 B, name:
Apr 7 16:45:32 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) p002 1/2 Content-Type: application/pdf, size: 3025036 B, name: DHHI_7226final50%.pdf
Apr 7 16:45:32 mail.smartexhibits.com /usr/bin/amavisd[577]: (00556-04) run_command: child process [577]: Error closing main::stdin: Bad file descriptor at /usr/bin/amavisd line 1881.\n
Apr 7 16:45:32 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) TROUBLE in check_mail: parts decodeext FAILED: parsing file(1) results - missing last 2 results at (eval 43) line 154.
Apr 7 16:45:32 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) PRESERVING EVIDENCE in /var/amavis/tmp/amavis-20060407T164530-00556
Apr 7 16:45:33 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) TIMING [total 2943 ms] - SMTP EHLO: 6 (0%)0, SMTP pre-MAIL: 2 (0%)0, mkdir tempdir: 1 (0%)0, create email.txt: 1 (0%)0, SMTP pre-DATA-flush: 5 (0%)1, SMTP DATA: 1055 (36%)36, body_digest: 135 (5%)41, gen mailid: 1 (0%)41, mkdir parts: 1 (0%)41, mime_decode: 950 (32%)73, rundown: 785 (27%)100
Apr 7 16:45:33 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) TROUBLE in process_request: Error writing a SMTP response to the socket: Broken pipe at (eval 40) line 813, <GEN20> line 53151.
Apr 7 16:45:33 mail.smartexhibits.com /usr/bin/amavisd[556]: (00556-04) Requesting process rundown after fatal error
Apr 7 16:45:33 mail.smartexhibits.com /usr/bin/amavisd[578]: TIMING [total 27 ms] - bdb-open: 27 (100%)100, rundown: 0 (0%)100
Apr 7 16:45:34 mail.smartexhibits.com /usr/bin/amavisd[492]: Net::Server: 2006/04/07-16:45:34 Server closing!

Xserve Dual 1 GHz G4 Mac OS X (10.4.1)

Apr 9, 2006 11:51 PM in response to Scott Slater

I thought so. That's why I asked twice 😉

I've seen this happening on a few systems (with or without buildsmart). Honestly, I haven't been able to figure out the pattern and when and why it happens. It seems to have something to do with Server Admin's caching mechanism, but I am not sure at all.

Try the following (I know it sound silly, but I have no better suggestion bar re-installing your server):
-Open Server Admin
-Change something irrelevant and save
-Close Server Admin
-Wait 2 minutes
-Open terminal and issue "sudo serveradmin stop mail"
-Reboot server
-fix "inet_interfaces = all"
-Reboot server
-Open terminal and issue "sudo serveradmin start mail"
-Keep your fingers crossed

Apr 11, 2006 7:57 AM in response to Scott Slater

Meaning I tried the above and it still does not stick. It just so happens that I am getting and new G5 Xserve today....I really liked the BuildSmart app but now I am very leery about using it on my new setup, especially since last Friday I submitted a bug report and have not gotten a response. I was one of the beta testers of the program and now I feel hung out to dry...Granted, it my not bee an issue with BuildSmart, but a bug report response would be nice...

Apr 11, 2006 8:17 AM in response to Scott Slater

Scott,

BuildSmart is a free app, developed by Dale in his spare time. BuildSmart being free, Dale obviously makes his living (as I do) with something different. This often means that support is given (if any) during ones' spare time. This also means that somebody could be away for the weekend or on holiday or just plain busy.

If you expect premium 2 hour turnaround support, get yourself a support contract with a professional in your area and let them worry about things.
Alternatively, learn how to build what you need yourself so you do not have to rely on free and unsupported software.

Now coming back to your issue. As I mentioned before, I don't know what is causing this behaviour, but I have seen it on a variety of systems that had never seen BuildSmart even from afar. While in IT I'd never dare to say impossible, I cannot see any connection with BuildSmart and your issue.

To further investigate and/or sort out your issue, I would need access to your server and some time. Unfortunately, I won't have time in the next few days. If you're interested in me looking at your system, and are in no rush, let me know.

Alex

P.S. Did you mail Dale directly? I have not seen any bug report filed through the official download site.

Apr 11, 2006 9:02 AM in response to pterobyte

BuildSmart is a free app, developed by Dale in his
spare time. BuildSmart being free, Dale obviously
makes his living (as I do) with something different.
This often means that support is given (if any)
during ones' spare time. This also means that
somebody could be away for the weekend or on holiday
or just plain busy.


Understood. Like I said I was a "there in the early days". I know what it is. Going this route always bears a bit of risk.

If you expect premium 2 hour turnaround support, get
yourself a support contract with a professional in
your area and let them worry about things.


I don't expect it. I'm happy with any assistance anyone in this forum can provide and the developer of BuildSmart when available.

Now coming back to your issue. As I mentioned before,
I don't know what is causing this behaviour, but I
have seen it on a variety of systems that had never
seen BuildSmart even from afar. While in IT I'd never
dare to say impossible, I cannot see any connection
with BuildSmart and your issue.


It's just very strange that it occurred directly after I updated ClamAV and amavis-new using the BuildSmart app.
To further investigate and/or sort out your issue, I
would need access to your server and some time.
Unfortunately, I won't have time in the next few
days. If you're interested in me looking at your
system, and are in no rush, let me know.

Yes, I will be setting up the our new server (just in time, I quess) starting today and I have my e-mail through our webhost for the time being. Let me know when you could poke around. That would be awesome...

P.S. Did you mail Dale directly? I have not seen any
bug report filed through the official download site.


No, I used the bug report form.

Apr 11, 2006 10:06 AM in response to Scott Slater

It's just very strange that it occurred directly
after I updated ClamAV and amavis-new using the
BuildSmart app.

Agreed. It may look like. My gut feeling is that it is caused by Server Admin caching something it shouldn't. So anything that stops and starts mail services could trigger it. On all systems I have seen so far, it happened after something was changed (with or without buildsmart) and mail services restarted. Just haven't found out what exactly. I've also seen some relation to NAT. (If you use NAT, you may want to stop NAT, do the changes, reload postfix, start NAT - you never know).

Yes, I will be setting up the our new server (just in
time, I quess) starting today and I have my e-mail
through our webhost for the time being. Let me know
when you could poke around. That would be awesome...

OK. Will let you know by mail.

No, I used the bug report form.

My fault. Had overlooked it. It's there.

Apr 11, 2006 9:52 PM in response to pterobyte

I've also seen
some relation to NAT. (If you use NAT, you may want
to stop NAT, do the changes, reload postfix, start
NAT - you never know).


Not using NAT

Yes, I will be setting up the our new server (just

in
time, I quess) starting today and I have my e-mail
through our webhost for the time being. Let me

know
when you could poke around. That would be

awesome...
OK. Will let you know by mail.


Thanks

This thread has been closed by the system or the community team. You may vote for any posts you find helpful, or search the Community for additional answers.

amavisd-new 2.4 seems to have hosed my mail server

Welcome to Apple Support Community
A forum where Apple customers help each other with their products. Get started with your Apple Account.