amavis rejects all inbound emails

Discussion in 'Installation/Configuration' started by aclhkaclhk, Feb 27, 2010.

  1. aclhkaclhk

    aclhkaclhk New Member

    i did a fresh install according to
    http://www.howtoforge.com/perfect-server-centos-5.4-x86_64-ispconfig-3

    I created a domain and mailbox with spam policy "uncensored".

    All incoming emails are blocked and returned according to /var/log/maillog

    I want to have all incoming email received with [spam] tag in the subject without rejection.

    I have changed the uncensor policy as below:

    - spam dsn: 999 or 0
    - spam: quarantine: 999 or 0
    - spam subject: yes

    pls advise.
     
  2. aclhkaclhk

    aclhkaclhk New Member

    below is the email log:

    Feb 27 13:37:29 ispconfig3 amavis[5245]: (05245-01) Blocked SPAM, MYNETS LOCAL [192.168.123.238] [192.168.123.238] <[email protected]> -> <[email protected]>, quarantine: spam-kB1dQ+EgK3NW.gz, Message-ID: <[email protected]>, mail_id: kB1dQ+EgK3NW, Hits: 9.54, size: 315, 1913 ms
    Feb 27 13:37:29 ispconfig3 postfix/smtp[5257]: 52002130E78: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=17, delays=15/0.01/0.01/1.9, dsn=2.5.0, status=sent (250 2.5.0 Ok, id=05245-01, BOUNCE)

    it seems that spamassassin was not launched. the email was rejected by amavis.

    i do not want amavis to reject email.
     
  3. till

    till Super Moderator Staff Member ISPConfig Developer

    amavisd is spamassassin, as amavisd is a daemon that uses the spamassassin libraries.

    To your original question, change:

    $final_spam_destiny = D_DISCARD;

    to:

    $final_spam_destiny = D_PASS;

    in amavisd.conf or the 50-user file in the amavisd directory (depends on the linux distribution) and restart amavisd.
     
  4. aclhkaclhk

    aclhkaclhk New Member

    thanks, I received all emails but there was no spam header inserted.

    I set the spam policy to normal, spam tag=0, modifies subject=yes.
    I tried a testing:
    sendmail -i [email protected] < sample-spam-GTUBE-junk.txt

    no X-Spam-Status and X-Spam-Level in header fields.

    pls advise
     
  5. aclhkaclhk

    aclhkaclhk New Member

    below is the debug log:
    from=<[email protected]>, size=1170, nrcpt=1 (queue active)
    Feb 28 10:53:35 ispconfig3 sendmail[5597]: o1S2rFqD005597: [email protected], ctladdr=root (0/0), delay=00:00:20, xdelay=00:00:10, mailer=relay, pri=210799, relay=[127.0.0.1] [127.0.0.1], dsn=2.0.0, stat=Sent (Ok: queued as 7DE05130F2A)
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-03-4) Net::Server: 2010/02/28-10:53:35 CONNECT TCP Peer: "127.0.0.1:35914" Local: "127.0.0.1:10024"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-03-4) idle_proc, hi : was idle, 207790.0 ms, total idle 638.035 s, busy 29.391 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-03-4) loaded base policy bank
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-03-4) lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-03-4) process_request: fileno sock=12, STDIN=0, STDOUT=1
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-03-4) switch_to_my_time 480 s, new request
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-03-4) process_request: suggested_protocol="" on TCP
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 4: was busy, 2.1 ms, total idle 638.035 s, busy 29.393 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 5: was idle, 0.5 ms, total idle 638.035 s, busy 29.393 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) SMTP< EHLO ispconfig3.123.com\r\n
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_my_time 480 s, SMTP EHLO received
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250-[127.0.0.1]
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250-VRFY
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250-PIPELINING
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250-SIZE
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250-ENHANCEDSTATUSCODES
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250-8BITMIME
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250-DSN
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250 XFORWARD NAME ADDR PORT PROTO HELO SOURCE
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 6: was busy, 1.5 ms, total idle 638.035 s, busy 29.395 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 5: was idle, 0.3 ms, total idle 638.036 s, busy 29.395 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP< XFORWARD NAME=localhost.localdomain ADDR=127.0.0.1\r\n
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_my_time 480 s, SMTP XFORWARD received
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250 2.5.0 Ok XFORWARD
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 6: was busy, 0.6 ms, total idle 638.036 s, busy 29.395 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 5: was idle, 0.1 ms, total idle 638.036 s, busy 29.395 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP< XFORWARD PROTO=ESMTP HELO=ispconfig3.123.com SOURCE=LOCAL\r\n
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_my_time 480 s, SMTP XFORWARD received
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250 2.5.0 Ok XFORWARD
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 6: was busy, 0.5 ms, total idle 638.036 s, busy 29.396 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 5: was idle, 0.3 ms, total idle 638.036 s, busy 29.396 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP< MAIL FROM:<[email protected]> SIZE=1170\r\n
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_my_time 480 s, SMTP MAIL received
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) check_mail_begin_task: task_count=7
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_ip_acl: key="127.0.0.1" matches "127.0.0.0/8", result=1
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) loaded policy bank "MYNETS"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [debug_sender] => undef, "[email protected]" does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250 2.1.0 Sender <[email protected]> OK
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 6: was busy, 1.6 ms, total idle 638.036 s, busy 29.397 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 5: was idle, 0.1 ms, total idle 638.036 s, busy 29.397 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP< RCPT TO:<[email protected]> ORCPT=rfc822;[email protected]\r\n
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_my_time 480 s, SMTP RCPT received
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250 2.1.5 Recipient <[email protected]> OK
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 6: was busy, 0.9 ms, total idle 638.036 s, busy 29.398 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) idle_proc, 5: was idle, 0.1 ms, total idle 638.036 s, busy 29.398 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP< DATA\r\n
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_my_time 480 s, SMTP DATA received
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP::10024 /var/amavis/tmp/amavis-20100228T104228-05003: <[email protected]> -> <[email protected]> SIZE=1170 Received: from ispconfig3.123.com ([127.0.0.1]) by localhost (ispconfig3.123.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <[email protected]>; Sun, 28 Feb 2010 10:53:35 +0800 (HKT)
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 354 End data with <CR><LF>.<CR><LF>
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, receiving data
    Feb 28 10:53:35 ispconfig3 postfix/smtpd[5598]: disconnect from localhost.localdomain[127.0.0.1]
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) switch_to_my_time 480 s, data-end received
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP< .<CR><LF>
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) smtp connection cache, dt: 209.8, state: 1
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) smtp connection cache, dt: 209.8 -> disabling
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer digest_init: timer set to 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) get_body_digest: reading header section
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer digest_hdr: timer set to 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) get_body_digest: reading mail body
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer digest_body: timer set to 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) body type (ESMTP BODY): unlabeled, good (h=0, b=0)
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) body hash: fbfaa7ae2ce107ec1ee6598c72d0e04f
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Original mail size: 1170; quota set to: 585000 bytes
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Checking: gTE40LXjoxuo MYNETS [127.0.0.1] <[email protected]> -> <[email protected]>
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) 2822.From: <[email protected]>, 2821.Mail_From: <[email protected]>
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_acl([email protected]), no match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [local_domains] => undef, "[email protected]" does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], 123@, abc.com, .abc.com, .com, .
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_virus_checks] => undef, "[email protected]" does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], 123@, abc.com, .abc.com, .com, .
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_banned_checks] => undef, "[email protected]" does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], 123@, abc.com, .abc.com, .com, .
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_spam_checks] => undef, "[email protected]" does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Cached virus check expired, TTL = 180 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) cached fbfaa7ae2ce107ec1ee6598c72d0e04f from <[email protected]> (0,1)
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) cache entry age: S c=20100228T024228 a=20100228T024413
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Extracting mime components
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Issued a new file name: p001
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Charging 505 bytes to remaining quota 585000 (out of 585000, (0%)) - by mime_decode
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) p001 1 Content-Type: text/plain, size: 505 B, name:
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer mime_decode: remaining time = 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer mime_decode-1: remaining time = 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) inspect_dsn: parts: text/plain
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) inspect_dsn: not a bounce
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer dsn_parse: remaining time = 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) decode_parts: level=1, #parts=1 : p001
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) running file(1) on 1 files, arglist size 18
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) run_command: [5608] /usr/bin/file p001 </dev/null 2>&1
    Feb 28 10:53:35 ispconfig3 amavis[5608]: (05003-04) open_on_specific_fd: target fd0 closing, to become < /dev/null
    Feb 28 10:53:35 ispconfig3 amavis[5608]: (05003-04) open_on_specific_fd: target fd1 closing, to become > &=16
    Feb 28 10:53:35 ispconfig3 amavis[5608]: (05003-04) open_on_specific_fd: target fd1 dup2 from fd16 > &=16
    Feb 28 10:53:35 ispconfig3 amavis[5608]: (05003-04) open_on_specific_fd: source fd16 closed
    Feb 28 10:53:35 ispconfig3 amavis[5608]: (05003-04) open_on_specific_fd: target fd2 closing, to become > &1
    Feb 28 10:53:35 ispconfig3 amavis[5608]: (05003-04) open_on_specific_fd: target fd2 dup2 from fd1 > &1
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) result line from file(1): p001: ASCII English text\n
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_re("ASCII English text") matches key "(?i-xsm:^(ASCII|text)\b)", result="asc"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [map_full_type_to_short_type] => true, "ASCII English text" matches, result="asc", matching_key="(?i-xsm:^(ASCII|text)\\b)"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) File-type of p001: ASCII English text; (asc)
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) do_ascii: Decoding part p001
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) do_ascii: Setting sigaction handler, was 0
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) timer set to 320 s (was 480 s)
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) do_ascii: Decoding part p001 (0 items), uulib V0.5pl20
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer do_ascii: timer set to 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) decompose_part: p001 - atomic
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer parts_decode: remaining time = 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], 123@, abc.com, .abc.com, .com, .
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_header_checks] => undef, "[email protected]" does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) check_header: 0, OK
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], 123@, abc.com, .abc.com, .com, .
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_header_checks] => undef, "[email protected]" does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Checking for banned types and filenames
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="DEFAULT"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [banned_filename], 1 matches for "[email protected]", results: "(constant:DEFAULT)"=>"DEFAULT"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) collect banned table[0]: [email protected], tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0xe39b580)
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) starting banned checks - traversing message structure tree
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) check_for_banned (p001) text/plain,.asc
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) doing banned check for [email protected] on text/plain,.asc
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_re(["text/plain",".asc"]), no matches
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [check_bann:[email protected]] => undef, ["text/plain",".asc"] does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [banned_namepath_re] => undef, "P=p001\tL=1\tM=text/plain\tT=asc" does not match
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) p.path [email protected]: "P=p001,L=1,M=text/plain,T=asc"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) banned check: any=0, all=N (1)
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_re("MAIL") matches key "(?-xism:^MAIL$)", result="1"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [keep_decoded_original] => true, "MAIL" matches, result="1", matching_key="(?-xism:^MAIL$)"
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Issued a new file name: p002
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) presenting full original message to scanners as /var/amavis/tmp/amavis-20100228T104228-05003/parts/p002
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) (!!)WARN: all primary virus scanners failed, considering backups
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) Calling virus scanners, 2 files to scan in /var/amavis/tmp/amavis-20100228T104228-05003/parts
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) run_av (ClamAV-clamscan): query template(0,1): --stdout --no-summary -r --tempdir=/var/amavis/tmp {}
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer run_av: timer set to 480 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) prolong_timer run_av: timer set to 384 s
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) run_av Using (ClamAV-clamscan): /usr/bin/clamscan --stdout --no-summary -r --tempdir=/var/amavis/tmp /var/amavis/tmp/amavis-20100228T104228-05003/parts
    Feb 28 10:53:35 ispconfig3 amavis[5609]: (05003-04) open_on_specific_fd: target fd0 closing, to become < /dev/null
    Feb 28 10:53:35 ispconfig3 amavis[5609]: (05003-04) open_on_specific_fd: target fd1 closing, to become > &=16
    Feb 28 10:53:35 ispconfig3 amavis[5609]: (05003-04) open_on_specific_fd: target fd1 dup2 from fd16 > &=16
    Feb 28 10:53:35 ispconfig3 amavis[5609]: (05003-04) open_on_specific_fd: source fd16 closed
    Feb 28 10:53:35 ispconfig3 amavis[5609]: (05003-04) open_on_specific_fd: target fd2 closing, to become > &1
    Feb 28 10:53:35 ispconfig3 amavis[5609]: (05003-04) open_on_specific_fd: target fd2 dup2 from fd1 > &1
    Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) run_command: [5609] /usr/bin/clamscan --stdout --no-summary -r --tempdir=/var/amavis/tmp /var/amavis/tmp/amavis-20100228T104228-05003/parts </dev/null 2>&1
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) collect_results from [5609] (ClamAV-clamscan), 120 bytes, (limit 204800)
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) prolong_timer run_av: timer set to 478 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) run_av: /usr/bin/clamscan exit 0, /var/amavis/tmp/amavis-20100228T104228-05003/parts/p002: OK\n/var/amavis/tmp/amavis-20100228T104228-05003/parts/p001: OK
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) run_av (ClamAV-clamscan): CLEAN
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) run_av (ClamAV-clamscan) result: clean
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) wbl: checking sender <[email protected]>, <[email protected]>
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [blacklist_recip<[email protected]>] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], root@, ispconfig3.123.com, .ispconfig3.123.com, .123.com, .com, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [blacklist_sender<[email protected]>,blacklist_sender] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [whitelist_recip<[email protected]>] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], root@, ispconfig3.123.com, .ispconfig3.123.com, .123.com, .com, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [whitelist_sender<[email protected]>,whitelist_sender] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], 123@, abc.com, .abc.com, .com, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]) matches keys: "."=>ARRAY(0xe39bae0)
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [score_recip<[email protected]>,score_sender], 1 matches for "[email protected]", results: "."=>[Amavis::Lookup::RE=ARRAY(0xe39b820),HASH(0xe39b8b0)]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_re("[email protected]"), no matches
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], root@, ispconfig3.123.com, .ispconfig3.123.com, .123.com, .com, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [score_sender<[email protected]>] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [blacklist_recip<[email protected]>] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], sender@, example.net, .example.net, .net, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [blacklist_sender<[email protected]>,blacklist_sender] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [whitelist_recip<[email protected]>] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], sender@, example.net, .example.net, .net, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [whitelist_sender<[email protected]>,whitelist_sender] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], 123@, abc.com, .abc.com, .com, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]) matches keys: "."=>ARRAY(0xe39bae0)
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [score_recip<[email protected]>,score_sender], 1 matches for "[email protected]", results: "."=>[Amavis::Lookup::RE=ARRAY(0xe39b820),HASH(0xe39b8b0)]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_re("[email protected]"), no matches
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], sender@, example.net, .example.net, .net, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]) matches key "[email protected]", result=3
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [score_sender<[email protected]>] => true, "[email protected]" matches, result="3", matching_key="[email protected]"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) wbl: soft-blacklisted (3) sender <[email protected]> => <[email protected]>, recip_key="."
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) spam_presence cached, skipping spam_scan
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) enqueue: stat is not numeric: ""
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="2"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_tag_level] => true, "[email protected]" matches, result="2", matching_key="(constant:2)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="6.2"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_tag2_level] => true, "[email protected]" matches, result="6.2", matching_key="(constant:6.2)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_tag3_level] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="6.9"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_kill_level] => true, "[email protected]" matches, result="6.9", matching_key="(constant:6.9)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) final_destiny (ccat=6) is PASS, recip [email protected]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) final_destiny (ccat=5) is PASS, recip [email protected]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) final_destiny (ccat=0) is PASS, recip [email protected]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) final_destiny PASS, recip [email protected]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) do_notify_and_quar: ccat=Spam (6,0) ("6":Spam, "5":Spammy, "1,1":CleanTag, "1":Clean, "0":CatchAll) ccat_block=(), qar_mth=
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="2"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_tag_level] => true, "[email protected]" matches, result="2", matching_key="(constant:2)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="6.2"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_tag2_level] => true, "[email protected]" matches, result="6.2", matching_key="(constant:6.2)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="6.9"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_kill_level] => true, "[email protected]" matches, result="6.9", matching_key="(constant:6.9)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="spam-quarantine"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_quarantine_to] => true, "[email protected]" matches, result="spam-quarantine", matching_key="(constant:spam-quarantine)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_quarantine_cutoff_level] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: [email protected], 123@, abc.com, .abc.com, .com, .
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash([email protected]), no matches
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_admin] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_quarantine_bysender_to] => undef, "[email protected]" does not match
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Spam-Flag: YES\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Spam-Score: 1003.88\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Spam-Level: ****************************************************************\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Spam-Status: Yes, score=1000.88+3 tag=2 tag2=6.2 kill=6.9 tests=[AM:BOOST=3,\n\tALL_TRUSTED=-1.44, DATE_IN_PAST_96_XX=2.32, GTUBE=1000]\n\tautolearn=unavailable\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Quarantine-ID: <gTE40LXjoxuo>\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Envelope-To-Blocked: \n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Envelope-To: <[email protected]>\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Envelope-From: <[email protected]>\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: Received: from ispconfig3.123.com ([127.0.0.1])\n\tby localhost (ispconfig3.123.com [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id gTE40LXjoxuo for <[email protected]>;\n\tSun, 28 Feb 2010 10:53:35 +0800 (HKT)\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) DO_QUARANTINE, local:spam-%m.gz, <> -> spam-quarantine
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) dkim: not signing mail which is not originating from inside
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) local delivery: <> -> spam-quarantine, mbx=/var/virusmails/spam-gTE40LXjoxuo.gz
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: Delivered-To: spam-quarantine\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: Return-Path: <>\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) write_header: 0, Amavis::IO::Zlib=HASH(0x1193dad0)
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) one_response_for_all <>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.6.0 Ok, delivered to /var/virusmails/spam-gTE40LXjoxuo.gz, id=05003-04'
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) DO_QUARANTINE done
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) SPAM, <[email protected]> -> <[email protected]>, Yes, score=1000.88+3 tag=2 tag2=6.2 kill=6.9 tests=[AM:BOOST=3, ALL_TRUSTED=-1.44, DATE_IN_PAST_96_XX=2.32, GTUBE=1000] autolearn=unavailable, quarantine gTE40LXjoxuo (spam-quarantine)
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) skip admin notification, no administrators
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) do_notify_and_quarantine - done
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="2"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_tag_level] => true, "[email protected]" matches, result="2", matching_key="(constant:2)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="6.2"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_tag2_level] => true, "[email protected]" matches, result="6.2", matching_key="(constant:6.2)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) headers CLUSTERING: NEW CLUSTER <[email protected]>: score=1000.88+3, tag=1, tag2=1, local=0, bl=, s=, mangle=
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Virus-Scanned: amavisd-new at example.com\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: Received: from ispconfig3.123.com ([127.0.0.1])\n\tby localhost (ispconfig3.123.com [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id gTE40LXjoxuo for <[email protected]>;\n\tSun, 28 Feb 2010 10:53:35 +0800 (HKT)\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) headers CLUSTERING: done all 1 recips in one go
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) dkim: not signing spam (from inside), From: <[email protected]>
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) (about to connect to [127.0.0.1]:10025) FWD via SMTP: <[email protected]> -> <[email protected]>
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp session reuse, 4 transactions so far
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) establish_or_refresh, state: ehlo
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp cmd> NOOP
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: needline=0, flush=1, wr=1, timeout=15
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: sending
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop sent 6> NOOP\r\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: needline=1, flush=0, wr=0, timeout=15
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: receiving
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop read 14 chars< 250 2.0.0 Ok\r\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp resp to NOOP (idle 211.4 s): 250 2.0.0 Ok
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) Remote host presents itself as: ispconfig3.123.com, handles DSN, handles PIPELINING
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) AUTH not needed, user='', MTA offers 'LOGIN PLAIN'
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp cmd> MAIL FROM:<[email protected]> BODY=7BIT
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp cmd> RCPT TO:<[email protected]> ORCPT=rfc822;[email protected]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp cmd> DATA
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: needline=0, flush=1, wr=1, timeout=120
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: sending
    Feb 28 02:53:37 ispconfig3 postfix/smtpd[5153]: 39C0F130F2C: client=unknown[127.0.0.1]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop sent 103> MAIL FROM:<[email protected]> BODY=7BIT\r\nRCPT TO:<[email protected]> ORCPT=rfc822;[email protected]\r\nDATA\r\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: needline=1, flush=0, wr=0, timeout=300
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: receiving
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) 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
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp resp to MAIL (pip): 250 2.1.0 Ok
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp resp to RCPT (pip) (<[email protected]>): 250 2.1.5 Ok
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp resp to DATA: 354 End data with <CR><LF>.<CR><LF>
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) write_header: 0, Amavis::Out::SMTP::protocol=HASH(0x10dae890)
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp cmd> QUIT
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: needline=0, flush=1, wr=1, timeout=478
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: sending
    Feb 28 10:53:37 ispconfig3 postfix/cleanup[5606]: 39C0F130F2C: message-id=<[email protected]>
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop sent 1439> X-Virus-Scanned: amavisd-new at example.com\r\nReceived: from ispconfig3.123.com ([127.0.0.1])\r\n\tby localhost (ispconfig3.123.com [127.0.0.1]) (amavisd-new, port 10024)\r\n\twith ESMTP id gTE40LXjoxuo [...]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: needline=1, flush=0, wr=0, timeout=478
    Feb 28 10:53:37 ispconfig3 postfix/qmgr[2250]: 39C0F130F2C: from=<[email protected]>, size=1599, nrcpt=1 (queue active)
    Feb 28 02:53:37 ispconfig3 postfix/smtpd[5153]: disconnect from unknown[127.0.0.1]
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop: receiving
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rw_loop read 52 chars< 250 2.0.0 Ok: queued as 39C0F130F2C\r\n221 2.0.0 Bye\r\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp resp to data-dot (<[email protected]>): 250 2.0.0 Ok: queued as 39C0F130F2C
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) Amavis::Out::SMTP::Session close, disconnecting
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) prolong_timer fwd-end-chkpnt: timer set to 478 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) FWD via SMTP: <[email protected]> -> <[email protected]>,BODY=7BIT 250 2.0.0 Ok, id=05003-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 39C0F130F2C
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) prolong_timer forwarding: remaining time = 478 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) DSN: sender is credible (orig), SA: 1000.880, <[email protected]>
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup: (scalar) matches, result="18"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup => true, "[email protected]" matches, result="18", matching_key="(constant:18)"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) dsn: from MTA 250 NonBlocking:Spam <[email protected]> -> <[email protected]>: 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=05003-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 39C0F130F2C"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) DSN: SUCC from MTA 250 NonBlocking:Spam, no DSN requested: <[email protected]> -> <[email protected]>
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) one_response_for_all <[email protected]>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.0.0 Ok, id=05003-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 39C0F130F2C'
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) notif=N, suppressed=0, ndn_needed=0, exit=0, 250 2.0.0 Ok, id=05003-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 39C0F130F2C
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) prolong_timer delivery-notification: remaining time = 478 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) fish_out_ip_from_received: 127.0.0.1
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_ip_acl (publicnetworks): key="127.0.0.1" matches "!127.0.0.0/8", result=0
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) parse_ip_address_from_received: 127.0.0.1
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) Passed SPAM, MYNETS LOCAL [127.0.0.1] [127.0.0.1] <[email protected]> -> <[email protected]>, quarantine: spam-gTE40LXjoxuo.gz, Message-ID: <[email protected]>, mail_id: gTE40LXjoxuo, Hits: 1003.88, size: 1170, queued_as: 39C0F130F2C, 1669 ms
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) prolong_timer main_log_entry: remaining time = 478 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) updating snmp variables
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) prolong_timer check done: remaining time = 478 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) sending SMTP response: "250 2.0.0 Ok, id=05003-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 39C0F130F2C"
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250 2.0.0 Ok, id=05003-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 39C0F130F2C
    Feb 28 10:53:37 ispconfig3 postfix/smtp[5607]: 7DE05130F2A: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=12, delays=10/0.01/0.01/1.7, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=05003-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 39C0F130F2C)
    Feb 28 10:53:37 ispconfig3 postfix/qmgr[2250]: 7DE05130F2A: removed
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) TempDir::strip: /var/amavis/tmp/amavis-20100228T104228-05003
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) rmdir_recursively: /var/amavis/tmp/amavis-20100228T104228-05003/parts, excl=1
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) TIMING [total 1693 ms] - SMTP greeting: 2 (0%)0, SMTP EHLO: 2 (0%)0, SMTP pre-MAIL: 2 (0%)0, SMTP pre-DATA-flush: 3 (0%)1, SMTP DATA: 28 (2%)2, check_init: 1 (0%)2, digest_hdr: 1 (0%)2, digest_body_dkim: 1 (0%)2, gen_mail_id: 1 (0%)2, mime_decode: 8 (0%)3, get-file-type1: 26 (2%)4, decompose_part: 2 (0%)5, parts_decode: 0 (0%)5, check_header: 3 (0%)5, AV-scan-1: 1510 (89%)94, spam-wb-list: 8 (0%)94, update_cache: 1 (0%)94, decide_mail_destiny: 3 (0%)95, notif-quar: 5 (0%)95, stat-mbx: 3 (0%)95, open-mbx: 0 (0%)95, write-header: 1 (0%)95, save-to-local-mailbox: 0 (0%)95, fwd-connect: 7 (0%)96, fwd-mail-pip: 7 (0%)96, fwd-rcpt-pip: 0 (0%)96, fwd-data-chkpnt: 0 (0%)96, write-header: 1 (0%)96, fwd-data-contents: 0 (0%)96, fwd-end-chkpnt: 33 (2%)98, prepare-dsn: 2 (0%)98, main_log_entry: 8 (0%)99, update_snmp: 3 (0%)99, SMTP pre-response: 18 (1%)100, SMTP response: 1 (0%)100, unlink-2-files: 1 (0%)100, rundown: 1 (0%)100
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) idle_proc, 6: was busy, 1684.9 ms, total idle 638.036 s, busy 31.083 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) idle_proc, 5: was idle, 0.2 ms, total idle 638.036 s, busy 31.083 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) ESMTP< QUIT\r\n
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) switch_to_my_time 480 s, SMTP QUIT received
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-new closing transmission channel
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) switch_to_client_time 480 s, smtp response sent
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) SMTP session over, timer stopped
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) exiting process_request
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) post_process_request_hook: timer was not running
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) idle_proc, bye: was busy, 2.6 ms, total idle 638.036 s, busy 31.086 s
    Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) load: 5 %, total idle 638.036 s, busy 31.086 s
    Feb 28 10:53:37 ispconfig3 postfix/pipe[5611]: 39C0F130F2C: to=<[email protected]>, relay=maildrop, delay=0.1, delays=0.02/0.01/0/0.07, dsn=2.0.0, status=sent (delivered via maildrop service)
    Feb 28 10:53:37 ispconfig3 postfix/qmgr[2250]: 39C0F130F2C: removed
    Feb 28 10:53:48 ispconfig3 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 28 10:53:48 ispconfig3 imapd: LOGIN, [email protected], ip=[::ffff:127.0.0.1], port=[59528], protocol=IMAP
    Feb 28 10:53:48 ispconfig3 imapd: LOGOUT, [email protected], ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=87, sent=392, time=0
     
  6. aclhkaclhk

    aclhkaclhk New Member

    if i changed $mydomain = 'abc.com' (default: example.com), spam header was inserted.

    /etc/amavisd.conf
    @local_domains_maps = ( [".$mydomain"] );

    I wonder why it just points to mydomain (which hardcodes a domain name). It should do a db lookup, shouldn't it?
     

Share This Page