View Single Post
  #5  
Old 28th February 2010, 05:18
aclhkaclhk aclhkaclhk is offline
Junior Member
 
Join Date: Aug 2009
Posts: 29
Thanks: 0
Thanked 0 Times in 0 Posts
Default

below is the debug log:
from=<root@ispconfig3.123.com>, size=1170, nrcpt=1 (queue active)
Feb 28 10:53:35 ispconfig3 sendmail[5597]: o1S2rFqD005597: to=123@abc.com, 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:<root@ispconfig3.123.com> 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, "root@ispconfig3.123.com" does not match
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) ESMTP> 250 2.1.0 Sender <root@ispconfig3.123.com> 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:<123@abc.com> ORCPT=rfc822;123@abc.com\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 <123@abc.com> 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: <root@ispconfig3.123.com> -> <123@abc.com> 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 <123@abc.com>; 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] <root@ispconfig3.123.com> -> <123@abc.com>
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) 2822.From: <sender@example.net>, 2821.Mail_From: <root@ispconfig3.123.com>
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_acl(123@abc.com), no match
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [local_domains] => undef, "123@abc.com" does not match
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) query_keys: 123@abc.com, 123@, abc.com, .abc.com, .com, .
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash(123@abc.com), no matches
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_virus_checks] => undef, "123@abc.com" does not match
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) query_keys: 123@abc.com, 123@, abc.com, .abc.com, .com, .
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash(123@abc.com), no matches
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_banned_checks] => undef, "123@abc.com" does not match
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) query_keys: 123@abc.com, 123@, abc.com, .abc.com, .com, .
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash(123@abc.com), no matches
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_spam_checks] => undef, "123@abc.com" 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 <root@ispconfig3.123.com> (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: 123@abc.com, 123@, abc.com, .abc.com, .com, .
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash(123@abc.com), no matches
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_header_checks] => undef, "123@abc.com" 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: 123@abc.com, 123@, abc.com, .abc.com, .com, .
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup_hash(123@abc.com), no matches
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) lookup [bypass_header_checks] => undef, "123@abc.com" 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 "123@abc.com", results: "(constantEFAULT)"=>"DEFAULT"
Feb 28 10:53:35 ispconfig3 amavis[5003]: (05003-04) collect banned table[0]: 123@abc.com, 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 123@abc.com 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:123@abc.com] => 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 123@abc.com: "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 <root@ispconfig3.123.com>, <sender@example.net>
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [blacklist_recip<123@abc.com>] => undef, "123@abc.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: root@ispconfig3.123.com, root@, ispconfig3.123.com, .ispconfig3.123.com, .123.com, .com, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(root@ispconfig3.123.com), no matches
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [blacklist_sender<root@ispconfig3.123.com>,blacklis t_sender] => undef, "root@ispconfig3.123.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [whitelist_recip<123@abc.com>] => undef, "123@abc.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: root@ispconfig3.123.com, root@, ispconfig3.123.com, .ispconfig3.123.com, .123.com, .com, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(root@ispconfig3.123.com), no matches
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [whitelist_sender<root@ispconfig3.123.com>,whitelis t_sender] => undef, "root@ispconfig3.123.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: 123@abc.com, 123@, abc.com, .abc.com, .com, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(123@abc.com) matches keys: "."=>ARRAY(0xe39bae0)
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [score_recip<123@abc.com>,score_sender], 1 matches for "123@abc.com", results: "."=>[Amavis::Lookup::RE=ARRAY(0xe39b820),HASH(0xe39b8b0 )]
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_re("root@ispconfig3.123.com"), no matches
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: root@ispconfig3.123.com, root@, ispconfig3.123.com, .ispconfig3.123.com, .123.com, .com, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(root@ispconfig3.123.com), no matches
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [score_sender<root@ispconfig3.123.com>] => undef, "root@ispconfig3.123.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [blacklist_recip<123@abc.com>] => undef, "123@abc.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: sender@example.net, sender@, example.net, .example.net, .net, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(sender@example.net), no matches
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [blacklist_sender<sender@example.net>,blacklist_sen der] => undef, "sender@example.net" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [whitelist_recip<123@abc.com>] => undef, "123@abc.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: sender@example.net, sender@, example.net, .example.net, .net, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(sender@example.net), no matches
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [whitelist_sender<sender@example.net>,whitelist_sen der] => undef, "sender@example.net" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: 123@abc.com, 123@, abc.com, .abc.com, .com, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(123@abc.com) matches keys: "."=>ARRAY(0xe39bae0)
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [score_recip<123@abc.com>,score_sender], 1 matches for "123@abc.com", results: "."=>[Amavis::Lookup::RE=ARRAY(0xe39b820),HASH(0xe39b8b0 )]
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_re("sender@example.net"), no matches
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: sender@example.net, sender@, example.net, .example.net, .net, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(sender@example.net) matches key "sender@example.net", result=3
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [score_sender<sender@example.net>] => true, "sender@example.net" matches, result="3", matching_key="sender@example.net"
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) wbl: soft-blacklisted (3) sender <sender@example.net> => <123@abc.com>, 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, "123@abc.com" 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, "123@abc.com" matches, result="6.2", matching_key="(constant:6.2)"
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_tag3_level] => undef, "123@abc.com" 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, "123@abc.com" 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 123@abc.com
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) final_destiny (ccat=5) is PASS, recip 123@abc.com
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) final_destiny (ccat=0) is PASS, recip 123@abc.com
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) final_destiny PASS, recip 123@abc.com
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, "123@abc.com" 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, "123@abc.com" 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, "123@abc.com" 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, "123@abc.com" 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, "123@abc.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) query_keys: 123@abc.com, 123@, abc.com, .abc.com, .com, .
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup_hash(123@abc.com), no matches
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_admin] => undef, "123@abc.com" does not match
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) lookup [spam_quarantine_bysender_to] => undef, "root@ispconfig3.123.com" 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: <123@abc.com>\n
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) header: X-Envelope-From: <root@ispconfig3.123.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 <123@abc.com>;\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, <root@ispconfig3.123.com> -> <123@abc.com>, 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, "123@abc.com" 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, "123@abc.com" matches, result="6.2", matching_key="(constant:6.2)"
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) headers CLUSTERING: NEW CLUSTER <123@abc.com>: 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 <123@abc.com>;\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: <sender@example.net>
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) (about to connect to [127.0.0.1]:10025) FWD via SMTP: <root@ispconfig3.123.com> -> <123@abc.com>
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:<root@ispconfig3.123.com> BODY=7BIT
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) smtp cmd> RCPT TO:<123@abc.com> ORCPT=rfc822;123@abc.com
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:<root@ispconfig3.123.com> BODY=7BIT\r\nRCPT TO:<123@abc.com> ORCPT=rfc822;123@abc.com\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) (<123@abc.com>): 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=<GTUBE1.1010101@example.net>
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=<root@ispconfig3.123.com>, 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 (<123@abc.com>): 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: <root@ispconfig3.123.com> -> <123@abc.com>,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, <root@ispconfig3.123.com>
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, "root@ispconfig3.123.com" matches, result="18", matching_key="(constant:18)"
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) dsn: from MTA 250 NonBlocking:Spam <root@ispconfig3.123.com> -> <123@abc.com>: 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: <root@ispconfig3.123.com> -> <123@abc.com>
Feb 28 10:53:37 ispconfig3 amavis[5003]: (05003-04) one_response_for_all <root@ispconfig3.123.com>: 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] <root@ispconfig3.123.com> -> <123@abc.com>, quarantine: spam-gTE40LXjoxuo.gz, Message-ID: <GTUBE1.1010101@example.net>, 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=<123@abc.com>, 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=<123@abc.com>, 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, user=123@abc.com, ip=[::ffff:127.0.0.1], port=[59528], protocol=IMAP
Feb 28 10:53:48 ispconfig3 imapd: LOGOUT, user=123@abc.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=87, sent=392, time=0
Reply With Quote