All,
I have been trying for 3 days to enable a notification for virus senders
on our out going SMTP servers. I want local LDAP users to receive a
notification if a virus is detected in their outgoing mail. I do not
want to scan for spam (although I wish I could). I would also like to
quarantine the virus.
This all sounds pretty simple to me. I added the following lines. But
currently viruses are quarantined, but the user notification is not
sent.
Current setup;
SunOS test-smtp01.unix 5.10 Generic i86pc i386 i86pc
postfix 2.5.1
amavisd-new-2.5.3 (20071212)
openldap-2.3.19
<conf>
$forward_method = 'smtp:[127.0.0.1]:10025';
$hdr_encoding = 'ISO-2022-JP';
$bdy_encoding = 'ISO-2022-JP';
$notify_virus_sender_templ=
read_text("$MYHOME/notify_virus_recipSJIS.txt", 'iso-2022-jp');
$notify_sender_templ= read_text("$MYHOME/notify_virus_recipSJIS.txt",
'iso-2022-jp');
$warnvirussender = 0;
$mailfrom_notify_sender = "[EMAIL PROTECTED]";
$mailfrom_notify_sender = ''; # null reverse path, like in MTA
notifications
$notify_method = 'smtp:[127.0.0.1]:10025';
</conf> [1]
I am running amavis with the -d 10 option to get the most out of the
logs. Try not to hate my verbosity. The most interesting line I can
find is this
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) dsn: . 554 Virus <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>:
on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=,
mta_resp: "554 5.7.0 Reject, id=29463-01 - VIRUS: Eicar-Test-Signature"
The "warn_sender=" has no I have tried changing the 0 to a 1 on the
$warnvirussender = 0; line with the same results.
I have included the rest of the log in case I missed something. Thanks
for your help in advance.
Regards,
Erin
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, smtp response sent
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 6: was busy, 3.6 ms, total idle 0.001 s, busy 0.024 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 5: was idle, 0.4 ms, total idle 0.001 s, busy 0.024 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP< XFORWARD NAME=host-30-131.internal-gmo
ADDR=192.168.30.131\r
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_my_time 480 s, SMTP XFORWARD received
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP> 250 2.5.0 Ok XFORWARD
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, smtp response sent
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 6: was busy, 2.2 ms, total idle 0.001 s, busy 0.026 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.001 s, busy 0.026 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP< XFORWARD PROTO=ESMTP HELO=host-30-131.internal-gmo\r
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_my_time 480 s, SMTP XFORWARD received
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP> 250 2.5.0 Ok XFORWARD
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, smtp response sent
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 6: was busy, 1.4 ms, total idle 0.001 s, busy 0.027 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.001 s, busy 0.027 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP< MAIL FROM:<[EMAIL PROTECTED]> SIZE=1061\r
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_my_time 480 s, SMTP MAIL received
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) check_mail_begin_task: task_count=1
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) TempDir::prepare: creating directory
/var/amavis/tmp/amavis-20080415T175045-29463
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) TempDir::prepare_file: creating file
/var/amavis/tmp/amavis-20080415T175045-29463/email.txt
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) TempDir::prepare_file: layers: stdio
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ip_acl (mynetworks): key="192.168.30.131" matches
"192.168.0.0/16", result=1
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (debug_sender) => undef, "[EMAIL PROTECTED]" does not
match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP> 250 2.1.0 Sender <[EMAIL PROTECTED]> OK
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, smtp response sent
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 6: was busy, 9.5 ms, total idle 0.001 s, busy 0.037 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.002 s, busy 0.037 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP< RCPT TO:<[EMAIL PROTECTED]>
ORCPT=rfc822;[EMAIL PROTECTED]
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_my_time 480 s, SMTP RCPT received
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_acl([EMAIL PROTECTED]) matches key ".", result=1
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (local_domains) => true, "[EMAIL PROTECTED]" matches,
result="1", matching_key="."
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin, @blue.zero.jp,
@.blue.zero.jp, @.zero.jp, @.jp, @.
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap "[EMAIL PROTECTED]", query keys:
"[EMAIL PROTECTED]", "erin", "@blue.zero.jp", "@.blue.zero.jp",
"@.zero.jp", "@.jp", "@.", base: ou=mail,dc=gmo,dc=jp, filter:
(&(objectClass=amavisAccount)(|(mail=%m)(mailAlternateAddress=%m)))
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ldap begin_work
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Connecting to LDAP server
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) connect_to_ldap: trying 172.20.12.23, 172.20.12.113
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) connect_to_ldap: connected to 172.20.12.23, 172.20.12.113
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap: searching base="ou=mail,dc=gmo,dc=jp",
scope="sub",
filter="(&(objectClass=amavisAccount)(|(|([EMAIL PROTECTED])(mail=erin)([EMAIL
PROTECTED])([EMAIL PROTECTED])([EMAIL PROTECTED])([EMAIL PROTECTED])([EMAIL
PROTECTED]))(|([EMAIL PROTECTED])(mailAlternateAddress=erin)([EMAIL
PROTECTED])([EMAIL PROTECTED])([EMAIL PROTECTED])([EMAIL PROTECTED])([EMAIL
PROTECTED]))))"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap, "[EMAIL PROTECTED]" no match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavismessagesizelimit), "[EMAIL PROTECTED]"
no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (message_size_limit) => undef, "[EMAIL PROTECTED]"
does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP> 250 2.1.5 Recipient <[EMAIL PROTECTED]> OK
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, smtp response sent
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 6: was busy, 24.5 ms, total idle 0.002 s, busy 0.061 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.002 s, busy 0.061 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP< DATA\r
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_my_time 480 s, SMTP DATA received
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) ESMTP::10024 /var/amavis/tmp/amavis-20080415T175045-29463:
<[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]> SIZE=1061 Received: from
smtp.zero.jp ([127.0.0.1]) by localhost (smtp.zero.jp [127.0.0.1])
(amavisd-new, port 10024) with ESMTP for <[EMAIL PROTECTED]>; Tue, 15
Apr 2008 17:50:45 +0900 (JST)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, smtp response sent
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, receiving data
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_my_time 480 s, data-end received
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP< .<CR><LF>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavislocal), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_acl([EMAIL PROTECTED]) matches key ".", result=1
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (local_domains) => true, "[EMAIL PROTECTED]" matches,
result="1", matching_key="."
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) body type: unlabeled, good (h=0, b=0)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) body hash: 67654c858cf85413de60a1b0b25abc97
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Original mail size: 1061; quota set to: 530500 bytes
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) Checking: KHW7vYg4ss92 [192.168.30.131] <[EMAIL PROTECTED]>
-> <[EMAIL PROTECTED]>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) 2822.From: <[EMAIL PROTECTED]>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisbypassviruschecks),
"[EMAIL PROTECTED]" no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin@, blue.zero.jp,
.blue.zero.jp, .zero.jp, .jp, .
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_hash([EMAIL PROTECTED]), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (bypass_virus_checks) => undef, "[EMAIL PROTECTED]"
does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Extracting mime components
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Issued a new file name: p001
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Issued a new file name: p002
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) mime_decode_preamble: 1 lines
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Issued a new pseudo part: p003
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) p003 1 Content-Type: multipart/mixed
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Charging 5 bytes to remaining quota 530500 (out of 530500,
(0%)) - by mime_decode
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) p001 1/1 Content-Type: text/plain, size: 5 B, name:
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) reparenting p001 from p000 to p003
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Charging 68 bytes to remaining quota 530495 (out of 530500,
(0%)) - by mime_decode
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) p002 1/2 Content-Type: application/octet-stream, size: 68 B,
name: eicar(2).com
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) reparenting p002 from p000 to p003
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer mime_decode: remaining time = 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer mime_decode-1: remaining time = 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) decode_parts: level=1, #parts=3 : p001, p002, p003
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) run_command: [2764] /usr/local/bin/file p001 p002 </dev/null 2>&1
Apr 15 17:50:45 test-smtp01.unix amavis[2764]: [ID 702911 mail.debug]
(29463-01) open_on_specific_fd: target fd0 closing, to become < /dev/null
Apr 15 17:50:45 test-smtp01.unix amavis[2764]: [ID 702911 mail.debug]
(29463-01) open_on_specific_fd: target fd2 closing, to become > &1
Apr 15 17:50:45 test-smtp01.unix amavis[2764]: [ID 702911 mail.debug]
(29463-01) open_on_specific_fd: target fd2 dup2 from fd1 > &1
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) result line from file(1): p001: ASCII text
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_re("ASCII text") matches key
"(?i-xsm:^(ASCII|text)\b)", result="asc"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (map_full_type_to_short_type) => true, "ASCII text"
matches, result="asc", matching_key="(?i-xsm:^(ASCII|text)\\b)"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) File-type of p001: ASCII text; (asc)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) result line from file(1): p002: ASCII text, with no line
terminators
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_re("ASCII text, with no line terminators") matches key
"(?i-xsm:^(ASCII|text)\b)", result="asc"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (map_full_type_to_short_type) => true, "ASCII text,
with no line terminators" matches, result="asc",
matching_key="(?i-xsm:^(ASCII|text)\\b)"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) File-type of p002: ASCII text, with no line terminators; (asc)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) do_ascii: Decoding part p001
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) do_ascii: Setting sigaction handler, was 0
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) timer set to 320 s (was 480 s)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) do_ascii: Decoding part p001 (0 items), uulib V0.5pl20
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer do_ascii: timer set to 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) decompose_part: p001 - atomic
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) do_ascii: Decoding part p002
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) do_ascii: Setting sigaction handler, was 0
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) timer set to 320 s (was 480 s)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) do_ascii: Decoding part p002 (0 items), uulib V0.5pl20
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer do_ascii: timer set to 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) decompose_part: p002 - atomic
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer parts_decode: remaining time = 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisbypassheaderchecks),
"[EMAIL PROTECTED]" no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin@, blue.zero.jp,
.blue.zero.jp, .zero.jp, .jp, .
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_hash([EMAIL PROTECTED]), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (bypass_header_checks) => undef, "[EMAIL PROTECTED]"
does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) check_header: 0, OK
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisbypassheaderchecks),
"[EMAIL PROTECTED]" no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin@, blue.zero.jp,
.blue.zero.jp, .zero.jp, .jp, .
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_hash([EMAIL PROTECTED]), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (bypass_header_checks) => undef, "[EMAIL PROTECTED]"
does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Checking for banned types and filenames
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisbypassbannedchecks),
"[EMAIL PROTECTED]" no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin@, blue.zero.jp,
.blue.zero.jp, .zero.jp, .jp, .
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_hash([EMAIL PROTECTED]), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (bypass_banned_checks) => undef, "[EMAIL PROTECTED]"
does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisbannedrulenames), "[EMAIL PROTECTED]"
no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup: (scalar) matches, result="DEFAULT"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (banned_filename), 1 matches for "[EMAIL PROTECTED]",
results: "(constant:DEFAULT)"=>"DEFAULT"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) collect banned table[0]: [EMAIL PROTECTED], tables:
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) skipping banned check: no applicable lookup tables
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) banned check: any=0, all=N (1)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (keep_decoded_original) => undef, "MAIL" does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Using ClamAV-clamd: (built-in interface)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) timer set to 320 s (was 480 s)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ask_av (ClamAV-clamd): query template1: CONTSCAN {}
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) Using (ClamAV-clamd) on dir: CONTSCAN
/var/amavis/tmp/amavis-20080415T175045-29463/parts
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) timer set to 10 s (was 320 s)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ClamAV-clamd: Connecting to socket /tmp/clamd
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ClamAV-clamd: Sending CONTSCAN
/var/amavis/tmp/amavis-20080415T175045-29463/parts\n to UNIX socket
/tmp/clamd
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer ask_daemon_internal: timer set to 256 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ask_av (ClamAV-clamd) result:
/var/amavis/tmp/amavis-20080415T175045-29463/parts/p002:
Eicar-Test-Signature FOUND
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) ask_av (ClamAV-clamd):
/var/amavis/tmp/amavis-20080415T175045-29463/parts INFECTED:
Eicar-Test-Signature
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer run_av: timer set to 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_re("Eicar-Test-Signature"), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (virus_name_to_spam_score) => undef,
"Eicar-Test-Signature" does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) virus_scan: (Eicar-Test-Signature), detected by 1 scanners:
ClamAV-clamd
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisbypassviruschecks),
"[EMAIL PROTECTED]" no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin@, blue.zero.jp,
.blue.zero.jp, .zero.jp, .jp, .
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_hash([EMAIL PROTECTED]), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (bypass_virus_checks) => undef, "[EMAIL PROTECTED]"
does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup: (scalar) matches, result="1"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (viruses_that_fake_sender) => true,
"Eicar-Test-Signature" matches, result="1", matching_key="(constant:1)"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) Virus Eicar-Test-Signature matches (constant:1), sender addr
ignored
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) fish_out_ip_from_received: 192.168.30.131
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ip_acl (publicnetworks): key="192.168.30.131" matches
"!192.168.0.0/16", result=0
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) best_try_originator_ip: 192.168.30.131
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ip_addr_to_name: DNS reverse-resolving: 192.168.30.131
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ip_addr_to_name: returning: [192.168.30.131]
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) infected contents, skipping spam_scan
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) enqueue: stat is not numeric: ""
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) enqueue: deleting: bd07edfae126e78f8eeac892817b6e64
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamtaglevel), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_tag_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamtag2level), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_tag2_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_tag3_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamkilllevel), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_kill_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisbypassspamchecks), "[EMAIL PROTECTED]"
no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin@, blue.zero.jp,
.blue.zero.jp, .zero.jp, .jp, .
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_hash([EMAIL PROTECTED]), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (bypass_spam_checks) => undef, "[EMAIL PROTECTED]"
does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavismessagesizelimit), "[EMAIL PROTECTED]"
no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (message_size_limit) => undef, "[EMAIL PROTECTED]"
does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisviruslover), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin@, blue.zero.jp,
.blue.zero.jp, .zero.jp, .jp, .
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_hash([EMAIL PROTECTED]), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (virus_lovers) => undef, "[EMAIL PROTECTED]" does not
match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) blocking contents category is (9) for [EMAIL PROTECTED]
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) blocking ccat=9, SMTP response: 554 5.7.0 Reject, id=29463-01
- VIRUS: Eicar-Test-Signature
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) do_notify_and_quar: ccat=Virus (9,0) ("9":Virus,
"1,1":CleanTag, "1":Clean, "0":CatchAll) ccat_block=(9),
q_mth=local:virus-%m, qar_mth=
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamtaglevel), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_tag_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamtag2level), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_tag2_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamkilllevel), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_kill_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisvirusquarantineto),
"[EMAIL PROTECTED]" no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup: (scalar) matches, result="virus-quarantine"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (virus_quarantine_to) => true, "[EMAIL PROTECTED]"
matches, result="virus-quarantine",
matching_key="(constant:virus-quarantine)"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisvirusadmin), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) query_keys: [EMAIL PROTECTED], erin@, blue.zero.jp,
.blue.zero.jp, .zero.jp, .jp, .
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_hash([EMAIL PROTECTED]), no matches
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (virus_admin) => undef, "[EMAIL PROTECTED]" does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: X-Amavis-Alert: INFECTED, message contains virus:
Eicar-Test-Signature
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: X-Spam-Flag: NO
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: X-Spam-Score: 0
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: X-Spam-Level:
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: X-Spam-Status: No, score=x tag=x tag2=x kill=x tests=[]
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: X-Quarantine-ID: <KHW7vYg4ss92>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: X-Envelope-To: <[EMAIL PROTECTED]>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: X-Envelope-From: <[EMAIL PROTECTED]>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: Received: from smtp.zero.jp ([127.0.0.1])\n\tby
localhost (smtp.zero.jp [127.0.0.1]) (amavisd-new, port 10024)\n\twith
ESMTP id KHW7vYg4ss92 for <[EMAIL PROTECTED]>;\n\tTue, 15 Apr 2008
17:50:45 +0900 (JST)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) DO_QUARANTINE, sender: <[EMAIL PROTECTED]>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) local delivery: <[EMAIL PROTECTED]> -> <virus-quarantine>,
mbx=/var/quarantine/K/virus-KHW7vYg4ss92
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: Delivered-To: virus-quarantine
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) header: Return-Path: <[EMAIL PROTECTED]>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) write_header: 0, IO::File=GLOB(0xa731500)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) one_response_for_all <[EMAIL PROTECTED]>: success,
r=0,b=0,d=0, ndn_needed=0, '250 2.6.0 Ok, delivered to
/var/quarantine/K/virus-KHW7vYg4ss92, id=29463-01'
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) DO_QUARANTINE done
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) skip admin notification, no administrators
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amaviswarnvirusrecip), "[EMAIL PROTECTED]"
no matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (warnvirusrecip) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) do_notify_and_quarantine - done
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer aux_quarantine: remaining time = 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_dsn_cutoff_level_bysender) => undef,
"[EMAIL PROTECTED]" does not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) dsn: . 554 Virus <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>:
on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=,
mta_resp: "554 5.7.0 Reject, id=29463-01 - VIRUS: Eicar-Test-Signature"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) DSN: FILTER 554 Virus, <[EMAIL PROTECTED]> (faked?) ->
<[EMAIL PROTECTED]>
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) one_response_for_all <[EMAIL PROTECTED]>: mixed, r=0,b=1,d=0,
ndn_needed=1, '250 2.5.0 Ok, id=29463-01, DISCARD(bounce.suppressed)'
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) notif=N, suppressed=1, ndn_needed=1, exit=99, 250 2.5.0 Ok,
id=29463-01, DISCARD(bounce.suppressed)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer delivery-notification: remaining time = 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) fish_out_ip_from_received: 192.168.30.131
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ip_acl (publicnetworks): key="192.168.30.131" matches
"!192.168.0.0/16", result=0
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) best_try_originator_ip: 192.168.30.131
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.notice]
(29463-01) Blocked INFECTED (Eicar-Test-Signature), LOCAL
[192.168.30.131] [192.168.30.131] <[EMAIL PROTECTED]> ->
<[EMAIL PROTECTED]>, quarantine: K/virus-KHW7vYg4ss92, Message-ID:
<[EMAIL PROTECTED]>, mail_id: KHW7vYg4ss92, Hits: -, size:
1061, 250 ms
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamtaglevel), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_tag_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamtag2level), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_tag2_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup_ldap_attr(amavisspamkilllevel), "[EMAIL PROTECTED]" no
matching records
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) lookup (spam_kill_level) => undef, "[EMAIL PROTECTED]" does
not match
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.notice]
(29463-01) Blocked INFECTED (Eicar-Test-Signature), <[EMAIL PROTECTED]>
-> <[EMAIL PROTECTED]>, Hits: -, tag=0, tag2=0, kill=0, L/Y/0/0
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer main_log_entry: remaining time = 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) updating snmp variables
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) prolong_timer check done: remaining time = 480 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) sending SMTP response: "250 2.5.0 Ok, id=29463-01,
DISCARD(bounce.suppressed)"
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP> 250 2.5.0 Ok, id=29463-01, DISCARD(bounce.suppressed)
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, smtp response sent
Apr 15 17:50:45 test-smtp01.unix postfix/smtp[2763]: [ID 197553
mail.info] 1C03580EA2: to=<[EMAIL PROTECTED]>,
relay=127.0.0.1[127.0.0.1]:10024, delay=0.32,
delays=0.03/0.02/0.03/0.24, dsn=2.5.0, status=sent (250 2.5.0 Ok,
id=29463-01, DISCARD(bounce.suppressed))
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) TempDir::strip: /var/amavis/tmp/amavis-20080415T175045-29463
Apr 15 17:50:45 test-smtp01.unix postfix/qmgr[1834]: [ID 197553
mail.info] 1C03580EA2: removed
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) rmdir_recursively:
/var/amavis/tmp/amavis-20080415T175045-29463/parts, excl=1
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.info]
(29463-01) TIMING [total 265 ms] - ldap-prepare: 12 (5%)5, SMTP
greeting: 8 (3%)8, SMTP EHLO: 4 (2%)9, SMTP pre-MAIL: 5 (2%)11, mkdir
tempdir: 2 (1%)12, create email.txt: 2 (1%)13, ldap-connect: 16 (6%)19,
lookup_ldap: 12 (5%)23, SMTP pre-DATA-flush: 5 (2%)25, SMTP DATA: 2
(1%)26, check_init: 3 (1%)27, digest_hdr: 1 (0%)27, digest_body: 1
(0%)28, gen_mail_id: 3 (1%)29, mkdir parts: 1 (1%)29, mime_decode: 40
(15%)45, get-file-type2: 41 (15%)60, decompose_part: 6 (2%)62,
decompose_part: 2 (1%)63, parts_decode: 0 (0%)63, check_header: 6
(2%)65, AV-scan-1: 12 (5%)70, read_snmp_variables: 2 (1%)71,
best_try_originator: 11 (4%)75, update_cache: 4 (1%)77,
decide_mail_destiny: 8 (3%)79, open-mbx: 17 (6%)86, write-header: 2
(1%)87, save-to-local-mailbox: 0 (0%)87, prepare-dsn: 6 (2%)89,
main_log_entry: 20 (8%)97, update_snmp: 3 (1%)98, SMTP pre-response: 1
(0%)98, SMTP response: 1 (0%)99, unlink-2-files: 2 (1%)99, rundown: 2
(1%)100
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 6: was busy, 202.9 ms, total idle 0.002 s, busy
0.264 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.002 s, busy 0.264 s
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP< QUIT\r
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_my_time 480 s, SMTP QUIT received
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-new closing transmission
channel
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) switch_to_client_time 480 s, smtp response sent
Apr 15 17:50:45 test-smtp01.unix amavis[29463]: [ID 702911 mail.debug]
(29463-01) SMTP session over, timer stopped
postfix 2.5.1
amavisd-new-2.5.3 (20071212)
openldap-2.3.19
-------------------------------------------------------------------------
This SF.net email is sponsored by the 2008 JavaOne(SM) Conference
Don't miss this year's exciting event. There's still time to save $100.
Use priority code J8TL2D2.
http://ad.doubleclick.net/clk;198757673;13503038;p?http://java.sun.com/javaone
_______________________________________________
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/
