Re: 421 Service not available (Amavis)

[El Neofito]

mouss wrote:
>>There is a problem here. What version of amavisd-new and of Net::Server
>>are you running?

 

You are spot on mouss, SuSE10.3 is running perl-Net-Server-0.96-33 with amavisd-new 2.5.7

I am using maia-amavisd since i am using maia so the problem has to be with Net::Server.

The line 1619 as stated in the logs point to this and i ve changed the below:

close(main::stdin) or die "Error closing main::stdin: $!";
to:
close(main::stdin);

 

And it is working now. Nothing to do with Postfix.

 

Thank you for pointing me to the right direction!

 

Cheers

Fred

Send instant messages to your online friends http://uk.messenger.yahoo.com

[mouss-2]

El Neofito wrote:

> mouss wrote:
>  
>>> There is a problem here. What version of amavisd-new and of Net::Server
>>> are you running?
>>>      
> You are spot on mouss, SuSE10.3 is running perl-Net-Server-0.96-33 with amavisd-new 2.5.7
> I am using maia-amavisd since i am using maia so the problem has to be with Net::Server.
> The line 1619 as stated in the logs point to this and i ve changed the below:
>
> close(main::stdin) or die "Error closing main::stdin: $!";
> to:
> close(main::stdin);
>  

looks like a lose patch. you'd better ask on the amavisd-new list for
the real problem/solution.
> And it is working now. Nothing to do with Postfix.
> Thank you for pointing me to the right direction!
> Cheers
> Fred
>
> Send instant messages to your online friends http://uk.messenger.yahoo.com 
>  

[Jonathan Dill]

On Apr 26, 2008, at 3:22 PM, El Neofito wrote:

mouss wrote:

>>I don't see anything obvious except the timeouts issue. try telnet to 
>>ports 10024 and 10025 and see which one drops the connection. you can 
>>also run amavisd in debug mode to see more logs.

Amavis debug did not output anything. However amavis.log did output something as below:

Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) run_command: child process [10525]: Can't close main::stdin: Bad file descriptor at /usr/sbin/amavisd line 1619.\n

Did you try googling "Can't close main::stdin: Bad file descriptor at /usr/sbin/amavisd line"?  I found this post, which references different line number in amavisd, but you also have MySQL in your config, which turned out to be related to the problem in this post:

http://www.howtoforge.com/forums/showthread.php?t=3705&page=2

More specifically, you may need to downgrade to a specific, older version of perl Net::Server which works with amavisd-new.

Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) child_finish_hook: invoking DESTROY methods
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) Amavis::Lookup::SQL called
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10243]: (10243-01) run_command: [10525] /usr/bin/file p001 </dev/null 2>&1
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) Amavis::Cache called
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) Amavis::DB::SNMP called
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) Amavis::In::SMTP::DESTROY called
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) SMTP shutdown: tempdir is being removed: /var/spool/amavis/tmp/amavis-20080427T030329-10243
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) rmdir_recursively: /var/spool/amavis/tmp/amavis-20080427T030329-10243, excl=
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) rmdir_recursively: /var/spool/amavis/tmp/amavis-20080427T030329-10243/parts, excl=0
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) ESMTP> 421 4.3.2 Service shutting down, closing channel
Apr 27 03:03:29 xxmail.dyndns.org /usr/sbin/amavisd[10525]: (10243-01) at the END handler: invoking DESTROY methods

Telnet to 10024 (amavis) and 10025 (postfix) was entirely successful. Any clues?

Send instant messages to your online friends http://uk.messenger.yahoo.com