Hi
I'm building a new machine to replace an aged amavis installation.
So far the new machine is in "sending only" configuration (it does no
receive mail yet).
I've run successfully v2.6.3 with the $banned_filename_re set to:
$banned_filename_re = new_RE(
### BLOCKED ANYWHERE
# qr'^UNDECIPHERABLE$', # is or contains any undecipherable components
qr'^\.(exe-ms|dll)$', # banned file(1) types, rudimentary
# qr'^\.(exe|lha|tnef|cab|dll)$', # banned file(1) types
### BLOCK THE FOLLOWING, EXCEPT WITHIN UNIX ARCHIVES:
# [ qr'^\.(gz|bz2)$' => 0 ], # allow any in gzip or bzip2
# [ qr'^\.(rpm|cpio|tar)$' => 0 ], # allow any in Unix-type archives
qr'.\.(pif|scr)$'i, # banned extensions - rudimentar
# [...] Everything else is commented out
);
$banned_namepath_re = undef;
This configuration allowed me to block executables within archives.
Upgrading to v2.6.4, archives with executables files in them (.exe)
are no longer blocked, and I dunno why.
I've tried to use also banned_namepath_re instead of
banned_filename_re with no luck.
I've read the release notes, but I could not find any hint as why it happens.
Any idea?
Thanks
Leonardo
(A full debug session follows)
Aug 24 12:13:25 smtp postfix/smtpd[20557]: connect from
df1844jvm01.nospam[10.44.9.210]
Aug 24 12:13:25 smtp postfix/smtpd[20557]: A912C6E1B9:
client=df1844jvm01.nospam[10.44.9.210]
Aug 24 12:13:25 smtp postfix/cleanup[20560]: A912C6E1B9:
message-id=<!&!AAAAAAAAAAAYAAAAAAAAACQnewdMPIxOpxx+/OD+wwbCgAAAEAAAAA69oq9tb/vikmld41wmnmmbaaaaa...@nospam>
Aug 24 12:13:25 smtp postfix/qmgr[3980]: A912C6E1B9:
from=<j...@nospam>, size=11986, nrcpt=1 (queue active)
Aug 24 12:13:25 smtp amavis[20479]: Net::Server: 2009/08/24-12:13:25
CONNECT TCP Peer: "127.0.0.1:44818" Local: "127.0.0.1:10024"
Aug 24 12:13:25 smtp amavis[20479]: loaded base policy bank
Aug 24 12:13:25 smtp amavis[20479]: lookup_ip_acl (inet_acl):
key="127.0.0.1" matches "127.0.0.1", result=1
Aug 24 12:13:25 smtp amavis[20479]: process_request: fileno sock=3,
STDIN=0, STDOUT=1
Aug 24 12:13:25 smtp amavis[20479]: switch_to_my_time 480 s, new request
Aug 24 12:13:25 smtp amavis[20479]: process_request:
suggested_protocol="" on TCP
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) SMTP> 220 [127.0.0.1]
ESMTP amavisd-new service ready
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 4: was busy,
4.0 ms, total idle 0.000 s, busy 0.004 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 5: was idle,
0.2 ms, total idle 0.000 s, busy 0.004 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) SMTP< EHLO ns.nospam\r\n
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_my_time
480 s, SMTP EHLO received
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250-[127.0.0.1]
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250-VRFY
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250-PIPELINING
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250-SIZE
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250-ENHANCEDSTATUSCODES
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250-8BITMIME
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250-DSN
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250 XFORWARD
NAME ADDR PORT PROTO HELO SOURCE
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 6: was busy,
1.4 ms, total idle 0.000 s, busy 0.005 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 5: was idle,
0.1 ms, total idle 0.000 s, busy 0.005 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP< XFORWARD
NAME=df1844jvm01.nospam ADDR=10.44.9.210 PORT=3234\r\n
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_my_time
480 s, SMTP XFORWARD received
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250 2.5.0 Ok XFORWARD
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 6: was busy,
0.6 ms, total idle 0.000 s, busy 0.006 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 5: was idle,
0.1 ms, total idle 0.000 s, busy 0.006 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP< XFORWARD
PROTO=ESMTP HELO=DF1844JVM01 SOURCE=LOCAL\r\n
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_my_time
480 s, SMTP XFORWARD received
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250 2.5.0 Ok XFORWARD
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 6: was busy,
0.5 ms, total idle 0.000 s, busy 0.007 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 5: was idle,
0.1 ms, total idle 0.000 s, busy 0.007 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP< MAIL
FROM:<j...@nospam> SIZE=11986\r\n
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_my_time
480 s, SMTP MAIL received
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) check_mail_begin_task:
task_count=1
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) TempDir::prepare:
creating directory /var/amavis/tmp/amavis-20090824T121325-20479
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) TempDir::prepare_file:
creating file /var/amavis/tmp/amavis-20090824T121325-20479/email.txt
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) TempDir::prepare_file:
layers: unix,perlio
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup_ip_acl:
key="10.44.9.210" matches "10.44.0.0/16", result=1
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) loaded policy bank "MYNETS"
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup [debug_sender]
=> undef, "j...@nospam" does not match
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250 2.1.0 Sender
<j...@nospam> OK
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 6: was busy,
2.6 ms, total idle 0.000 s, busy 0.009 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 5: was idle,
0.1 ms, total idle 0.001 s, busy 0.009 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP< RCPT
TO:<d...@nospam> ORCPT=rfc822;d...@nospam\r\n
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_my_time
480 s, SMTP RCPT received
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 250 2.1.5
Recipient <d...@nospam> OK
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 6: was busy,
0.8 ms, total idle 0.001 s, busy 0.010 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) idle_proc, 5: was idle,
0.1 ms, total idle 0.001 s, busy 0.010 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP< DATA\r\n
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_my_time
480 s, SMTP DATA received
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP::10024
/var/amavis/tmp/amavis-20090824T121325-20479: <j...@nospam> ->
<d...@nospam> SIZE=11986 Received: from ns.nospam ([127.0.0.1]) by
localhost (smtp.nospam [127.0.0.1]) (amavisd-new, port 10024) with
ESMTP for <d...@nospam>; Mon, 24 Aug 2009 12:13:25 +0000 (UTC)
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP> 354 End data
with <CR><LF>.<CR><LF>
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, receiving data
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) switch_to_my_time
480 s, data-end received
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) ESMTP< .<CR><LF>
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) prolong_timer
digest_init: timer set to 480 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) get_body_digest:
reading header section
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) prolong_timer
digest_hdr: timer set to 480 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) get_body_digest:
reading mail body
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) prolong_timer
digest_body: timer set to 480 s
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) body type (ESMTP BODY):
unlabeled, good (h=0, b=0)
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) body hash:
b518b06fab261d6f863f24d43caacf35
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Original mail size:
11986; quota set to: 5993000 bytes
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Checking: kXFeEuPHxq5g
MYNETS [10.44.9.210] <j...@nospam> -> <d...@nospam>
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) 2822.From: <j...@nospam>
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup_acl(d...@nospam), no match
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup [local_domains]
=> undef, "d...@nospam" does not match
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) query_keys: d...@nospam,
dek@, nospam, .nospam, .com, .
Aug 24 12:13:25 smtp amavis[20479]: (20479-01)
lookup_hash(d...@nospam), no matches
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup
[bypass_virus_checks] => undef, "d...@nospam" does not match
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup_acl(d...@nospam), no match
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup
[bypass_banned_checks] => undef, "d...@nospam" does not match
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup_acl(d...@nospam), no match
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) lookup
[bypass_spam_checks] => undef, "d...@nospam" does not match
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Extracting mime components
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Issued a new file name: p001
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Issued a new file name: p002
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Issued a new file name: p003
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) mime_decode_preamble: 2 lines
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Issued a new pseudo part: p004
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) p004 1 Content-Type:
multipart/mixed
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) mime_decode_epilogue: 1 lines
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) mime_decode_preamble: 1 lines
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Issued a new pseudo part: p005
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) p005 1/1 Content-Type:
multipart/alternative
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) mime_decode_epilogue: 1 lines
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) Charging 2 bytes to
remaining quota 5993000 (out of 5993000, (0%)) - by mime_decode
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) p001 1/1/1
Content-Type: text/plain, size: 2 B, name:
Aug 24 12:13:25 smtp amavis[20479]: (20479-01) run_command: [20562]
/usr/bin/file p001 p002 p003 </dev/null 2>&1
Aug 24 12:13:25 smtp amavis[20562]: (20479-01) open_on_specific_fd:
target fd0 closing, to become < /dev/null
Aug 24 12:13:25 smtp amavis[20562]: (20479-01) open_on_specific_fd:
target fd1 closing, to become > &=30
Aug 24 12:13:25 smtp amavis[20562]: (20479-01) open_on_specific_fd:
target fd1 dup2 from fd30 > &=30
Aug 24 12:13:25 smtp amavis[20562]: (20479-01) open_on_specific_fd:
source fd30 closed
Aug 24 12:13:25 smtp amavis[20562]: (20479-01) open_on_specific_fd:
target fd2 closing, to become > &1
Aug 24 12:13:25 smtp amavis[20562]: (20479-01) open_on_specific_fd:
target fd2 dup2 from fd1 > &1
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) result line from
file(1): p001: ASCII text\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup_re("ASCII text")
matches key "(?i-xsm:^(ASCII|text)\b)", result="asc"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[map_full_type_to_short_type] => true, "ASCII text" matches,
result="asc", matching_key="(?i-xsm:^(ASCII|text)\\b)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) File-type of p001:
ASCII text; (asc)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) result line from
file(1): p002: HTML document text\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup_re("HTML
document text") matches key "(?-xism:^HTML document text\b)",
result="html"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[map_full_type_to_short_type] => true, "HTML document text" matches,
result="html", matching_key="(?-xism:^HTML document text\\b)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) File-type of p002: HTML
document text; (html)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) result line from
file(1): p003: Zip archive data, at least v2.0 to extract\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup_re("Zip archive
data, at least v2.0 to extract") matches key "(?i-xsm:^Zip
archive\b)", result="zip"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[map_full_type_to_short_type] => true, "Zip archive data, at least
v2.0 to extract" matches, result="zip", matching_key="(?i-xsm:^Zip
archive\\b)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) File-type of p003: Zip
archive data, at least v2.0 to extract; (zip)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) do_ascii: Decoding part p001
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) do_ascii: Setting
sigaction handler, was 0
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) timer set to 320 s (was 480 s)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) do_ascii: Decoding part
p001 (0 items), uulib V0.5pl20
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer do_ascii:
timer set to 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) decompose_part: p001 - atomic
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) decompose_part: p002 - atomic
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) Unzipping p003
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) (!)Decoding of p003
(Zip archive data, at least v2.0 to extract) failed, leaving it
unpacked: Compress::Raw::Zlib version 2.017 required--this is only
version 2.008 at (eval 100) line 467.
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) decompose_part: p003 -
source retained
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
parts_decode: remaining time = 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) query_keys: d...@nospam,
dek@, nospam, .nospam, .com, .
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_hash(d...@nospam), no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[bypass_header_checks] => undef, "d...@nospam" does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) check_header: 0, OK
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) query_keys: d...@nospam,
dek@, nospam, .nospam, .com, .
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_hash(d...@nospam), no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[bypass_header_checks] => undef, "d...@nospam" does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) Checking for banned
types and filenames
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup: (scalar)
matches, result="DEFAULT"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[banned_filename], 1 matches for "d...@nospam", results:
"(constant:DEFAULT)"=>"DEFAULT"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) collect banned
table[0]: d...@nospam, tables:
DEFAULT=>Amavis::Lookup::RE=ARRAY(0xf9224588)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) starting banned checks
- traversing message structure tree
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) check_for_banned
(p004,p003) multipart/mixed |
application/x-zip-compressed,.zip,TASKMAN.zip
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) doing banned check for
d...@nospam on multipart/mixed |
application/x-zip-compressed,.zip,TASKMAN.zip
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_re(["multipart/mixed","application/x-zip-compressed",".zip","TASKMAN.zip"]),
no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[check_bann:d...@nospam] => undef,
["multipart/mixed","application/x-zip-compressed",".zip","TASKMAN.zip"]
does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[banned_namepath_re] => undef,
"P=p004\tL=1\tM=multipart/mixed\nP=p003\tL=1/2\tM=application/x-zip-compressed\tT=zip\tN=TASKMAN.zip"
does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) p.path d...@nospam:
"P=p004,L=1,M=multipart/mixed |
P=p003,L=1/2,M=application/x-zip-compressed,T=zip,N=TASKMAN.zip"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) check_for_banned
(p004,p005,p001) multipart/mixed | multipart/alternative |
text/plain,.asc
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) doing banned check for
d...@nospam on multipart/mixed | multipart/alternative |
text/plain,.asc
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_re(["multipart/mixed","multipart/alternative","text/plain",".asc"]),
no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[check_bann:d...@nospam] => undef,
["multipart/mixed","multipart/alternative","text/plain",".asc"] does
not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[banned_namepath_re] => undef,
"P=p004\tL=1\tM=multipart/mixed\nP=p005\tL=1/1\tM=multipart/alternative\nP=p001\tL=1/1/1\tM=text/plain\tT=asc"
does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) p.path d...@nospam:
"P=p004,L=1,M=multipart/mixed | P=p005,L=1/1,M=multipart/alternative |
P=p001,L=1/1/1,M=text/plain,T=asc"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) check_for_banned
(p004,p005,p002) multipart/mixed | multipart/alternative |
text/html,.html
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) doing banned check for
d...@nospam on multipart/mixed | multipart/alternative |
text/html,.html
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_re(["multipart/mixed","multipart/alternative","text/html",".html"]),
no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[check_bann:d...@nospam] => undef,
["multipart/mixed","multipart/alternative","text/html",".html"] does
not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[banned_namepath_re] => undef,
"P=p004\tL=1\tM=multipart/mixed\nP=p005\tL=1/1\tM=multipart/alternative\nP=p002\tL=1/1/2\tM=text/html\tT=html"
does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) p.path d...@nospam:
"P=p004,L=1,M=multipart/mixed | P=p005,L=1/1,M=multipart/alternative |
P=p002,L=1/1/2,M=text/html,T=html"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) banned check: any=0, all=N (1)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup_re("MAIL")
matches key "(?-xism:^MAIL$)", result="1"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[keep_decoded_original] => true, "MAIL" matches, result="1",
matching_key="(?-xism:^MAIL$)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) Issued a new file name: p006
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) presenting full
original message to scanners as
/var/amavis/tmp/amavis-20090824T121325-20479/parts/p006
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) Calling virus scanners,
4 files to scan in /var/amavis/tmp/amavis-20090824T121325-20479/parts
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) run_av (ClamAV-clamd):
query template(1,3): CONTSCAN {}\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer run_av:
timer set to 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer run_av:
timer set to 384 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ask_av Using
(ClamAV-clamd): CONTSCAN
/var/amavis/tmp/amavis-20090824T121325-20479/parts\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ask_daemon_internal:
timer set to 10 s (was 384 s)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ClamAV-clamd:
Connecting to socket /var/run/clamav/clamd
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) creating socket by
IO::Socket::UNIX to /var/run/clamav/clamd
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ClamAV-clamd: Sending
CONTSCAN /var/amavis/tmp/amavis-20090824T121325-20479/parts\n to UNIX
socket /var/run/clamav/clamd
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
ask_daemon_internal: timer set to 307 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
ask_daemon_internal: timer set to 384 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer ask_av:
timer set to 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ask_av (ClamAV-clamd)
result: /var/amavis/tmp/amavis-20090824T121325-20479/parts: OK\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) run_av (ClamAV-clamd): CLEAN
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) run_av (ClamAV-clamd)
result: clean
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) wbl: checking sender
<j...@nospam>
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[blacklist_recip<d...@nospam>] => undef, "d...@nospam" does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) query_keys: j...@nospam,
joe@, nospam, .nospam, .wtbts.net, .net, .
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_hash(j...@nospam), no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[blacklist_sender<j...@nospam>,blacklist_sender] => undef, "j...@nospam"
does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[whitelist_recip<d...@nospam>] => undef, "d...@nospam" does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) query_keys: j...@nospam,
joe@, nospam, .nospam, .wtbts.net, .net, .
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_hash(j...@nospam), no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[whitelist_sender<j...@nospam>,whitelist_sender] => undef, "j...@nospam"
does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) query_keys: d...@nospam,
dek@, nospam, .nospam, .com, .
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup_hash(d...@nospam)
matches keys: "."=>ARRAY(0xf9224b68)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[score_recip<d...@nospam>,score_sender], 1 matches for "d...@nospam",
results: "."=>[Amavis::Lookup::RE=ARRAY(0xf92248a8),HASH(0xf9224938)]
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_re("j...@nospam"), no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) query_keys: j...@nospam,
joe@, nospam, .nospam, .wtbts.net, .net, .
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
lookup_hash(j...@nospam), no matches
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[score_sender<j...@nospam>] => undef, "j...@nospam" does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) SpamControl: calling
spam scanner SpamAssassin
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) timer set to 320 s for
SA (was 480 s)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) calling SA parse, SA
version 3.2.5, 3.002005, data as GLOB
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) CALLING SA check
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
spam_scan_sa_finish: timer set to 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) spam_scan: score=-0.295
autolearn=no
tests=[ALL_TRUSTED=-1.44,HTML_MESSAGE=0.001,MIME_HTML_MOSTLY=0.001,MPART_ALT_DIFF=1.143]
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
spam_scan: remaining time = 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup: (scalar)
matches, result="2"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup [spam_tag_level]
=> true, "d...@nospam" matches, result="2",
matching_key="(constant:2)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup: (scalar)
matches, result="6.2"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[spam_tag2_level] => true, "d...@nospam" matches, result="6.2",
matching_key="(constant:6.2)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[spam_tag3_level] => undef, "d...@nospam" does not match
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup: (scalar)
matches, result="15"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[spam_kill_level] => true, "d...@nospam" matches, result="15",
matching_key="(constant:15)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) final_destiny (ccat=0)
is PASS, recip d...@nospam
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) final_destiny PASS,
recip d...@nospam
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) do_notify_and_quar:
ccat=Clean (1,0) ("1":Clean, "0":CatchAll) ccat_block=(), qar_mth=
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) skip admin
notification, no administrators
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) do_notify_and_quarantine - done
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup: (scalar)
matches, result="2"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup [spam_tag_level]
=> true, "d...@nospam" matches, result="2",
matching_key="(constant:2)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup: (scalar)
matches, result="6.2"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup
[spam_tag2_level] => true, "d...@nospam" matches, result="6.2",
matching_key="(constant:6.2)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) headers CLUSTERING: NEW
CLUSTER <d...@nospam>: score=-0.295, tag=0, tag2=0, local=0, bl=, s=,
mangle=
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) header: X-Virus-Scanned: Yes\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) header: Received: from
ns.nospam ([127.0.0.1])\n\tby localhost (smtp.nospam [127.0.0.1])
(amavisd-new, port 10024)\n\twith ESMTP id kXFeEuPHxq5g for
<d...@nospam>;\n\tMon, 24 Aug 2009 12:13:25 +0000 (UTC)\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) headers CLUSTERING:
done all 1 recips in one go
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) dkim: candidate
originators: 2822.From:<j...@nospam>, 2821.mail_from:<j...@nospam>
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup => undef,
"j...@nospam", no lookup tables
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) dkim: not signing, no
applicable private key for domains nospam, s=, From: <j...@nospam>
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) (about to connect to
[127.0.0.1]:10025) FWD via SMTP: <j...@nospam> -> <d...@nospam>
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp session: setting
up a new session
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) establish_or_refresh, state: down
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp creating socket by
IO::Socket::INET to [127.0.0.1]:10025
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=1,
flush=0, wr=0, timeout=35
Aug 24 12:13:26 smtp postfix/smtpd[20564]: connect from localhost[127.0.0.1]
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: receiving
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop read 22 chars<
220 ns.nospam ESMTP\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp resp to greeting:
220 ns.nospam ESMTP
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp cmd> EHLO localhost
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=0,
flush=1, wr=1, timeout=300
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: sending
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop sent 16> EHLO
localhost\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=1,
flush=0, wr=0, timeout=300
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: receiving
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop read 156 chars<
250-ns.nospam\r\n250-PIPELINING\r\n250-SIZE
10240000\r\n250-ETRN\r\n250-XFORWARD NAME ADDR PROTO HELO SOURCE
PORT\r\n250-ENHANCEDSTATUSCODES\r\n250-8BITMIME\r\n250 DSN\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp resp to EHLO: 250
ns.nospam\nPIPELINING\nSIZE 10240000\nETRN\nXFORWARD NAME ADDR PROTO
HELO SOURCE PORT\nENHANCEDSTATUSCODES\n8BITMIME\nDSN
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) Remote host presents
itself as: ns.nospam, handles DSN, handles PIPELINING
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp cmd> XFORWARD
ADDR=10.44.9.210 NAME=df1844jvm01.nospam PORT=3234 PROTO=ESMTP
HELO=DF1844JVM01 SOURCE=LOCAL
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=0,
flush=1, wr=1, timeout=300
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: sending
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop sent 110>
XFORWARD ADDR=10.44.9.210 NAME=df1844jvm01.nospam PORT=3234
PROTO=ESMTP HELO=DF1844JVM01 SOURCE=LOCAL\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=1,
flush=0, wr=0, timeout=300
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: receiving
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop read 14 chars<
250 2.0.0 Ok\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp resp to XFORWARD:
250 2.0.0 Ok
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) AUTH not needed,
user='', MTA offers ''
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp cmd> MAIL
FROM:<j...@nospam> BODY=7BIT
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp cmd> RCPT
TO:<d...@nospam> ORCPT=rfc822;d...@nospam
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp cmd> DATA
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=0,
flush=1, wr=1, timeout=120
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: sending
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop sent 108> MAIL
FROM:<j...@nospam> BODY=7BIT\r\nRCPT TO:<d...@nospam>
ORCPT=rfc822;d...@nospam\r\ndata\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=1,
flush=0, wr=0, timeout=300
Aug 24 12:13:26 smtp postfix/smtpd[20564]: B929B6E1BD:
client=df1844jvm01.nospam[10.44.9.210]
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: receiving
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop read 65 chars<
250 2.1.0 Ok\r\n250 2.1.5 Ok\r\n354 End data with
<CR><LF>.<CR><LF>\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp resp to MAIL
(pip): 250 2.1.0 Ok
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp resp to RCPT (pip)
(<d...@nospam>): 250 2.1.5 Ok
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp resp to DATA: 354
End data with <CR><LF>.<CR><LF>
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) write_header: 0,
Amavis::Out::SMTP::Protocol=HASH(0xfa7d2f88)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp cmd> QUIT
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=0,
flush=1, wr=1, timeout=480
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: sending
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop sent 12225>
X-Virus-Scanned: Yes\r\nReceived: from ns.nospam ([127.0.0.1])\r\n\tby
localhost (smtp.nospam [127.0.0.1]) (amavisd-new, port
10024)\r\n\twith ESMTP id kXFeEuPHxq5g for <d...@nospam>;\r\n\tMon, 24
A [...]
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: needline=1,
flush=0, wr=0, timeout=480
Aug 24 12:13:26 smtp postfix/cleanup[20560]: B929B6E1BD:
message-id=<!&!AAAAAAAAAAAYAAAAAAAAACQnewdMPIxOpxx+/OD+wwbCgAAAEAAAAA69oq9tb/vikmld41wmnmmbaaaaa...@nospam>
Aug 24 12:13:26 smtp postfix/smtpd[20564]: disconnect from localhost[127.0.0.1]
Aug 24 12:13:26 smtp postfix/qmgr[3980]: B929B6E1BD:
from=<j...@nospam>, size=12373, nrcpt=1 (queue active)
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop: receiving
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rw_loop read 51 chars<
250 2.0.0 Ok: queued as B929B6E1BD\r\n221 2.0.0 Bye\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) smtp resp to data-dot
(<d...@nospam>): 250 2.0.0 Ok: queued as B929B6E1BD
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
Amavis::Out::SMTP::Session close, disconnecting
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
fwd-end-chkpnt: timer set to 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) FWD via SMTP:
<j...@nospam> -> <d...@nospam>,BODY=7BIT 250 2.0.0 Ok, id=20479-01, from
MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as B929B6E1BD
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
forwarding: remaining time = 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) DSN: sender is credible
(orig), SA: -0.295, <j...@nospam>
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup: (scalar)
matches, result="18"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup => true,
"j...@nospam" matches, result="18", matching_key="(constant:18)"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) dsn: from MTA 250
NonBlocking:Clean <j...@nospam> -> <d...@nospam>: on_succ=0, on_dly=1,
on_fail=1, never=0, warn_sender=, DSN_passed_on=1, mta_resp: "250
2.0.0 Ok, id=20479-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok:
queued as B929B6E1BD"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) DSN: SUCC from MTA 250
NonBlocking:Clean, no DSN requested: <j...@nospam> -> <d...@nospam>
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) one_response_for_all
<j...@nospam>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.0.0 Ok,
id=20479-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as
B929B6E1BD'
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) notif=N, suppressed=0,
ndn_needed=0, exit=0, 250 2.0.0 Ok, id=20479-01, from
MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as B929B6E1BD
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
delivery-notification: remaining time = 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
fish_out_ip_from_received: 10.44.9.210
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) lookup_ip_acl
(publicnetworks): key="10.44.9.210" matches "!10.0.0.0/8", result=0
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
parse_ip_address_from_received: 10.44.9.210
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) Passed CLEAN, MYNETS
LOCAL [10.44.9.210] [10.44.9.210] <j...@nospam> -> <d...@nospam>,
Message-ID:
<!&!AAAAAAAAAAAYAAAAAAAAACQnewdMPIxOpxx+/OD+wwbCgAAAEAAAAA69oq9tb/vikmld41wmnmmbaaaaa...@nospam>,
mail_id: kXFeEuPHxq5g, Hits: -0.295, size: 11986, queued_as:
B929B6E1BD, 928 ms
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer
main_log_entry: remaining time = 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) Syslog retries: 15 x
Resource temporarily unavailable
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) updating snmp variables
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) prolong_timer check
done: remaining time = 480 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) sending SMTP response:
"250 2.0.0 Ok, id=20479-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok:
queued as B929B6E1BD"
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ESMTP> 250 2.0.0 Ok,
id=20479-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as
B929B6E1BD
Aug 24 12:13:26 smtp postfix/smtp[20561]: A912C6E1B9: to=<d...@nospam>,
relay=localhost[127.0.0.1]:10024, delay=1.2,
delays=0.23/0.01/0.01/0.93, dsn=2.0.0, status=sent (250 2.0.0 Ok,
id=20479-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as
B929B6E1BD)
Aug 24 12:13:26 smtp postfix/qmgr[3980]: A912C6E1B9: removed
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) TempDir::strip:
/var/amavis/tmp/amavis-20090824T121325-20479
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) rmdir_recursively:
/var/amavis/tmp/amavis-20090824T121325-20479/parts, excl=1
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) TIMING [total 935 ms] -
SMTP greeting: 4 (0%)0, SMTP EHLO: 2 (0%)1, SMTP pre-MAIL: 2 (0%)1,
mkdir tempdir: 0 (0%)1, create email.txt: 0 (0%)1, SMTP
pre-DATA-flush: 4 (0%)1, SMTP DATA: 34 (4%)5, check_init: 0 (0%)5,
digest_hdr: 2 (0%)5, digest_body_dkim: 1 (0%)5, gen_mail_id: 1 (0%)5,
mkdir parts: 2 (0%)6, mime_decode: 17 (2%)7, get-file-type3: 122
(13%)20, decompose_part: 37 (4%)24, decompose_part: 1 (0%)24,
parts_decode: 0 (0%)24, check_header: 2 (0%)25, AV-scan-1: 29 (3%)28,
spam-wb-list: 3 (0%)28, SA parse: 4 (0%)29, SA check: 587 (63%)91,
update_cache: 6 (1%)92, decide_mail_destiny: 1 (0%)92, fwd-connect: 15
(2%)94, fwd-xforward: 1 (0%)94, fwd-mail-pip: 15 (2%)95, fwd-rcpt-pip:
0 (0%)95, fwd-data-chkpnt: 0 (0%)96, write-header: 1 (0%)96,
fwd-data-contents: 0 (0%)96, fwd-end-chkpnt: 24 (3%)98, prepare-dsn: 1
(0%)98, main_log_entry: 11 (1%)100, update_snmp: 3 (0%)100, SMTP
pre-response: 0 (0%)100, SMTP response: 1 (0%)100, unlink-4-files: 0
(0%)100, ...
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ...rundown: 1 (0%)100
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) idle_proc, 6: was busy,
924.9 ms, total idle 0.001 s, busy 0.935 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) idle_proc, 5: was idle,
0.1 ms, total idle 0.001 s, busy 0.935 s
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ESMTP< QUIT\r\n
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) switch_to_my_time
480 s, SMTP QUIT received
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) ESMTP> 221 2.0.0
[127.0.0.1] amavisd-new closing transmission channel
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) switch_to_client_time
480 s, smtp response sent
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) SMTP session over, timer stopped
Aug 24 12:13:26 smtp amavis[20479]: (20479-01) exiting process_request
Aug 24 12:13:26 smtp amavis[20479]: (20479-01)
post_process_request_hook: timer was not running
Aug 24 12:13:28 smtp postfix/smtpd[20557]: disconnect from
df1844jvm01.nospam[10.44.9.210]
Aug 24 12:13:29 smtp postfix/smtp[20565]: B929B6E1BD: to=<d...@nospam>,
relay=gmail-smtp-in.l.google.com[209.85.212.57]:25, delay=2.2,
delays=0.04/0.01/0.96/1.2, dsn=5.7.0, status=bounced (host
gmail-smtp-in.l.google.com[209.85.212.57] said: 552-5.7.0 Our system
detected an illegal attachment on your message. Please 552-5.7.0 visit
http://mail.google.com/support/bin/answer.py?answer=6590 to 552 5.7.0
review our attachment guidelines. 31si5133637vws.21 (in reply to end
of DATA command))
Aug 24 12:13:29 smtp postfix/cleanup[20560]: 051B06E1BE:
message-id=<[email protected]>
Aug 24 12:13:29 smtp postfix/bounce[20566]: B929B6E1BD: sender
non-delivery notification: 051B06E1BE
Aug 24 12:13:29 smtp postfix/qmgr[3980]: 051B06E1BE: from=<>,
size=14677, nrcpt=1 (queue active)
Aug 24 12:13:29 smtp postfix/qmgr[3980]: B929B6E1BD: removed
Aug 24 12:13:29 smtp postfix/smtp[20565]: 051B06E1BE: to=<j...@nospam>,
relay=itexch03.nospam[10.44.8.116]:25, delay=0.09,
delays=0.02/0/0/0.06, dsn=2.6.0, status=sent (250 2.6.0
<[email protected]> Queued mail for delivery)
Aug 24 12:13:29 smtp postfix/qmgr[3980]: 051B06E1BE: removed
------------------------------------------------------------------------------
Let Crystal Reports handle the reporting - Free Crystal Reports 2008 30-Day
trial. Simplify your report design, integration and deployment - and focus on
what you do best, core application coding. Discover what's new with
Crystal Reports now. http://p.sf.net/sfu/bobj-july
_______________________________________________
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/
