amavisd-new 2.4.0
Hi, I'm trying to debug amavisd but am a bit confused. If I telnet to
localhost 10024 or 10025 everything looks good. But the actually logs
tell a different story and mail is just building in the queue. Below are
the syslog output and debug output. Any help on this would be greatly
appreciated.
LOG MESSAGE:
relay=none, delay=0, status=deferred (delivery temporarily suspended:
lost connection with 127.0.0.1[127.0.0.1] while sending MAIL FROM)
AMAVISD -U AMAVIS DEBUG OUTPUT:
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24134]:
(24134-01) prolong_timer smtp response sent: timer set to = 120 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24134]:
(24134-01) idle_proc, 6: was busy, 1.7 ms, total idle 0.002 s, busy 0.031 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24134]:
(24134-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.002 s, busy 0.031 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]: Apr
10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24134]:
(24134-01) ESMTP< MAIL FROM:<[EMAIL PROTECTED]>
SIZE=8842\r\n
(24132-01-2) Apr 10 15:02:16 mymachine.mydomain
/usr/local/sbin/amavisd[24134]: (24134-01) prolong_timer SMTP MAIL
received: timer set to = 300 s
ESMTP< RCPT TO:<[EMAIL PROTECTED]>\r\n
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24134]:
(24134-01) check_mail_begin_task: task_count=1
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) prolong_timer SMTP RCPT received: timer set to = 300 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24134]:
(24134-01) TempDir::prepare: creating directory
/var/amavis/tmp/amavis-20060410T150216-24134Apr 10 15:02:16
mymachine.mydomain /usr/local/sbin/amavisd[24132]: (24132-01-2) ESMTP>
250 2.1.5 Recipient [EMAIL PROTECTED] OK
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) prolong_timer smtp response sent: timer set to = 120 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) idle_proc, 6: was busy, 5.6 ms, total idle 0.032 s, busy
2.397 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) idle_proc, 5: was idle, 0.3 ms, total idle 0.032 s, busy
2.397 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) ESMTP< DATA\r\n
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) prolong_timer SMTP DATA received: timer set to = 300 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) ESMTP::10024 /var/amavis/tmp/amavis-20060410T150214-24132:
<[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>
SIZE=5824 Received: from mymachine.mydomain ([127.0.0.1]) by localhost
(mymachine.mydomain [127.0.0.1]) (amavisd-new, port 10024) with ESMTP
for <[EMAIL PROTECTED]>; Mon, 10 Apr 2006 15:02:16 -0400 (EDT)
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) prolong_timer smtp response sent: timer set to = 120 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) prolong_timer receiving data: timer set to = 120 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) prolong_timer data-end received: timer set to = 300 s
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) ESMTP< .<CR><LF>
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) Actual message size 5816 B, declared 5824 B
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) setting body type: 7BIT (h=0, b=0)
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) body hash: 428d1d892d001710d376248b4a449d6b
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24132]:
(24132-01-2) Original mail size: 5816; quota set to: 2908000 bytes
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[22583]:
Net::Server: Starting "1" children
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24141]:
Net::Server: Child Preforked (24141)
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[24141]:
TIMING [total 15 ms] - bdb-open: 15 (99%)99, rundown: 0 (1%)100
Apr 10 15:02:16 mymachine.mydomain /usr/local/sbin/amavisd[22583]:
Net::Server: Starting "1" children
Apr 10 15:02:17 mymachine.mydomain /usr/local/sbin/amavisd[24142]:
Net::Server: Child Preforked (24142)
Apr 10 15:02:17 mymachine.mydomain /usr/local/sbin/amavisd[24142]:
TIMING [total 18 ms] - bdb-open: 18 (99%)99, rundown: 0 (1%)100
-------------------------------------------------------
This SF.Net email is sponsored by xPML, a groundbreaking scripting language
that extends applications into web and mobile media. Attend the live webcast
and join the prime developer group breaking into this new coding territory!
http://sel.as-us.falkag.net/sel?cmd=lnk&kid=110944&bid=241720&dat=121642
_______________________________________________
AMaViS-user mailing list
AMaViS-user@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/amavis-user
AMaViS-FAQ:http://www.amavis.org/amavis-faq.php3
AMaViS-HowTos:http://www.amavis.org/howto/