Hi,
Here's from a loglevel of 5
RHAS 3 == Redhat Advanced Server
amavisd-new == 2.3.2 (rpm from dag weeirs)
Thanks,
Harry
Sep 7 17:27:57 pm1 postfix/smtpd[5414]: connect from
some.host.com[10.0.0.1]
Sep 7 17:28:01 pm1 postfix/smtpd[5414]: 62193225D82:
client=some.host.com[10.0.0.1], sasl_method=PLAIN, sasl_username=hh52
Sep 7 17:28:01 pm1 postfix/cleanup[5416]: 62193225D82:
message-id=<[EMAIL PROTECTED]>
Sep 7 17:28:01 pm1 postfix/qmgr[3326]: 62193225D82:
from=<[EMAIL PROTECTED]>, size=783, nrcpt=1 (queue active)
Sep 7 17:28:01 pm1 amavis[5403]: loaded base policy bank
Sep 7 17:28:01 pm1 amavis[5403]: lookup_ip_acl: key="127.0.0.1" matches
"127.0.0.1", result=1
Sep 7 17:28:01 pm1 amavis[5403]: prolong_timer after new request -
timer reset: remaining time = 480 s
Sep 7 17:28:01 pm1 amavis[5403]: process_request: suggested_protocol=""
on TCP
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) SMTP> 220 [127.0.0.1] ESMTP
amavisd-new service ready
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) idle_proc, 4: was busy, 5.6
ms, total idle 0.000 s, busy 0.006 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) idle_proc, 5: was idle, 0.2
ms, total idle 0.000 s, busy 0.006 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) prolong_timer after reading
SMTP command: remaining time = 480 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) SMTP< EHLO
smtp.mail.some.com\r\nSep 7 17:28:01 pm1 amavis[5403]: (05403-01)
ESMTP> 250-[127.0.0.1]
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP> 250-PIPELINING
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP> 250-SIZE
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP> 250-8BITMIME
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP> 250-ENHANCEDSTATUSCODES
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP> 250 XFORWARD NAME
ADDR PROTO HELO
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) idle_proc, 6: was busy, 1.2
ms, total idle 0.000 s, busy 0.007 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) idle_proc, 5: was idle, 0.2
ms, total idle 0.000 s, busy 0.007 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) prolong_timer after reading
SMTP command: remaining time = 480 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP< MAIL
FROM:<[EMAIL PROTECTED]> SIZE=783\r\n
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) prolong_timer after MAIL
FROM received - timer reset: remaining time = 480 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) check_mail_begin_task:
task_count=1Sep 7 17:28:01 pm1 amavis[5403]: (05403-01)
prepare_tempdir: creating directory
/var/amavis/tmp/amavis-20050907T172801-05403
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) prepare_tempdir: creating
file /var/amavis/tmp/amavis-20050907T172801-05403/email.txt
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) lookup (debug_sender) =>
undef, "[EMAIL PROTECTED]" does not match
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP> 250 2.1.0 Sender
[EMAIL PROTECTED] OK
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) idle_proc, 6: was busy, 2.6
ms, total idle 0.000 s, busy 0.009 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) idle_proc, 5: was idle, 0.1
ms, total idle 0.001 s, busy 0.009 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) prolong_timer after reading
SMTP command: remaining time = 480 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP< RCPT
TO:<[EMAIL PROTECTED]>\r\n
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) lookup_acl([EMAIL PROTECTED])
matches key ".some.com", result=1
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) lookup (local_domains) =>
true, "[EMAIL PROTECTED]" matches, result="1", matching_key=".some.com"
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) query_keys: [EMAIL PROTECTED],
hh52, @some.com, @.some.com, @.edu, @.
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) TROUBLE in process_request:
Undefined subroutine &Net::LDAP::Util::escape_filter_value called at
(eval 41) line 362, <GEN4> line 3.
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) Requesting process rundown
after fatal error
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) post_process_request_hook:
timer stopped
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) idle_proc, bye: was busy,
3.7 ms, total idle 0.001 s, busy 0.013 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) load: 96 %, total idle
0.001 s, busy 0.013 s
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) child_finish_hook: invoking
DESTROY methods
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) Amavis::In::SMTP::DESTROY
called
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) SMTP shutdown: empty
tempdir is being removed: /var/amavis/tmp/amavis-20050907T172801-05403,
nothing to preserve
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) rmdir_recursively:
/var/amavis/tmp/amavis-20050907T172801-05403, excl=
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) ESMTP> 421 4.3.2 Service
shutting down, closing channel
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) SMTP shutdown: Error
writing a SMTP response to the socket: Bad file descriptor at (eval 42)
line 799.\n
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) Amavis::Out::SQL::Log
DESTROY called
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01)
Amavis::Out::SQL::Connection DESTROY called
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) Amavis::Cache called
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) Amavis::DB::SNMP called
Sep 7 17:28:01 pm1 postfix/smtp[5417]: 62193225D82: to=<[EMAIL PROTECTED]>,
relay=127.0.0.1[127.0.0.1], delay=0, status=deferred (lost connection
with 127.0.0.1[127.0.0.1] while sending RCPT TO)
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) Amavis::LDAP::Connection
DESTROY called
Sep 7 17:28:01 pm1 amavis[5403]: (05403-01) Amavis::Lookup::LDAP
DESTROY calledSep 7 17:28:01 pm1 amavis[5420]: TIMING [total 5 ms] -
bdb-open: 5 (100%)100, rundown: 0 (0%)100
Sep 7 17:28:01 pm1 postfix/smtpd[5414]: disconnect from
some.host.com[10.0.0.1]
Michael Hall wrote:
On Wed, Sep 07, 2005 at 04:11:08PM -0400, Harry Hoffman wrote:
...
the relevant parts of amavisd.conf look like:
$enable_db = 1;
$sa_kill_level_deflt = 999;
$spam_quarantine_method = 'sql:amavis';
$bad_header_quarantine_method = 'sql:amavis';
$enable_ldap = 1;
$default_ldap = {
hostname => [ 'ldap.my.com' ],
timeout => 5,
tls => 0,
base => 'o=MyCom',
query_filter => '(|(mail=%m)(mailAlternateAddress=%m))',
};
@storage_sql_dsn =
( ['DBI:mysql:database=amavis;host=127.0.0.1;port=3306', 'user',
'pass'] );
...
Could you provide the complete logging for a message with $log_level set to
'5', there must be some sort of LDAP related error before this.
Also what version of amavisd-new are you running? What OS?
-------------------------------------------------------
SF.Net email is Sponsored by the Better Software Conference & EXPO
September 19-22, 2005 * San Francisco, CA * Development Lifecycle Practices
Agile & Plan-Driven Development * Managing Projects & Teams * Testing & QA
Security * Process Improvement & Measurement * http://www.sqe.com/bsce5sf
_______________________________________________
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/
