Solaris 9
Amavisd-New 2.3.3
Postfix 2.2.5
Can someone help me figure this out? I return from holiday on Monday and
my Amavisd is dead. I've been trying to sort this out all week by
downloading the latest Perl modules and upgrading to the latest
Amavisd-New but its always the same. Below are the relevant sections
from my Postfix and Amavisd-New logs.
Thanks to any and all who may be of assistance.
Cheers,
Gordon Thagard
------------------------------------------------------ cut here
------------------------------------------------------
# tn localhost 10024
Trying 127.0.0.1...
Connected to localhost.
Escape character is '^]'.
220 [127.0.0.1] ESMTP amavisd-new service ready
quit
221 2.0.0 [127.0.0.1] amavisd-new closing transmission channel
Connection to localhost closed by foreign host.
# tn localhost 10025
Trying 127.0.0.1...
Connected to localhost.
Escape character is '^]'.
220 horus.eng.fsu.edu ESMTP FAMU-FSU College of Engineering
quit
221 Bye
Connection to localhost closed by foreign host.
POSTFIX LOGS
Dec 1 12:42:44 horus postfix/smtp[19180]: [ID 197553 mail.info]
0320620C63: to=<[EMAIL PROTECTED]>, relay=127.0.0.1[127.0.0.1], delay=1,
status=deferred (lost connection with 127.0.0.1[127.0.0.1] while sending
end of data -- message may be sent more than once)
Dec 1 12:42:44 horus postfix/smtp[19180]: [ID 197553 mail.info]
0320620C63: to=<[EMAIL PROTECTED]>, relay=127.0.0.1[127.0.0.1],
delay=1, status=deferred (lost connection with 127.0.0.1[127.0.0.1]
while sending end of data -- message may be sent more than once)
AMAVISD LOGS
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17892]:
starting. /usr/local/sbin/amavisd at horus.eng.fsu.edu
amavisd-new-2.3.3 (20050822)
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17892]:
user=amavis, EUID: 11369 (11369); group=amavis, EGID: 184 184 (184 184)
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17892]: Perl
version 5.006001
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17892]: INFO:
no optional modules: DBD::mysql Sys::Hostname::Long Mail::SPF::Query
Net::CIDR::Lite
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Net::Server: Process Backgrounded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Net::Server: 2005/12/01-12:38:00 Amavis (type
Net::Server::PreForkSimple) starting! pid(17916)
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Net::Server: Binding to UNIX socket file /var/amavis/amavisd.sock using
SOCK_STREAM
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Net::Server: Group Not Defined. Defaulting to EGID '184 184'
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Net::Server: User Not Defined. Defaulting to EUID '11369'
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Amavis::Conf 2.043
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Archive::Tar 1.26
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Archive::Zip 1.16
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
BerkeleyDB 0.27
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Compress::Zlib 1.41
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Convert::TNEF 0.17
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Convert::UUlib 1.051
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
DBI 1.47
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
DB_File 1.811
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
MIME::Entity 5.417
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
MIME::Parser 5.417
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
MIME::Tools 5.417
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Mail::Header 1.66
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Mail::Internet 1.66
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Mail::SpamAssassin 3.000002
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Net::Cmd 2.26
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Net::DNS 0.48
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Net::SMTP 2.29
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Net::Server 0.89
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Razor2::Client::Version 2.67
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Time::HiRes 1.66
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Module
Unix::Syslog 0.100
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Amavis::DB code loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Amavis::Cache code loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: SQL
base code NOT loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
SQL::Log code NOT loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
SQL::Quarantine NOT loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Lookup::SQL code NOT loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Lookup::LDAP code NOT loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: AM.PDP
prot code loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
SMTP-in prot code loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
ANTI-VIRUS code loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
ANTI-SPAM code loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Unpackers code loaded
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: Found
$file at /usr/bin/file
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]: No
$dspam, not using it
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Internal decoder for .mail
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Internal decoder for .asc
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Internal decoder for .uue
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
Internal decoder for .hqx
[EMAIL PROTECTED] amavis]# tail -f amavis.log
Dec 1 12:38:00 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
SpamControl: initializing Mail::SpamAssassin
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17916]:
SpamControl: done
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17924]: TIMING
[total 28 ms] - bdb-open: 28 (100%)100, rundown: 0 (0%)100
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17925]: TIMING
[total 20 ms] - bdb-open: 20 (100%)100, rundown: 0 (0%)100
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17927]: TIMING
[total 20 ms] - bdb-open: 20 (100%)100, rundown: 0 (0%)100
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17928]: TIMING
[total 20 ms] - bdb-open: 20 (100%)100, rundown: 0 (0%)100
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]: TIMING
[total 25 ms] - bdb-open: 25 (100%)100, rundown: 0 (0%)100
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]: TIMING
[total 18 ms] - bdb-open: 18 (100%)100, rundown: 0 (0%)100
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17931]: TIMING
[total 19 ms] - bdb-open: 18 (100%)100, rundown: 0 (0%)100
Dec 1 12:38:02 horus.eng.fsu.edu /usr/local/sbin/amavisd[17932]: TIMING
[total 18 ms] - bdb-open: 18 (100%)100, rundown: 0 (0%)100
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]: loaded
base policy bank
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
prolong_timer after new request - timer reset: remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
process_request: suggested_protocol="" on TCP
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 4: was busy, 17.2 ms, total idle 0.000 s, busy
0.017 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 5: was idle, 0.7 ms, total idle 0.001 s, busy 0.017 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after reading SMTP command: remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) SMTP< EHLO horus.eng.fsu.edu\r\n
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250-[127.0.0.1]
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250-PIPELINING
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250-SIZE
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250-8BITMIME
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250-ENHANCEDSTATUSCODES
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 6: was busy, 3.0 ms, total idle 0.001 s, busy 0.020 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.001 s, busy 0.020 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after reading SMTP command: remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP< XFORWARD NAME=localhost ADDR=127.0.0.1\r\n
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250 2.5.0 Ok XFORWARD
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 6: was busy, 1.3 ms, total idle 0.001 s, busy 0.022 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.001 s, busy 0.022 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after reading SMTP command: remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP< XFORWARD PROTO=ESMTP HELO=webmail.eng.fsu.edu\r\n
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250 2.5.0 Ok XFORWARD
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 6: was busy, 1.1 ms, total idle 0.001 s, busy 0.023 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.001 s, busy 0.023 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after reading SMTP command: remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP< MAIL FROM:<[EMAIL PROTECTED]> SIZE=75846 BODY=8BITMIME\r\n
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after MAIL FROM received - timer reset:
remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) check_mail_begin_task: task_count=1
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prepare_tempdir: creating directory
/var/amavis/tmp/amavis-20051201T124244-17929Dec 1 12:42:44
horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]: (17929-01)
prepare_tempdir: creating file
/var/amavis/tmp/amavis-20051201T124244-17929/email.txt
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) lookup (debug_sender) => undef, "[EMAIL PROTECTED]" does not
match
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250 2.1.0 Sender [EMAIL PROTECTED] OK
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 6: was busy, 46.5 ms, total idle 0.001 s, busy
0.069 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.002 s, busy 0.069 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after reading SMTP command: remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP< RCPT TO:<[EMAIL PROTECTED]>\r\n
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250 2.1.5 Recipient [EMAIL PROTECTED] OK
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 6: was busy, 1.6 ms, total idle 0.002 s, busy 0.071 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.002 s, busy 0.071 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after reading SMTP command: remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP< RCPT TO:<[EMAIL PROTECTED]>\r\n
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 250 2.1.5 Recipient [EMAIL PROTECTED] OK
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 6: was busy, 1.1 ms, total idle 0.002 s, busy 0.072 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.002 s, busy 0.072 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after reading SMTP command: remaining time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP< DATA\r\n
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) prolong_timer after DATA received - timer reset: remaining
time = 300 s
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP::10024 /var/amavis/tmp/amavis-20051201T124244-17929:
<[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>,<[EMAIL PROTECTED]>
Received: SIZE=75846 BODY=8BITMIME from horus.eng.fsu.edu ([127.0.0.1])
by localhost (horus.eng.fsu.edu [127.0.0.1]) (amavisd-new, port 10024)
with ESMTP id 17929-01; Thu, 1 Dec 2005 12:42:44 -0500 (EST)
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[17929]:
(17929-01) ESMTP< .\r\n
Dec 1 12:42:44 horus.eng.fsu.edu /usr/local/sbin/amavisd[19301]: TIMING
[total 18 ms] - bdb-open: 18 (100%)100, rundown: 0 (0%)100
Dec 1 12:43:37 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]: loaded
base policy bank
Dec 1 12:43:37 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]:
lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1
Dec 1 12:43:37 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]:
prolong_timer after new request - timer reset: remaining time = 300 s
Dec 1 12:43:37 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]:
process_request: suggested_protocol="" on TCP
Dec 1 12:43:37 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]:
(17930-01) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready
Dec 1 12:43:37 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]:
(17930-01) idle_proc, 4: was busy, 16.6 ms, total idle 0.000 s, busy
0.017 s
Dec 1 12:43:37 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]:
(17930-01) idle_proc, 5: was idle, 0.6 ms, total idle 0.001 s, busy 0.017 s
Dec 1 12:43:37 horus.eng.fsu.edu /usr/local/sbin/amavisd[17930]:
(17930-01) prolong_timer after reading SMTP command: remaining time = 300 s
-------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc. Do you grep through log files
for problems? Stop! Download the new AJAX search engine that makes
searching your log files as easy as surfing the web. DOWNLOAD SPLUNK!
http://ads.osdn.com/?ad_id=7637&alloc_id=16865&op=click
_______________________________________________
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/
