[Postfixbuch-users] Spamassassin + amavisd

[Jan Dinger]

Ok, anbei ein kompletter amavisd-debug auszug.
-------------- nächster Teil --------------
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-01) Net::Server: 2006/11/09-21:29:06 CONNECT TCP Peer: "127.0.0.1:49814" Local: "127.0.0.1:10024"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-01) idle_proc, hi : was idle, 495334.3 ms, total idle 495.336 s, busy 1.656 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-01) loaded base policy bank
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-01) lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-01) process_request: fileno sock=12, STDIN=0, STDOUT=1
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-01) switch_to_my_time     480 s, new request
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-01) process_request: suggested_protocol="" on TCP
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 4: was busy, 1.6 ms, total idle 495.336 s, busy 1.658 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 5: was idle, 0.4 ms, total idle 495.336 s, busy 1.658 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) SMTP< EHLO cassiopeia.noro-online.de\r\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_my_time     480 s, SMTP EHLO received
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250-[127.0.0.1]
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250-VRFY
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250-PIPELINING
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250-SIZE
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250-ENHANCEDSTATUSCODES
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250-8BITMIME
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250-DSN
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 6: was busy, 1.7 ms, total idle 495.336 s, busy 1.659 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 5: was idle, 0.3 ms, total idle 495.336 s, busy 1.659 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP< XFORWARD NAME=mailout07.sul.t-online.com ADDR=194.25.134.83\r\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_my_time     480 s, SMTP XFORWARD received
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250 2.5.0 Ok XFORWARD
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 6: was busy, 0.8 ms, total idle 495.336 s, busy 1.660 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 5: was idle, 0.1 ms, total idle 495.337 s, busy 1.660 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP< XFORWARD PROTO=ESMTP HELO=mailout07.sul.t-online.com\r\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_my_time     480 s, SMTP XFORWARD received
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250 2.5.0 Ok XFORWARD
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 6: was busy, 0.7 ms, total idle 495.337 s, busy 1.661 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 5: was idle, 0.1 ms, total idle 495.337 s, busy 1.661 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP< MAIL FROM:<fgl at md-x.de> SIZE=134727\r\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_my_time     480 s, SMTP MAIL received
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) check_mail_begin_task: task_count=2
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_ip_acl (mynetworks): key="194.25.134.83", no match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (debug_sender) => undef, "fgl at md-x.de" does not match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250 2.1.0 Sender fgl at md-x.de OK
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 6: was busy, 1.6 ms, total idle 495.337 s, busy 1.663 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 5: was idle, 0.1 ms, total idle 495.337 s, busy 1.663 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP< RCPT TO:<info at noro.de> ORCPT=rfc822;info at noro.de\r\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_my_time     480 s, SMTP RCPT received
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250 2.1.5 Recipient info at noro.de OK
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 6: was busy, 0.8 ms, total idle 495.337 s, busy 1.663 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 5: was idle, 0.1 ms, total idle 495.337 s, busy 1.663 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP< DATA\r\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_my_time     480 s, SMTP DATA received
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP::10024 /var/lib/amavis/tmp/amavis-20061109T212049-27006: <fgl at md-x.de> -> <info at noro.de> SIZE=134727 Received: from cassiopeia.noro-online.de ([127.0.0.1]) by localhost (cassiopeia.noro-online.de [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <info at noro.de>; Thu,  9 Nov 2006 21:29:06 +0100 (CET)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, receiving data
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_my_time     480 s, data-end received
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP< .<CR><LF>
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Actual message size 134726 B, declared 134727 B
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) setting body type: 8BITMIME (h=0, b=1)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) body hash: d23e26564df23bd04d5b5e2bcc7c1fdd
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Original mail size: 134726; quota set to: 67363000 bytes
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Checking: z3jqCsfyk+SO [194.25.134.83] <fgl at md-x.de> -> <info at noro.de>
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (bypass_virus_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Extracting mime components
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Issued a new file name: p001
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Issued a new file name: p002
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) mime_decode_preamble: 1 lines
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Issued a new pseudo part: p003
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) p003 1 Content-Type: multipart/mixed
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) mime_decode_epilogue: 1 lines
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Charging 167 bytes to remaining quota 67363000 (out of 67363000, (0%)) - by mime_decode
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) p001 1/1 Content-Type: text/plain, size: 167 B, name:
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) reparenting p001 from p000 to p003
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Charging 97199 bytes to remaining quota 67362833 (out of 67363000, (0%)) - by mime_decode
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) p002 1/2 Content-Type: application/octet-stream, size: 97199 B, name: update_v3_206.fgl
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) reparenting p002 from p000 to p003
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer mime_decode-1: remaining time = 480 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) decode_parts: level=1, #parts=3 : p001, p002, p003
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27103]: (27006-02) open_on_specific_fd: target fd0 closing, to become < /dev/null
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27103]: (27006-02) open_on_specific_fd: target fd2 closing, to become > &1
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) run_command: [27103] /usr/bin/file p001 p002 </dev/null 2>&1
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) result line from file(1): p001: ISO-8859 text
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_re("ISO-8859 text") matches key "(?i-xsm:^ISO-8859.*\btext\b)", result="txt"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (map_full_type_to_short_type) => true,  "ISO-8859 text" matches, result="txt", matching_key="(?i-xsm:^ISO-8859.*\\btext\\b)"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) File-type of p001: ISO-8859 text; (txt)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) result line from file(1): p002: data
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_re("data") matches key "(?-xism:^dataz)", result="dat"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (map_full_type_to_short_type) => true,  "data" matches, result="dat", matching_key="(?-xism:^data\\z)"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) File-type of p002: data; (dat)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) decompose_part: p001 - atomic
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) decompose_part: p002 - atomic
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer parts_decode: remaining time = 480 s
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (bypass_header_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) check_header: 0, OK
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (bypass_header_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Checking for banned types and filenames
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (bypass_banned_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup: (scalar) matches, result="DEFAULT"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (banned_filename), 1 matches for "info at noro.de", results: "(constant:DEFAULT)"=>"DEFAULT"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) collect banned table[0]: info at noro.de, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8c73eb0)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) starting banned checks - traversing message structure tree
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) check_for_banned (p003,p001) multipart/mixed | text/plain,.txt
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) doing banned check for info at noro.de on multipart/mixed | text/plain,.txt
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_re(["multipart/mixed","text/plain",".txt"]), no matches
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (check_bann:info at noro.de) => undef, ["multipart/mixed","text/plain",".txt"] does not match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) p.path info at noro.de: "P=p003,L=1,M=multipart/mixed | P=p001,L=1/1,M=text/plain,T=txt"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) check_for_banned (p003,p002) multipart/mixed | application/octet-stream,.dat,update_v3_206.fgl
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) doing banned check for info at noro.de on multipart/mixed | application/octet-stream,.dat,update_v3_206.fgl
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_re(["multipart/mixed","application/octet-stream",".dat","update_v3_206.fgl"]), no matches
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (check_bann:info at noro.de) => undef, ["multipart/mixed","application/octet-stream",".dat","update_v3_206.fgl"] does not match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) p.path info at noro.de: "P=p003,L=1,M=multipart/mixed | P=p002,L=1/2,M=application/octet-stream,T=dat,N=update_v3_206.fgl"
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) banned check: any=0, all=N (1)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_re("MAIL"), no matches
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (keep_decoded_original) => undef, "MAIL" does not match
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Using ClamAV-clamd: (built-in interface)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ask_av (ClamAV-clamd): query template1: CONTSCAN {}\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Using (ClamAV-clamd) on dir: CONTSCAN /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ClamAV-clamd: Connecting to socket  /var/run/clamav/clamd.ctl
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ClamAV-clamd: Sending CONTSCAN /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts\n to UNIX socket /var/run/clamav/clamd.ctl
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ask_av (ClamAV-clamd) result: /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts: lstat() failed. ERROR\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ask_av (ClamAV-clamd) FAILED - unexpected result: /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts: lstat() failed. ERROR\n
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Using check-jpeg: (built-in interface)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ask_av (check-jpeg): query template1: {}/*
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Using (check-jpeg) on file: /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts/p001
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ask_av (check-jpeg) result: not jpeg
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ask_av (check-jpeg): p001 CLEAN, but inconclusive
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Using (check-jpeg) on file: /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts/p002
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ask_av (check-jpeg) result: not jpeg
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ask_av (check-jpeg): p002 CLEAN, but inconclusive
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) WARN: all primary virus scanners failed, considering backups
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Using (ClamAV-clamscan): /usr/bin/clamscan --stdout --disable-summary -r --tempdir=/var/lib/amavis/tmp /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) timer set to 320 s (was 480 s)
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27105]: (27006-02) open_on_specific_fd: target fd0 closing, to become < /dev/null
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27105]: (27006-02) open_on_specific_fd: target fd2 closing, to become > &1
Nov  9 21:29:06 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) run_command: [27105] /usr/bin/clamscan --stdout --disable-summary -r --tempdir=/var/lib/amavis/tmp /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts </dev/null 2>&1
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer run_av: timer set to = 479 s
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) run_av: /usr/bin/clamscan exit 0, LibClamAV Warning: ********************************************************\nLibClamAV Warning: ***  This version of the ClamAV engine is outdated.  ***\nLibClamAV Warning: *** DON'T PANIC! Read http://www.clamav.net/faq.html ***\nLibClamAV Warning: ********************************************************\n/var/lib/amavis/tmp/amavis-20061109T212049-27006/parts/p001: OK\n/var/lib/amavis/tmp/amavis-20061109T212049-27006/parts/p002: OK
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) run_av (ClamAV-clamscan): CLEAN
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (bypass_virus_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (bypass_spam_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) wbl: checking sender <fgl at md-x.de>
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (blacklist_recip<info at noro.de>) => undef, "info at noro.de" does not match
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: fgl at md-x.de, fgl@, md-x.de, .md-x.de, .de, .
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(fgl at md-x.de), no matches
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (blacklist_sender) => undef, "fgl at md-x.de" does not match
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (whitelist_recip<info at noro.de>) => undef, "info at noro.de" does not match
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: fgl at md-x.de, fgl@, md-x.de, .md-x.de, .de, .
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(fgl at md-x.de), no matches
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (whitelist_sender) => undef, "fgl at md-x.de" does not match
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de) matches keys: "."=>ARRAY(0x8c936fc)
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (score_sender), 1 matches for "info at noro.de", results: "."=>[Amavis::Lookup::RE=ARRAY(0x87ee140),HASH(0x87ee8a8)]
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_re("fgl at md-x.de"), no matches
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: fgl at md-x.de, fgl@, md-x.de, .md-x.de, .de, .
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(fgl at md-x.de), no matches
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (score_sender<fgl at md-x.de>) => undef, "fgl at md-x.de" does not match
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) SpamControl: calling spam scanner
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) spam_scan: DSPAM not available, skipping it
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) timer set to 319 s for SA (was 479 s)
Nov  9 21:29:07 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) calling SA parse, SA version 3.1.4
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) CALLING SA check
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer spam_scan_sa_finish: timer set to = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) spam_scan: score=2.261 tests=[INVALID_MSGID=1.71,NO_REAL_NAME=0.55,UNPARSEABLE_RELAY=0.001]
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer spam_scan: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) enqueue: stat is not numeric: ""
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup: (scalar) matches, result="5"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (spam_tag2_level) => true,  "info at noro.de" matches, result="5", matching_key="(constant:5)"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (spam_tag3_level) => undef, "info at noro.de" does not match
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup: (scalar) matches, result="10"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (spam_kill_level) => true,  "info at noro.de" matches, result="10", matching_key="(constant:10)"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (bypass_spam_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) final_destiny PASS, recip info at noro.de
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) do_notify_and_quarantine: ccat=Clean, (1,0)
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup: (scalar) matches, result="clean-quarantine"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (clean_quarantine_to) => true,  "info at noro.de" matches, result="clean-quarantine", matching_key="(constant:clean-quarantine)"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) skip admin notification, no administrators
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup => undef, "info at noro.de", no lookup tables
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) do_notify_and_quarantine - done
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) header: Received: from cassiopeia.noro-online.de ([127.0.0.1])\n\tby localhost (cassiopeia.noro-online.de [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id z3jqCsfyk+SO for <info at noro.de>;\n\tThu,  9 Nov 2006 21:29:06 +0100 (CET)\n
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_acl(info at noro.de), no match
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (local_domains) => undef, "info at noro.de" does not match
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_hash(info at noro.de), no matches
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (bypass_spam_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup: (scalar) matches, result="-1000"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (spam_tag_level) => true,  "info at noro.de" matches, result="-1000", matching_key="(constant:-1000)"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup: (scalar) matches, result="5"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup (spam_tag2_level) => true,  "info at noro.de" matches, result="5", matching_key="(constant:5)"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) headers CLUSTERING: NEW CLUSTER <info at noro.de>: score=2.261, tag=0, tag2=0, subj=0, subj_u=0, local=0, bl=, s=
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) header: X-Virus-Scanned: Debian amavisd-new at cassiopeia.noro-online.de\n
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) headers CLUSTERING: done all 1 recips in one go
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) (about to connect to [127.0.0.1]:10025) FWD via SMTP: <fgl at md-x.de> -> <info at noro.de>
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Remote host presents itself as: cassiopeia.noro-online.de, handles DSN, no ORCPT
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer fwd-connect: timer set to = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) AUTH not needed, user='', MTA offers 'LOGIN PLAIN DIGEST-MD5 CRAM-MD5'
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer fwd-mail-from: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) sending RCPT TO:<info at noro.de>
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) response to RCPT TO for <info at noro.de>: "250 2.1.5 Ok"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer fwd-rcpt-to: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer fwd-data-cmd: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) response to DATA: "354 End data with <CR><LF>.<CR><LF>"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) write_header: 0, Amavis::Out::SMTP=HASH(0xa480f74)
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer fwd-data-contents: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer fwd-data-end: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) response to data end: "250 2.0.0 Ok: queued as 158D64802A6"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer fwd-rundown-1: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) FWD via SMTP: <fgl at md-x.de> -> <info at noro.de>, BODY=8BITMIME 250 2.6.0 Ok, id=27006-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 158D64802A6
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer forwarding: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) one_response_for_all <fgl at md-x.de>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.6.0 Ok, id=27006-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 158D64802A6'
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ndn_needed=0, exit=0, 250 2.6.0 Ok, id=27006-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 158D64802A6
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) dsn: from MTA 250 Clean <fgl at md-x.de> -> <info at noro.de>: on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=1
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) DSN: SUCC from MTA 250 Clean, no DSN requested: <fgl at md-x.de> -> <info at noro.de>
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer delivery-notification: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: from = localhost  /localhost//
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: by = mo-p07-ob.rzone.de /mo-p07-ob.rzone.de//
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: with = SMTP /SMTP //
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: id = iA9FAAQB04eb0C  /iA9FAAQB04eb0C  //
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: for = <webmaster at noroonline.de>/<webmaster at noroonline.de>//
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: ; = Thu, 9 Nov 2006 21:23:46 +0100 (MET)/Thu, 9 Nov 2006 21:23:46 +0100 (MET)//
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) fish_out_ip_from_received: , localhost
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: from = mo-p07-ob.rzone.de ([81.169.146.188]) /mo-p07-ob.rzone.de//81.169.146.188
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: by = mailin29.sul.t-online.de\t/mailin29.sul.t-online.de//
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: with = esmtp /esmtp //
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: id = 1GiGR8-1nuu800/1GiGR8-1nuu800//
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) parse_received: ; = Thu, 9 Nov 2006 21:23:46 +0100/Thu, 9 Nov 2006 21:23:46 +0100//
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) fish_out_ip_from_received: 81.169.146.188, mo-p07-ob.rzone.de ([81.169.146.188])
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) lookup_ip_acl (publicnetworks): key="81.169.146.188" matches "[::FFFF:0:0]/96", result=1
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) best_try_originator_ip: 81.169.146.188
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) Passed CLEAN, [194.25.134.83] [81.169.146.188] <fgl at md-x.de> -> <info at noro.de>, Message-ID: 20061109_194521_027643.fgl at md-x.de, mail_id: z3jqCsfyk+SO, Hits: 2.261, queued_as: 158D64802A6, 1640 ms
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) updating snmp variables
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) prolong_timer check done: remaining time = 478 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) sending SMTP response: "250 2.6.0 Ok, id=27006-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 158D64802A6"
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) TempDir::strip: /var/lib/amavis/tmp/amavis-20061109T212049-27006
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) rmdir_recursively: /var/lib/amavis/tmp/amavis-20061109T212049-27006/parts, excl=1
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) TIMING [total 1643 ms] - SMTP EHLO: 3 (0%)0, SMTP pre-MAIL: 4 (0%)0, SMTP pre-DATA-flush: 3 (0%)1, SMTP DATA: 46 (3%)3, body_digest: 2 (0%)3, gen_mail_id: 0 (0%)3, mime_decode: 15 (1%)4, get-file-type2: 10 (1%)5, parts_decode: 0 (0%)5, AV-scan-1: 8 (0%)6, AV-scan-2: 1 (0%)6, AV-scan-3: 1370 (83%)89, spam-wb-list: 3 (0%)89, SA msg read: 5 (0%)90, SA parse: 10 (1%)90, SA check: 51 (3%)93, SA finish: 2 (0%)93, update_cache: 2 (0%)93, decide_mail_destiny: 2 (0%)93, fwd-connect: 28 (2%)95, fwd-mail-from: 3 (0%)95, fwd-rcpt-to: 4 (0%)96, fwd-data-cmd: 0 (0%)96, write-header: 1 (0%)96, fwd-data-contents: 8 (0%)96, fwd-data-end: 51 (3%)99, fwd-rundown: 1 (0%)99, prepare-dsn: 1 (0%)99, main_log_entry: 7 (0%)100, update_snmp: 1 (0%)100, unlink-2-files: 1 (0%)100, rundown: 0 (0%)100
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 250 2.6.0 Ok, id=27006-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 158D64802A6
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 6: was busy, 1635.6 ms, total idle 495.337 s, busy 3.299 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, 5: was idle, 0.2 ms, total idle 495.337 s, busy 3.299 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP< QUIT\r\n
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_my_time     480 s, SMTP QUIT received
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-new closing transmission channel
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) SMTP session over, timer stopped
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) exiting process_request
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) post_process_request_hook: timer was not running
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) idle_proc, bye: was busy, 2.2 ms, total idle 495.337 s, busy 3.301 s
Nov  9 21:29:08 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27006]: (27006-02) load: 1 %, total idle 495.337 s, busy 3.301 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-02) Net::Server: 2006/11/09-21:29:49 CONNECT TCP Peer: "127.0.0.1:49832" Local: "127.0.0.1:10024"
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-02) idle_proc, hi : was idle, 288676.8 ms, total idle 1032.669 s, busy 3.326 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-02) loaded base policy bank
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-02) lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-02) process_request: fileno sock=12, STDIN=0, STDOUT=1
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-02) switch_to_my_time     480 s, new request
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-02) process_request: suggested_protocol="" on TCP
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 4: was busy, 1.7 ms, total idle 1032.669 s, busy 3.328 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 5: was idle, 0.3 ms, total idle 1032.669 s, busy 3.328 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) SMTP< EHLO cassiopeia.noro-online.de\r\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_my_time     480 s, SMTP EHLO received
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250-[127.0.0.1]
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250-VRFY
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250-PIPELINING
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250-SIZE
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250-ENHANCEDSTATUSCODES
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250-8BITMIME
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250-DSN
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 6: was busy, 0.8 ms, total idle 1032.669 s, busy 3.329 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 5: was idle, 0.2 ms, total idle 1032.669 s, busy 3.329 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP< XFORWARD NAME=p549B7DFC.dip.t-dialin.net ADDR=84.155.125.252\r\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_my_time     480 s, SMTP XFORWARD received
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250 2.5.0 Ok XFORWARD
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 6: was busy, 0.4 ms, total idle 1032.669 s, busy 3.329 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 5: was idle, 0.1 ms, total idle 1032.670 s, busy 3.329 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP< XFORWARD PROTO=ESMTP HELO=[192.168.2.195]\r\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_my_time     480 s, SMTP XFORWARD received
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250 2.5.0 Ok XFORWARD
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 6: was busy, 0.4 ms, total idle 1032.670 s, busy 3.329 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 5: was idle, 0.1 ms, total idle 1032.670 s, busy 3.329 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP< MAIL FROM:<jan.dinger at noro.de> SIZE=520\r\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_my_time     480 s, SMTP MAIL received
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) check_mail_begin_task: task_count=3
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_ip_acl (mynetworks): key="84.155.125.252", no match
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (debug_sender) => undef, "jan.dinger at noro.de" does not match
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250 2.1.0 Sender jan.dinger at noro.de OK
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 6: was busy, 1.0 ms, total idle 1032.670 s, busy 3.330 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 5: was idle, 0.1 ms, total idle 1032.670 s, busy 3.330 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP< RCPT TO:<info at noro.de> ORCPT=rfc822;info at noro.de\r\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_my_time     480 s, SMTP RCPT received
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250 2.1.5 Recipient info at noro.de OK
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 6: was busy, 0.5 ms, total idle 1032.670 s, busy 3.331 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 5: was idle, 0.1 ms, total idle 1032.670 s, busy 3.331 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP< DATA\r\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_my_time     480 s, SMTP DATA received
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP::10024 /var/lib/amavis/tmp/amavis-20061109T211233-27005: <jan.dinger at noro.de> -> <info at noro.de> SIZE=520 Received: from cassiopeia.noro-online.de ([127.0.0.1]) by localhost (cassiopeia.noro-online.de [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <info at noro.de>; Thu,  9 Nov 2006 21:29:49 +0100 (CET)
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, receiving data
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_my_time     480 s, data-end received
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP< .<CR><LF>
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) setting body type: 7BIT (h=0, b=0)
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) body hash: e07910a06a086c83ba41827aa00b26ed
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Original mail size: 520; quota set to: 260000 bytes
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) generate_mail_id retry: +ggaGTwFFdW492OczW8rxw
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Checking: FxjabiSr9NSv [84.155.125.252] <jan.dinger at noro.de> -> <info at noro.de>
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (bypass_virus_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Extracting mime components
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Issued a new file name: p001
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Charging 4 bytes to remaining quota 260000 (out of 260000, (0%)) - by mime_decode
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) p001 1 Content-Type: text/plain, size: 4 B, name:
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer mime_decode-1: remaining time = 480 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) decode_parts: level=1, #parts=1 : p001
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27114]: (27005-03) open_on_specific_fd: target fd0 closing, to become < /dev/null
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27114]: (27005-03) open_on_specific_fd: target fd2 closing, to become > &1
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) run_command: [27114] /usr/bin/file p001 </dev/null 2>&1
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) result line from file(1): p001: ASCII text
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_re("ASCII text") matches key "(?i-xsm:^(ASCII|text)\b)", result="asc"
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (map_full_type_to_short_type) => true,  "ASCII text" matches, result="asc", matching_key="(?i-xsm:^(ASCII|text)\\b)"
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) File-type of p001: ASCII text; (asc)
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) do_ascii: Decoding part p001
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) do_ascii: Decoding part p001 (0 items), uulib V0.5pl20
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) decompose_part: p001 - atomic
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer parts_decode: remaining time = 480 s
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (bypass_header_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) check_header: 0, OK
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (bypass_header_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Checking for banned types and filenames
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (bypass_banned_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup: (scalar) matches, result="DEFAULT"
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (banned_filename), 1 matches for "info at noro.de", results: "(constant:DEFAULT)"=>"DEFAULT"
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) collect banned table[0]: info at noro.de, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8c73eb0)
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) starting banned checks - traversing message structure tree
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) check_for_banned (p001) text/plain,.asc
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) doing banned check for info at noro.de on text/plain,.asc
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_re(["text/plain",".asc"]), no matches
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (check_bann:info at noro.de) => undef, ["text/plain",".asc"] does not match
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) p.path info at noro.de: "P=p001,L=1,M=text/plain,T=asc"
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) banned check: any=0, all=N (1)
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_re("MAIL"), no matches
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (keep_decoded_original) => undef, "MAIL" does not match
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Using ClamAV-clamd: (built-in interface)
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ask_av (ClamAV-clamd): query template1: CONTSCAN {}\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Using (ClamAV-clamd) on dir: CONTSCAN /var/lib/amavis/tmp/amavis-20061109T211233-27005/parts\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ClamAV-clamd: Connecting to socket  /var/run/clamav/clamd.ctl
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ClamAV-clamd: Sending CONTSCAN /var/lib/amavis/tmp/amavis-20061109T211233-27005/parts\n to UNIX socket /var/run/clamav/clamd.ctl
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ask_av (ClamAV-clamd) result: /var/lib/amavis/tmp/amavis-20061109T211233-27005/parts: lstat() failed. ERROR\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ask_av (ClamAV-clamd) FAILED - unexpected result: /var/lib/amavis/tmp/amavis-20061109T211233-27005/parts: lstat() failed. ERROR\n
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Using check-jpeg: (built-in interface)
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ask_av (check-jpeg): query template1: {}/*
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Using (check-jpeg) on file: /var/lib/amavis/tmp/amavis-20061109T211233-27005/parts/p001
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ask_av (check-jpeg) result: not jpeg
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ask_av (check-jpeg): p001 CLEAN, but inconclusive
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) WARN: all primary virus scanners failed, considering backups
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Using (ClamAV-clamscan): /usr/bin/clamscan --stdout --disable-summary -r --tempdir=/var/lib/amavis/tmp /var/lib/amavis/tmp/amavis-20061109T211233-27005/parts
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) timer set to 320 s (was 480 s)
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27116]: (27005-03) open_on_specific_fd: target fd0 closing, to become < /dev/null
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27116]: (27005-03) open_on_specific_fd: target fd2 closing, to become > &1
Nov  9 21:29:49 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) run_command: [27116] /usr/bin/clamscan --stdout --disable-summary -r --tempdir=/var/lib/amavis/tmp /var/lib/amavis/tmp/amavis-20061109T211233-27005/parts </dev/null 2>&1
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer run_av: timer set to = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) run_av: /usr/bin/clamscan exit 0, LibClamAV Warning: ********************************************************\nLibClamAV Warning: ***  This version of the ClamAV engine is outdated.  ***\nLibClamAV Warning: *** DON'T PANIC! Read http://www.clamav.net/faq.html ***\nLibClamAV Warning: ********************************************************\n/var/lib/amavis/tmp/amavis-20061109T211233-27005/parts/p001: OK
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) run_av (ClamAV-clamscan): CLEAN
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (bypass_virus_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (bypass_spam_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) wbl: checking sender <jan.dinger at noro.de>
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (blacklist_recip<info at noro.de>) => undef, "info at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: jan.dinger at noro.de, jan.dinger@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(jan.dinger at noro.de), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (blacklist_sender) => undef, "jan.dinger at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (whitelist_recip<info at noro.de>) => undef, "info at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: jan.dinger at noro.de, jan.dinger@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(jan.dinger at noro.de), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (whitelist_sender) => undef, "jan.dinger at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de) matches keys: "."=>ARRAY(0x8c936fc)
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (score_sender), 1 matches for "info at noro.de", results: "."=>[Amavis::Lookup::RE=ARRAY(0x87ee140),HASH(0x87ee8a8)]
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_re("jan.dinger at noro.de"), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: jan.dinger at noro.de, jan.dinger@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(jan.dinger at noro.de), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (score_sender<jan.dinger at noro.de>) => undef, "jan.dinger at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) SpamControl: calling spam scanner
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) spam_scan: DSPAM not available, skipping it
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) timer set to 319 s for SA (was 479 s)
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) calling SA parse, SA version 3.1.4
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) CALLING SA check
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer spam_scan_sa_finish: timer set to = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) spam_scan: score=0 tests=[none]
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer spam_scan: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) enqueue: stat is not numeric: ""
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup: (scalar) matches, result="5"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (spam_tag2_level) => true,  "info at noro.de" matches, result="5", matching_key="(constant:5)"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (spam_tag3_level) => undef, "info at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup: (scalar) matches, result="10"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (spam_kill_level) => true,  "info at noro.de" matches, result="10", matching_key="(constant:10)"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (bypass_spam_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) final_destiny PASS, recip info at noro.de
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) do_notify_and_quarantine: ccat=Clean, (1,0)
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup: (scalar) matches, result="clean-quarantine"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (clean_quarantine_to) => true,  "info at noro.de" matches, result="clean-quarantine", matching_key="(constant:clean-quarantine)"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) skip admin notification, no administrators
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup => undef, "info at noro.de", no lookup tables
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) do_notify_and_quarantine - done
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) header: Received: from cassiopeia.noro-online.de ([127.0.0.1])\n\tby localhost (cassiopeia.noro-online.de [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id FxjabiSr9NSv for <info at noro.de>;\n\tThu,  9 Nov 2006 21:29:49 +0100 (CET)\n
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_acl(info at noro.de), no match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (local_domains) => undef, "info at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) query_keys: info at noro.de, info@, noro.de, .noro.de, .de, .
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_hash(info at noro.de), no matches
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (bypass_spam_checks) => undef, "info at noro.de" does not match
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup: (scalar) matches, result="-1000"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (spam_tag_level) => true,  "info at noro.de" matches, result="-1000", matching_key="(constant:-1000)"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup: (scalar) matches, result="5"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup (spam_tag2_level) => true,  "info at noro.de" matches, result="5", matching_key="(constant:5)"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) headers CLUSTERING: NEW CLUSTER <info at noro.de>: score=0, tag=0, tag2=0, subj=0, subj_u=0, local=0, bl=, s=
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) header: X-Virus-Scanned: Debian amavisd-new at cassiopeia.noro-online.de\n
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) headers CLUSTERING: done all 1 recips in one go
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) (about to connect to [127.0.0.1]:10025) FWD via SMTP: <jan.dinger at noro.de> -> <info at noro.de>
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Remote host presents itself as: cassiopeia.noro-online.de, handles DSN, no ORCPT
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer fwd-connect: timer set to = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) AUTH not needed, user='', MTA offers 'LOGIN PLAIN DIGEST-MD5 CRAM-MD5'
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer fwd-mail-from: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) sending RCPT TO:<info at noro.de>
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) response to RCPT TO for <info at noro.de>: "250 2.1.5 Ok"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer fwd-rcpt-to: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer fwd-data-cmd: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) response to DATA: "354 End data with <CR><LF>.<CR><LF>"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) write_header: 0, Amavis::Out::SMTP=HASH(0xa4e2b04)
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer fwd-data-contents: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer fwd-data-end: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) response to data end: "250 2.0.0 Ok: queued as DE1E14802A6"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer fwd-rundown-1: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) FWD via SMTP: <jan.dinger at noro.de> -> <info at noro.de>, 250 2.6.0 Ok, id=27005-03, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as DE1E14802A6
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer forwarding: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) one_response_for_all <jan.dinger at noro.de>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.6.0 Ok, id=27005-03, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as DE1E14802A6'
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ndn_needed=0, exit=0, 250 2.6.0 Ok, id=27005-03, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as DE1E14802A6
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) dsn: from MTA 250 Clean <jan.dinger at noro.de> -> <info at noro.de>: on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=1
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) DSN: SUCC from MTA 250 Clean, no DSN requested: <jan.dinger at noro.de> -> <info at noro.de>
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer delivery-notification: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) parse_received: from = [192.168.2.195] (p549B7DFC.dip.t-dialin.net [84.15.../[192.168.2.195]/p549B7DFC.dip.t-dialin.net/84.155.125.252
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) parse_received: by = cassiopeia.noro-online.de /cassiopeia.noro-online.de//
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) parse_received: with = ESMTP /ESMTP //
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) parse_received: id = 394094802A5\t/394094802A5\t//
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) parse_received: for = <info at noro.de>/<info at noro.de>//
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) parse_received: ; = Thu,  9 Nov 2006 21:29:49 +0100 (CET)/Thu,  9 Nov 2006 21:29:49 +0100 (CET)//
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) fish_out_ip_from_received: 84.155.125.252, [192.168.2.195] (p549B7DFC.dip.t-dialin.net [84.155.125.252])\t
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) lookup_ip_acl (publicnetworks): key="84.155.125.252" matches "[::FFFF:0:0]/96", result=1
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) best_try_originator_ip: 84.155.125.252
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) Passed CLEAN, [84.155.125.252] [84.155.125.252] <jan.dinger at noro.de> -> <info at noro.de>, Message-ID: <45538E6E.2030806 at noro.de>, mail_id: FxjabiSr9NSv, Hits: 0., queued_as: DE1E14802A6, 1562 ms
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) updating snmp variables
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) prolong_timer check done: remaining time = 479 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) sending SMTP response: "250 2.6.0 Ok, id=27005-03, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as DE1E14802A6"
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) TempDir::strip: /var/lib/amavis/tmp/amavis-20061109T211233-27005
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) rmdir_recursively: /var/lib/amavis/tmp/amavis-20061109T211233-27005/parts, excl=1
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) TIMING [total 1565 ms] - SMTP EHLO: 2 (0%)0, SMTP pre-MAIL: 2 (0%)0, SMTP pre-DATA-flush: 2 (0%)0, SMTP DATA: 37 (2%)3, body_digest: 1 (0%)3, gen_mail_id: 0 (0%)3, mime_decode: 4 (0%)3, get-file-type1: 9 (1%)4, decompose_part: 0 (0%)4, parts_decode: 0 (0%)4, AV-scan-1: 5 (0%)4, AV-scan-2: 1 (0%)4, AV-scan-3: 1377 (88%)92, spam-wb-list: 3 (0%)92, SA msg read: 1 (0%)92, SA parse: 1 (0%)92, SA check: 51 (3%)96, SA finish: 2 (0%)96, update_cache: 1 (0%)96, decide_mail_destiny: 1 (0%)96, fwd-connect: 7 (0%)96, fwd-mail-from: 1 (0%)96, fwd-rcpt-to: 2 (0%)96, fwd-data-cmd: 0 (0%)97, write-header: 1 (0%)97, fwd-data-contents: 0 (0%)97, fwd-data-end: 42 (3%)99, fwd-rundown: 1 (0%)99, prepare-dsn: 1 (0%)99, main_log_entry: 8 (0%)100, update_snmp: 1 (0%)100, unlink-1-files: 1 (0%)100, rundown: 0 (0%)100
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 250 2.6.0 Ok, id=27005-03, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as DE1E14802A6
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 6: was busy, 1560.9 ms, total idle 1032.670 s, busy 4.892 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, 5: was idle, 0.2 ms, total idle 1032.670 s, busy 4.892 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP< QUIT\r\n
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_my_time     480 s, SMTP QUIT received
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-new closing transmission channel
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) switch_to_client_time 480 s, smtp response sent
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) SMTP session over, timer stopped
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) exiting process_request
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) post_process_request_hook: timer was not running
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) idle_proc, bye: was busy, 1.9 ms, total idle 1032.670 s, busy 4.894 s
Nov  9 21:29:50 cassiopeia.noro-online.de /usr/sbin/amavisd-new[27005]: (27005-03) load: 0 %, total idle 1032.670 s, busy 4.894 s