Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: loaded base policy bank Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1 Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: process_request: fileno sock=11, STDIN=0, STDOUT=1 Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: switch_to_my_time 480 s, new request Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: process_request: suggested_protocol="" on TCP Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 4: was busy, 5.0 ms, total idle 0.000 s, busy 0.005 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.000 s, busy 0.005 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) SMTP< EHLO mail.hotels-olympus.com\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_my_time 480 s, SMTP EHLO received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250-[127.0.0.1] Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250-VRFY Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250-PIPELINING Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250-SIZE Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250-ENHANCEDSTATUSCODES Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250-8BITMIME Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250-DSN Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 6: was busy, 1.7 ms, total idle 0.000 s, busy 0.007 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 5: was idle, 0.7 ms, total idle 0.001 s, busy 0.007 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP< XFORWARD NAME=unknown ADDR=189.149.130.59\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_my_time 480 s, SMTP XFORWARD received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250 2.5.0 Ok XFORWARD Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 6: was busy, 1.0 ms, total idle 0.001 s, busy 0.008 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.008 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP< XFORWARD PROTO=ESMTP HELO=OLYMPUS03\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_my_time 480 s, SMTP XFORWARD received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: Net::Server: 2007/09/28-21:50:33 CONNECT TCP Peer: "127.0.0.1:54484" Local: "127.0.0.1:10024" Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: loaded base policy bank Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250 2.5.0 Ok XFORWARD Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 6: was busy, 4.3 ms, total idle 0.001 s, busy 0.012 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.012 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP< MAIL FROM: SIZE=15370\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_my_time 480 s, SMTP MAIL received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) check_mail_begin_task: task_count=1 Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) TempDir::prepare: creating directory /var/amavis/tmp/amavis-20070928T215033-01618 Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) TempDir::prepare_file: creating file /var/amavis/tmp/amavis-20070928T215033-01618/email.txt Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) TempDir::prepare_file: layers: stdio Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_ip_acl (mynetworks): key="189.149.130.59", no match Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (debug_sender) => undef, "reservaciones3@olympus-tours.com" does not match Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250 2.1.0 Sender reservaciones3@olympus-tours.com OK Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 6: was busy, 3.4 ms, total idle 0.001 s, busy 0.015 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.001 s, busy 0.015 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP< RCPT TO: ORCPT=rfc822;emelgoza@posadas.com\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_my_time 480 s, SMTP RCPT received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 250 2.1.5 Recipient emelgoza@posadas.com OK Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 6: was busy, 1.2 ms, total idle 0.001 s, busy 0.017 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.002 s, busy 0.017 s lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1 Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP< DATA\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_my_time 480 s, SMTP DATA received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: process_request: fileno sock=11, STDIN=0, STDOUT=1 Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: switch_to_my_time 480 s, new request Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP::10024 /var/amavis/tmp/amavis-20070928T215033-01618: -> SIZE=15370 Received: from mail.hotels-olympus.com ([127.0.0.1]) by localhost (olympus-tours.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for ; Fri, 28 Sep 2007 21:50:33 -0500 (CDT) Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP> 354 End data with . Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_client_time 480 s, receiving data Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: process_request: suggested_protocol="" on TCP Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 4: was busy, 10.7 ms, total idle 0.000 s, busy 0.011 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 5: was idle, 0.8 ms, total idle 0.001 s, busy 0.011 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) SMTP< EHLO mail.hotels-olympus.com\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, SMTP EHLO received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250-[127.0.0.1] Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250-VRFY Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250-PIPELINING Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250-SIZE Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250-ENHANCEDSTATUSCODES Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250-8BITMIME Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250-DSN Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 6: was busy, 1.9 ms, total idle 0.001 s, busy 0.013 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.001 s, busy 0.013 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP< XFORWARD NAME=125-26-239-43.adsl.totbb.net ADDR=125.26.239.43\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, SMTP XFORWARD received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250 2.5.0 Ok XFORWARD Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 6: was busy, 0.8 ms, total idle 0.001 s, busy 0.013 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.013 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP< XFORWARD PROTO=ESMTP HELO=125-26-239-43.adsl.totbb.net\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, SMTP XFORWARD received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250 2.5.0 Ok XFORWARD Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 6: was busy, 1.0 ms, total idle 0.001 s, busy 0.015 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.015 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP< MAIL FROM:<0acuvo-nieuws@netcomuk.co.uk> SIZE=3376\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, SMTP MAIL received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) check_mail_begin_task: task_count=1 Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) TempDir::prepare: creating directory /var/amavis/tmp/amavis-20070928T215033-01619 Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) TempDir::prepare_file: creating file /var/amavis/tmp/amavis-20070928T215033-01619/email.txt Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) TempDir::prepare_file: layers: stdio Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_ip_acl (mynetworks): key="125.26.239.43", no match Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (debug_sender) => undef, "0acuvo-nieuws@netcomuk.co.uk" does not match Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250 2.1.0 Sender 0acuvo-nieuws@netcomuk.co.uk OK Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 6: was busy, 3.2 ms, total idle 0.001 s, busy 0.018 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.002 s, busy 0.018 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP< RCPT TO: ORCPT=rfc822;incentives@olympus-tours.com\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, SMTP RCPT received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 250 2.1.5 Recipient incentives@olympus-tours.com OK Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 6: was busy, 0.9 ms, total idle 0.002 s, busy 0.019 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.002 s, busy 0.019 s Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP< DATA\r\n Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, SMTP DATA received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP::10024 /var/amavis/tmp/amavis-20070928T215033-01619: <0acuvo-nieuws@netcomuk.co.uk> -> SIZE=3376 Received: from mail.hotels-olympus.com ([127.0.0.1]) by localhost (olympus-tours.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for ; Fri, 28 Sep 2007 21:50:33 -0500 (CDT) Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 354 End data with . Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, receiving data Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, data-end received Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP< . Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) setting body type: 8BITMIME (h=1, b=1) Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) body hash: 9298e1607a36d60f0cb5cd75bfe2467d Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Original mail size: 3376; quota set to: 1688000 bytes Sep 28 21:50:33 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Checking: Rax-X3q4+wSo [125.26.239.43] <0acuvo-nieuws@netcomuk.co.uk> -> Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_virus_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Extracting mime components Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Issued a new file name: p001 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Issued a new file name: p002 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) mime_decode_preamble: 2 lines Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Issued a new pseudo part: p003 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) p003 1 Content-Type: multipart/alternative Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) mime_decode_epilogue: 2 lines Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Charging 295 bytes to remaining quota 1688000 (out of 1688000, (0%)) - by mime_decode Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) p001 1/1 Content-Type: text/plain, size: 295 B, name: Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) reparenting p001 from p000 to p003 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Charging 1629 bytes to remaining quota 1687705 (out of 1688000, (0%)) - by mime_decode Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) p002 1/2 Content-Type: text/html, size: 1629 B, name: Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) reparenting p002 from p000 to p003 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer mime_decode-1: remaining time = 480 s Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) decode_parts: level=1, #parts=3 : p001, p002, p003 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) run_command: [1633] /usr/bin/file p001 p002 &1 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1633]: (01619-01) open_on_specific_fd: target fd0 closing, to become < /dev/null Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1633]: (01619-01) open_on_specific_fd: target fd2 closing, to become > &1 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) result line from file(1): p001: ASCII English text Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_re("ASCII English text") matches key "(?i-xsm:^(ASCII|text)\b)", result="asc" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (map_full_type_to_short_type) => true, "ASCII English text" matches, result="asc", matching_key="(?i-xsm:^(ASCII|text)\\b)" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) File-type of p001: ASCII English text; (asc) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) result line from file(1): p002: HTML document text Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_re("HTML document text") matches key "(?-xism:^HTML document text\b)", result="html" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (map_full_type_to_short_type) => true, "HTML document text" matches, result="html", matching_key="(?-xism:^HTML document text\\b)" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) File-type of p002: HTML document text; (html) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) do_ascii: Decoding part p001 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) do_ascii: Setting sigaction handler, was 0 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) timer set to 320 s (was 480 s) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) do_ascii: Decoding part p001 (0 items), uulib V0.5pl20 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) decompose_part: p001 - atomic Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) decompose_part: p002 - atomic Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer parts_decode: remaining time = 0 s Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_header_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) check_header: 0, OK Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_header_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Checking for banned types and filenames Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_banned_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="DEFAULT" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (banned_filename), 1 matches for "incentives@olympus-tours.com", results: "(constant:DEFAULT)"=>"DEFAULT" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) collect banned table[0]: incentives@olympus-tours.com, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8a2d244) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) starting banned checks - traversing message structure tree Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) check_for_banned (p003,p001) multipart/alternative | text/plain,.asc Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) doing banned check for incentives@olympus-tours.com on multipart/alternative | text/plain,.asc Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_re(["multipart/alternative","text/plain",".asc"]), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (check_bann:incentives@olympus-tours.com) => undef, ["multipart/alternative","text/plain",".asc"] does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) p.path incentives@olympus-tours.com: "P=p003,L=1,M=multipart/alternative | P=p001,L=1/1,M=text/plain,T=asc" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) check_for_banned (p003,p002) multipart/alternative | text/html,.html Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) doing banned check for incentives@olympus-tours.com on multipart/alternative | text/html,.html Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_re(["multipart/alternative","text/html",".html"]), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (check_bann:incentives@olympus-tours.com) => undef, ["multipart/alternative","text/html",".html"] does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) p.path incentives@olympus-tours.com: "P=p003,L=1,M=multipart/alternative | P=p002,L=1/2,M=text/html,T=html" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) banned check: any=0, all=N (1) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_re("MAIL"), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (keep_decoded_original) => undef, "MAIL" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Using ClamAV-clamd: (built-in interface) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ask_av (ClamAV-clamd): query template1: CONTSCAN {}\n Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Using (ClamAV-clamd) on dir: CONTSCAN /var/amavis/tmp/amavis-20070928T215033-01619/parts\n Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ClamAV-clamd: Connecting to socket /var/run/clamav/clamd.sock Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ClamAV-clamd: Sending CONTSCAN /var/amavis/tmp/amavis-20070928T215033-01619/parts\n to UNIX socket /var/run/clamav/clamd.sock Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ask_av (ClamAV-clamd) result: /var/amavis/tmp/amavis-20070928T215033-01619/parts: lstat() failed. ERROR\n Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) (!!)ask_av (ClamAV-clamd) FAILED - unexpected result: /var/amavis/tmp/amavis-20070928T215033-01619/parts: lstat() failed. ERROR\n Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) (!!)WARN: all primary virus scanners failed, considering backups Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Using (ClamAV-clamscan): /usr/local/bin/clamscan --stdout --disable-summary -r --tempdir=/var/amavis/tmp /var/amavis/tmp/amavis-20070928T215033-01619/parts Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) timer set to 40 s (was 60 s) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) run_command: [1634] /usr/local/bin/clamscan --stdout --disable-summary -r --tempdir=/var/amavis/tmp /var/amavis/tmp/amavis-20070928T215033-01619/parts &1 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1634]: (01619-01) open_on_specific_fd: target fd0 closing, to become < /dev/null Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1634]: (01619-01) open_on_specific_fd: target fd2 closing, to become > &1 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) switch_to_my_time 480 s, data-end received Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ESMTP< . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Actual message size 15369 B, declared 15370 B Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) setting body type: 8BITMIME (h=1, b=1) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) body hash: ed7b508eb8aae6b42bd813d5cd1d2832 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Original mail size: 15369; quota set to: 7684500 bytes Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Checking: DOr8-reXy1ow [189.149.130.59] -> Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (bypass_virus_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Extracting mime components Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Issued a new file name: p001 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Issued a new file name: p002 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) mime_decode_preamble: 2 lines Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Issued a new pseudo part: p003 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) p003 1 Content-Type: multipart/alternative Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) mime_decode_epilogue: 1 lines Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Charging 2142 bytes to remaining quota 7684500 (out of 7684500, (0%)) - by mime_decode Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) p001 1/1 Content-Type: text/plain, size: 2142 B, name: Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) reparenting p001 from p000 to p003 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Charging 10266 bytes to remaining quota 7682358 (out of 7684500, (0%)) - by mime_decode Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) p002 1/2 Content-Type: text/html, size: 10266 B, name: Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) reparenting p002 from p000 to p003 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer mime_decode-1: remaining time = 480 s Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) decode_parts: level=1, #parts=3 : p001, p002, p003 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1635]: (01618-01) open_on_specific_fd: target fd0 closing, to become < /dev/null Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1635]: (01618-01) open_on_specific_fd: target fd2 closing, to become > &1 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) run_command: [1635] /usr/bin/file p001 p002 &1 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) result line from file(1): p001: Non-ISO extended-ASCII English text Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_re("Non-ISO extended-ASCII English text") matches key "(?-xism:^Non-ISO.*ASCII\b.*\btext\b)", result="txt" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (map_full_type_to_short_type) => true, "Non-ISO extended-ASCII English text" matches, result="txt", matching_key="(?-xism:^Non-ISO.*ASCII\\b.*\\btext\\b)" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) File-type of p001: Non-ISO extended-ASCII English text; (txt) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) result line from file(1): p002: HTML document text Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_re("HTML document text") matches key "(?-xism:^HTML document text\b)", result="html" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (map_full_type_to_short_type) => true, "HTML document text" matches, result="html", matching_key="(?-xism:^HTML document text\\b)" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) File-type of p002: HTML document text; (html) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) decompose_part: p001 - atomic Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) decompose_part: p002 - atomic Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer parts_decode: remaining time = 480 s Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (bypass_header_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) check_header: 0, OK Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (bypass_header_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Checking for banned types and filenames Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (bypass_banned_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup: (scalar) matches, result="DEFAULT" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (banned_filename), 1 matches for "emelgoza@posadas.com", results: "(constant:DEFAULT)"=>"DEFAULT" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) collect banned table[0]: emelgoza@posadas.com, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8a2d244) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) starting banned checks - traversing message structure tree Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) check_for_banned (p003,p001) multipart/alternative | text/plain,.txt Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) doing banned check for emelgoza@posadas.com on multipart/alternative | text/plain,.txt Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_re(["multipart/alternative","text/plain",".txt"]), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (check_bann:emelgoza@posadas.com) => undef, ["multipart/alternative","text/plain",".txt"] does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) p.path emelgoza@posadas.com: "P=p003,L=1,M=multipart/alternative | P=p001,L=1/1,M=text/plain,T=txt" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) check_for_banned (p003,p002) multipart/alternative | text/html,.html Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) doing banned check for emelgoza@posadas.com on multipart/alternative | text/html,.html Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_re(["multipart/alternative","text/html",".html"]), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (check_bann:emelgoza@posadas.com) => undef, ["multipart/alternative","text/html",".html"] does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) p.path emelgoza@posadas.com: "P=p003,L=1,M=multipart/alternative | P=p002,L=1/2,M=text/html,T=html" Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) banned check: any=0, all=N (1) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_re("MAIL"), no matches Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (keep_decoded_original) => undef, "MAIL" does not match Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Using ClamAV-clamd: (built-in interface) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ask_av (ClamAV-clamd): query template1: CONTSCAN {}\n Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Using (ClamAV-clamd) on dir: CONTSCAN /var/amavis/tmp/amavis-20070928T215033-01618/parts\n Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ClamAV-clamd: Connecting to socket /var/run/clamav/clamd.sock Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ClamAV-clamd: Sending CONTSCAN /var/amavis/tmp/amavis-20070928T215033-01618/parts\n to UNIX socket /var/run/clamav/clamd.sock Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) ask_av (ClamAV-clamd) result: /var/amavis/tmp/amavis-20070928T215033-01618/parts: lstat() failed. ERROR\n Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) (!!)ask_av (ClamAV-clamd) FAILED - unexpected result: /var/amavis/tmp/amavis-20070928T215033-01618/parts: lstat() failed. ERROR\n Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) (!!)WARN: all primary virus scanners failed, considering backups Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Using (ClamAV-clamscan): /usr/local/bin/clamscan --stdout --disable-summary -r --tempdir=/var/amavis/tmp /var/amavis/tmp/amavis-20070928T215033-01618/parts Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) timer set to 320 s (was 480 s) Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) run_command: [1636] /usr/local/bin/clamscan --stdout --disable-summary -r --tempdir=/var/amavis/tmp /var/amavis/tmp/amavis-20070928T215033-01618/parts &1 Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1636]: (01618-01) open_on_specific_fd: target fd0 closing, to become < /dev/null Sep 28 21:50:34 olympus-tours.com /usr/local/sbin/amavisd[1636]: (01618-01) open_on_specific_fd: target fd2 closing, to become > &1 Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer run_av: timer set to = 58 s Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) run_av: /usr/local/bin/clamscan exit 0, /var/amavis/tmp/amavis-20070928T215033-01619/parts/p001: OK\n/var/amavis/tmp/amavis-20070928T215033-01619/parts/p002: OK Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) run_av (ClamAV-clamscan): CLEAN Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_virus_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_spam_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) wbl: checking sender <0acuvo-nieuws@netcomuk.co.uk> Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (blacklist_recip) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: 0acuvo-nieuws@netcomuk.co.uk, 0acuvo-nieuws@, netcomuk.co.uk, .netcomuk.co.uk, .co.uk, .uk, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(0acuvo-nieuws@netcomuk.co.uk), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (blacklist_sender) => undef, "0acuvo-nieuws@netcomuk.co.uk" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (whitelist_recip) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: 0acuvo-nieuws@netcomuk.co.uk, 0acuvo-nieuws@, netcomuk.co.uk, .netcomuk.co.uk, .co.uk, .uk, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(0acuvo-nieuws@netcomuk.co.uk), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (whitelist_sender) => undef, "0acuvo-nieuws@netcomuk.co.uk" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com) matches keys: "."=>ARRAY(0x8a2d61c) Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (score_sender), 1 matches for "incentives@olympus-tours.com", results: "."=>[Amavis::Lookup::RE=ARRAY(0x8a2d424),HASH(0x8a2d490)] Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_re("0acuvo-nieuws@netcomuk.co.uk"), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: 0acuvo-nieuws@netcomuk.co.uk, 0acuvo-nieuws@, netcomuk.co.uk, .netcomuk.co.uk, .co.uk, .uk, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(0acuvo-nieuws@netcomuk.co.uk), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (score_sender<0acuvo-nieuws@netcomuk.co.uk>) => undef, "0acuvo-nieuws@netcomuk.co.uk" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) SpamControl: calling spam scanner Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) spam_scan: DSPAM not available, skipping it Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) timer set to 38 s for SA (was 58 s) Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) calling SA parse, SA version 3.1.6 Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) CALLING SA check Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer run_av: timer set to = 478 s Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) run_av: /usr/local/bin/clamscan exit 0, /var/amavis/tmp/amavis-20070928T215033-01618/parts/p001: OK\n/var/amavis/tmp/amavis-20070928T215033-01618/parts/p002: OK Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) run_av (ClamAV-clamscan): CLEAN Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (bypass_virus_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (bypass_spam_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) wbl: checking sender Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (blacklist_recip) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: reservaciones3@olympus-tours.com, reservaciones3@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(reservaciones3@olympus-tours.com), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (blacklist_sender) => undef, "reservaciones3@olympus-tours.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (whitelist_recip) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: reservaciones3@olympus-tours.com, reservaciones3@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(reservaciones3@olympus-tours.com), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (whitelist_sender) => undef, "reservaciones3@olympus-tours.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com) matches keys: "."=>ARRAY(0x8a2d61c) Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (score_sender), 1 matches for "emelgoza@posadas.com", results: "."=>[Amavis::Lookup::RE=ARRAY(0x8a2d424),HASH(0x8a2d490)] Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_re("reservaciones3@olympus-tours.com"), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: reservaciones3@olympus-tours.com, reservaciones3@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(reservaciones3@olympus-tours.com), no matches Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (score_sender) => undef, "reservaciones3@olympus-tours.com" does not match Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) SpamControl: calling spam scanner Sep 28 21:50:36 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) spam_scan: DSPAM not available, skipping it Sep 28 21:50:37 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) timer set to 318 s for SA (was 478 s) Sep 28 21:50:37 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) calling SA parse, SA version 3.1.6 Sep 28 21:50:37 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) CALLING SA check Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer spam_scan_sa_finish: timer set to = 51 s Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) spam_scan: score=36.289 tests=[BAYES_99=3.5,DATE_IN_PAST_06_12=0.827,DCC_CHECK=2.17,DIGEST_MULTIPLE=0.765,DRUGS_ERECTILE=0.493,FROM_NO_LOWER=0.141,HELO_DYNAMIC_HCC=4.1,HELO_DYNAMIC_IPADDR2=3.818,HTML_ATTR_UNIQUE=0.639,HTML_FONT_FACE_BAD=0.156,HTML_MESSAGE=0.001,RAZOR2_CF_RANGE_51_100=0.5,RAZOR2_CF_RANGE_E4_51_100=1.5,RAZOR2_CF_RANGE_E8_51_100=1.5,RAZOR2_CHECK=0.5,RCVD_IN_NJABL_DUL=1.946,URIBL_JP_SURBL=4.087,URIBL_OB_SURBL=3.008,URIBL_SC_SURBL=4.498,URIBL_WS_SURBL=2.14] Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer spam_scan: remaining time = 51 s Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="-999" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_tag_level) => true, "incentives@olympus-tours.com" matches, result="-999", matching_key="(constant:-999)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="5" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_tag2_level) => true, "incentives@olympus-tours.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_tag3_level) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="5" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_kill_level) => true, "incentives@olympus-tours.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_spam_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) final_destiny PASS, recip incentives@olympus-tours.com Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) do_notify_and_quar: ccat=Spam (6,0) ("6":Spam, "5":Spammy, "1,1":CleanTag, "1":Clean, "0":CatchAll), q_mth=local:spam-%m.gz, qar_mth= Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_spam_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="-999" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_tag_level) => true, "incentives@olympus-tours.com" matches, result="-999", matching_key="(constant:-999)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="5" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_tag2_level) => true, "incentives@olympus-tours.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="5" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_kill_level) => true, "incentives@olympus-tours.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="spam-quarantine" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_quarantine_to) => true, "incentives@olympus-tours.com" matches, result="spam-quarantine", matching_key="(constant:spam-quarantine)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_quarantine_cutoff_level) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_admin) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_quarantine_bysender_to) => undef, "0acuvo-nieuws@netcomuk.co.uk" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Spam-Flag: YES\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Spam-Score: 36.289\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Spam-Level: ************************************\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Spam-Status: Yes, score=36.289 tag=-999 tag2=5 kill=5 tests=[BAYES_99=3.5,\n\tDATE_IN_PAST_06_12=0.827, DCC_CHECK=2.17, DIGEST_MULTIPLE=0.765,\n\tDRUGS_ERECTILE=0.493, FROM_NO_LOWER=0.141, HELO_DYNAMIC_HCC=4.1,\n\tHELO_DYNAMIC_IPADDR2=3.818, HTML_ATTR_UNIQUE=0.639,\n\tHTML_FONT_FACE_BAD=0.156, HTML_MESSAGE=0.001,\n\tRAZOR2_CF_RANGE_51_100=0.5, RAZOR2_CF_RANGE_E4_51_100=1.5,\n\tRAZOR2_CF_RANGE_E8_51_100=1.5, RAZOR2_CHECK=0.5,\n\tRCVD_IN_NJABL_DUL=1.946, URIBL_JP_SURBL=4.087, URIBL_OB_SURBL=3.008,\n\tURIBL_SC_SURBL=4.498, URIBL_WS_SURBL=2.14]\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Quarantine-ID: \n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Envelope-To: \n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Envelope-From: <0acuvo-nieuws@netcomuk.co.uk>\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Received: from mail.hotels-olympus.com ([127.0.0.1])\n\tby localhost (olympus-tours.com [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id Rax-X3q4+wSo for ;\n\tFri, 28 Sep 2007 21:50:33 -0500 (CDT)\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) DO_QUARANTINE, sender: <> Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) local delivery: <> -> , mbx=/var/amavis/quarantine Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Delivered-To: spam-quarantine\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Return-Path: <>\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) write_header: 0, IO::File=GLOB(0xa699754) Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) one_response_for_all <>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.6.0 Ok, delivered to /var/amavis/quarantine, id=01619-01' Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) DO_QUARANTINE done Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) SPAM, <0acuvo-nieuws@netcomuk.co.uk> -> , Yes, score=36.289 tag=-999 tag2=5 kill=5 tests=[BAYES_99=3.5, DATE_IN_PAST_06_12=0.827, DCC_CHECK=2.17, DIGEST_MULTIPLE=0.765, DRUGS_ERECTILE=0.493, FROM_NO_LOWER=0.141, HELO_DYNAMIC_HCC=4.1, HELO_DYNAMIC_IPADDR2=3.818, HTML_ATTR_UNIQUE=0.639, HTML_FONT_FACE_BAD=0.156, HTML_MESSAGE=0.001, RAZOR2_CF_RANGE_51_100=0.5, RAZOR2_CF_RANGE_E4_51_100=1.5, RAZOR2_CF_RANGE_E8_51_100=1.5, RAZOR2_CHECK=0.5, RCVD_IN_NJABL_DUL=1.946, URIBL_JP_SURBL=4.087, URIBL_OB_SURBL=3.008, URIBL_SC_SURBL=4.498, URIBL_WS_SURBL=2.14], autolearn=spam, quarantine Rax-X3q4+wSo (spam-quarantine) Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) skip admin notification, no administrators Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) do_notify_and_quarantine - done Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) defang? ccat "6": 1 Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) defang? ccat "5": 1 Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) defang? ccat "0": Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) DEFANGING MAIL: Spam detection software, running on the system "olympus-tours.com", has identified this incoming email as possible spam. The original message has ... Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Received: by mail.hotels-olympus.com (Postfix, from userid 125)\n\tid A4F7D2B5429D; Fri, 28 Sep 2007 21:25:28 -0500 (CDT)\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Received: from 125-26-239-43.adsl.totbb.net (125-26-239-43.adsl.totbb.net [125.26.239.43])\n\tby mail.hotels-olympus.com (Postfix) with ESMTP id 3EC982B54184\n\tfor ; Fri, 28 Sep 2007 21:08:47 -0500 (CDT)\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Received: from [125.26.239.43] by excalibur.netcomuk.co.uk; Fri, 28 Sep 2007 26:10:30 +0700\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: From: "Glenna Gay"\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: To: \n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Date: Fri, 28 Sep 2007 26:10:30 +0700\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Message-ID: <01c80203$3c75ce10$2bef1a7d@0acuvo-nieuws>\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Mailer: Microsoft Outlook, Build 10.0.2627\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Quarantine-ID: \n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Amavis-Modified: Original mail moved to attachment (defanged) by\n\tolympus-tours.com\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Inserting header field: X-Amavis-Modified: Original mail moved to attachment (defanged) by olympus-tours.com Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Resent-From: "Content-filter at olympus-tours.com"\n\t\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Resent-Date: Fri, 28 Sep 2007 21:50:33 -0500 (CDT)\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Resent-Message-ID: \n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Sender: "Content-filter at olympus-tours.com" \n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: Received: from mail.hotels-olympus.com ([127.0.0.1])\n\tby localhost (olympus-tours.com [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id Rax-X3q4+wSo for ;\n\tFri, 28 Sep 2007 21:50:33 -0500 (CDT)\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (local_domains) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) query_keys: incentives@olympus-tours.com, incentives@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_hash(incentives@olympus-tours.com), no matches Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (bypass_spam_checks) => undef, "incentives@olympus-tours.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="-999" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_tag_level) => true, "incentives@olympus-tours.com" matches, result="-999", matching_key="(constant:-999)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup: (scalar) matches, result="5" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_tag2_level) => true, "incentives@olympus-tours.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) headers CLUSTERING: NEW CLUSTER : score=36.289, tag=0, tag2=0, local=0, bl=, s= Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) header: X-Virus-Scanned: amavisd-new at hotels-olympus.com\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) headers CLUSTERING: done all 1 recips in one go Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) (about to connect to [127.0.0.1]:10024) FWD via SMTP: <0acuvo-nieuws@netcomuk.co.uk> -> Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer spam_scan_sa_finish: timer set to = 472 s Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) spam_scan: score=-95.71 tests=[AWL=0.000,BAYES_00=-2.599,FUZZY_CREDIT=1.079,HTML_MESSAGE=0.001,RCVD_IN_NJABL_DUL=1.946,RCVD_IN_SORBS_DUL=2.046,SPF_SOFTFAIL=1.384,USER_IN_WHITELIST=-100,X_PRIORITY_HIGH=0.433] Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer spam_scan: remaining time = 472 s Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) enqueue: stat is not numeric: "" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup: (scalar) matches, result="-999" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (spam_tag_level) => true, "emelgoza@posadas.com" matches, result="-999", matching_key="(constant:-999)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup: (scalar) matches, result="5" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (spam_tag2_level) => true, "emelgoza@posadas.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (spam_tag3_level) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup: (scalar) matches, result="5" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (spam_kill_level) => true, "emelgoza@posadas.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (bypass_spam_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) final_destiny PASS, recip emelgoza@posadas.com Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) do_notify_and_quar: ccat=CleanTag (1,1) ("1,1":CleanTag, "1":Clean, "0":CatchAll), q_mth=, qar_mth= Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) skip admin notification, no administrators Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) do_notify_and_quarantine - done Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) defang? ccat "0": Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) header: Received: from mail.hotels-olympus.com ([127.0.0.1])\n\tby localhost (olympus-tours.com [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id DOr8-reXy1ow for ;\n\tFri, 28 Sep 2007 21:50:33 -0500 (CDT)\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (local_domains) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (bypass_spam_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup: (scalar) matches, result="-999" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (spam_tag_level) => true, "emelgoza@posadas.com" matches, result="-999", matching_key="(constant:-999)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup: (scalar) matches, result="5" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) lookup (spam_tag2_level) => true, "emelgoza@posadas.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) headers CLUSTERING: NEW CLUSTER : score=-95.71, tag=0, tag2=0, local=0, bl=, s= Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) header: X-Virus-Scanned: amavisd-new at hotels-olympus.com\n Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) headers CLUSTERING: done all 1 recips in one go Sep 28 21:50:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) (about to connect to [127.0.0.1]:10024) FWD via SMTP: -> Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer fwd-connect: timer set to = 60 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) mail_via_smtp: session failed: Can't connect to 127.0.0.1 port 10024, (Socket is already connected) at (eval 55) line 145, line 380. Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) (!)FWD via SMTP: <0acuvo-nieuws@netcomuk.co.uk> -> , 450 4.4.1 Can't connect to 127.0.0.1 port 10024, (Socket is already connected) at (eval 55) line 145, line 380., MTA([127.0.0.1]:10024), id=01619-01 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer forwarding: remaining time = 60 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) one_response_for_all <0acuvo-nieuws@netcomuk.co.uk>: 4xx found, '450 4.4.1 Can't connect to 127.0.0.1 port 10024, (Socket is already connected) at (eval 55) line 145, line 380., MTA([127.0.0.1]:10024), id=01619-01' Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ndn_needed=, exit=75, 450 4.4.1 Can't connect to 127.0.0.1 port 10024, (Socket is already connected) at (eval 55) line 145, line 380., MTA([127.0.0.1]:10024), id=01619-01 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup (spam_dsn_cutoff_level_bysender) => undef, "0acuvo-nieuws@netcomuk.co.uk" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) dsn: from MTA 450 Spam <0acuvo-nieuws@netcomuk.co.uk> -> : on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=0 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) DSN: TMPFAIL from MTA 450 Spam, need not be reported: <0acuvo-nieuws@netcomuk.co.uk> -> Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer delivery-notification: remaining time = 60 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) parse_received: from = [125.26.239.43] /[125.26.239.43]// Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) parse_received: by = excalibur.netcomuk.co.uk/excalibur.netcomuk.co.uk// Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) parse_received: ; = Fri, 28 Sep 2007 26:10:30 +0700/Fri, 28 Sep 2007 26:10:30 +0700// Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) fish_out_ip_from_received: 125.26.239.43, [125.26.239.43] Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_ip_acl (publicnetworks): key="125.26.239.43" matches "::FFFF:0:0/96", result=1 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) best_try_originator_ip: 125.26.239.43 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Blocked SPAM, [125.26.239.43] [125.26.239.43] <0acuvo-nieuws@netcomuk.co.uk> -> , quarantine: /var/amavis/quarantine, Message-ID: <01c80203$3c75ce10$2bef1a7d@0acuvo-nieuws>, mail_id: Rax-X3q4+wSo, Hits: 36.289, 69780 ms Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer main_log_entry: remaining time = 60 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) updating snmp variables Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) prolong_timer check done: remaining time = 60 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) sending SMTP response: "450 4.4.1 Can't connect to 127.0.0.1 port 10024, (Socket is already connected) at (eval 55) line 145, line 380., MTA([127.0.0.1]:10024), id=01619-01" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) TempDir::strip: /var/amavis/tmp/amavis-20070928T215033-01619 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) rmdir_recursively: /var/amavis/tmp/amavis-20070928T215033-01619/parts, excl=1 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) TIMING [total 69783 ms] - SMTP EHLO: 12 (0%)0, SMTP pre-MAIL: 4 (0%)0, mkdir tempdir: 1 (0%)0, create email.txt: 1 (0%)0, SMTP pre-DATA-flush: 4 (0%)0, SMTP DATA: 94 (0%)0, body_digest: 1 (0%)0, gen_mail_id: 1 (0%)0, mkdir parts: 25 (0%)0, mime_decode: 16 (0%)0, get-file-type2: 15 (0%)0, decompose_part: 2 (0%)0, parts_decode: 0 (0%)0, AV-scan-1: 9 (0%)0, AV-scan-2: 2844 (4%)4, spam-wb-list: 5 (0%)4, SA msg read: 1 (0%)4, SA parse: 3 (0%)4, SA check: 6682 (10%)14, SA finish: 4 (0%)14, update_cache: 2 (0%)14, decide_mail_destiny: 2 (0%)14, write-header: 9 (0%)14, save-to-local-mailbox: 1 (0%)14, defang: 23 (0%)14, fwd-rundown: 60010 (86%)100, prepare-dsn: 1 (0%)100, main_log_entry: 9 (0%)100, update_snmp: 1 (0%)100, unlink-2-files: 1 (0%)100, rundown: 0 (0%)100 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 450 4.4.1 Can't connect to 127.0.0.1 port 10024, (Socket is already connected) at (eval 55) line 145, line 380., MTA([127.0.0.1]:10024), id=01619-01 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 6: was busy, 69763.7 ms, total idle 0.002 s, busy 69.782 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.002 s, busy 69.782 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP< QUIT\r\n Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, SMTP QUIT received Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-new closing transmission channel Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) SMTP session over, timer stopped Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) exiting process_request Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) post_process_request_hook: timer was not running Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, bye: was busy, 2.5 ms, total idle 0.002 s, busy 69.785 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) load: 100 %, total idle 0.002 s, busy 69.785 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) Net::Server: 2007/09/28-21:51:43 CONNECT TCP Peer: "127.0.0.1:55158" Local: "127.0.0.1:10024" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) idle_proc, hi : was idle, 1.3 ms, total idle 0.003 s, busy 69.785 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) loaded base policy bank Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) process_request: fileno sock=12, STDIN=0, STDOUT=1 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) switch_to_my_time 480 s, new request Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-01) process_request: suggested_protocol="" on TCP Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) idle_proc, 4: was busy, 2.8 ms, total idle 0.003 s, busy 69.788 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) SMTP session over, timer stopped Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) idle_proc, 7: was idle, 0.3 ms, total idle 0.003 s, busy 69.788 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) SMTP: NOTICE: client broke the connection without a QUIT (Connection reset by peer) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) exiting process_request Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) post_process_request_hook: timer was not running Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) idle_proc, bye: was busy, 1.4 ms, total idle 0.003 s, busy 69.789 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) load: 100 %, total idle 0.003 s, busy 69.789 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) Net::Server: 2007/09/28-21:51:43 CONNECT TCP Peer: "127.0.0.1:55799" Local: "127.0.0.1:10024" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) idle_proc, hi : was idle, 0.8 ms, total idle 0.004 s, busy 69.789 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) loaded base policy bank Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) process_request: fileno sock=12, STDIN=0, STDOUT=1 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) switch_to_my_time 480 s, new request Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-02) process_request: suggested_protocol="" on TCP Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 4: was busy, 2.6 ms, total idle 0.004 s, busy 69.792 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 5: was idle, 0.2 ms, total idle 0.004 s, busy 69.792 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) SMTP< EHLO localhost\r\n Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_my_time 480 s, SMTP EHLO received Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250-[127.0.0.1] Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250-VRFY Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250-PIPELINING Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250-SIZE Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250-ENHANCEDSTATUSCODES Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250-8BITMIME Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250-DSN Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 6: was busy, 1.7 ms, total idle 0.004 s, busy 69.793 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) Remote host presents itself as: [127.0.0.1], handles DSN, no ORCPT Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer fwd-connect: timer set to = 472 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) sending XFORWARD ADDR=189.149.130.59 NAME=unknown PROTO=ESMTP HELO=OLYMPUS03 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 5: was idle, 1.3 ms, total idle 0.006 s, busy 69.793 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP< XFORWARD ADDR=189.149.130.59 NAME=unknown PROTO=ESMTP HELO=OLYMPUS03\r\n Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_my_time 480 s, SMTP XFORWARD received Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250 2.5.0 Ok XFORWARD Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 6: was busy, 0.9 ms, total idle 0.006 s, busy 69.794 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer fwd-xforward: remaining time = 472 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) AUTH not needed, user='', MTA offers '' Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 5: was idle, 0.6 ms, total idle 0.006 s, busy 69.794 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP< MAIL FROM: BODY=8BITMIME\r\n Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_my_time 480 s, SMTP MAIL received Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) check_mail_begin_task: task_count=2 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_ip_acl (mynetworks): key="189.149.130.59", no match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (debug_sender) => undef, "reservaciones3@olympus-tours.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250 2.1.0 Sender reservaciones3@olympus-tours.com OK Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer fwd-mail-from: remaining time = 472 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 6: was busy, 2.0 ms, total idle 0.006 s, busy 69.796 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) sending RCPT TO: Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 5: was idle, 0.3 ms, total idle 0.007 s, busy 69.796 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP< RCPT TO:\r\n Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_my_time 480 s, SMTP RCPT received Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 250 2.1.5 Recipient emelgoza@posadas.com OK Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) response to RCPT TO for : "250 2.1.5 Recipient emelgoza@posadas.com OK" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 6: was busy, 0.9 ms, total idle 0.007 s, busy 69.797 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer fwd-rcpt-to: remaining time = 472 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) idle_proc, 5: was idle, 0.3 ms, total idle 0.007 s, busy 69.797 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP< DATA\r\n Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_my_time 480 s, SMTP DATA received Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP::10024 /var/amavis/tmp/amavis-20070928T215033-01619: -> BODY=8BITMIME Received: from localhost ([127.0.0.1]) by localhost (olympus-tours.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for ; Fri, 28 Sep 2007 21:51:43 -0500 (CDT) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP> 354 End data with . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_client_time 480 s, smtp response sent Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_client_time 480 s, receiving data Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer fwd-data-cmd: remaining time = 472 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) response to DATA: "354 End data with ." Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) write_header: 0, Amavis::Out::SMTP=HASH(0x845c760) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1618]: (01618-01) prolong_timer fwd-data-contents: remaining time = 472 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) switch_to_my_time 480 s, data-end received Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) ESMTP< . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) body hash: ed7b508eb8aae6b42bd813d5cd1d2832 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Original mail size: 15645; quota set to: 7822500 bytes Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Checking: GKvcAHWXil03 [189.149.130.59] -> Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) cached ed7b508eb8aae6b42bd813d5cd1d2832 from (1,1) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) cache entry age: . c=20070929T025034 a=20070929T025034 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (bypass_virus_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Extracting mime components Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Issued a new file name: p001 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Issued a new file name: p002 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) mime_decode_preamble: 2 lines Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Issued a new pseudo part: p003 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) p003 1 Content-Type: multipart/alternative Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) mime_decode_epilogue: 1 lines Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Charging 2142 bytes to remaining quota 7822500 (out of 7822500, (0%)) - by mime_decode Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) p001 1/1 Content-Type: text/plain, size: 2142 B, name: Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) reparenting p001 from p000 to p003 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Charging 10266 bytes to remaining quota 7820358 (out of 7822500, (0%)) - by mime_decode Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) p002 1/2 Content-Type: text/html, size: 10266 B, name: Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) reparenting p002 from p000 to p003 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) prolong_timer mime_decode-1: remaining time = 480 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) decode_parts: level=1, #parts=3 : p001, p002, p003 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) run_command: [1656] /usr/bin/file p001 p002 &1 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1656]: (01619-03) open_on_specific_fd: target fd0 closing, to become < /dev/null Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1656]: (01619-03) open_on_specific_fd: target fd2 closing, to become > &1 Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) result line from file(1): p001: Non-ISO extended-ASCII English text Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_re("Non-ISO extended-ASCII English text") matches key "(?-xism:^Non-ISO.*ASCII\b.*\btext\b)", result="txt" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (map_full_type_to_short_type) => true, "Non-ISO extended-ASCII English text" matches, result="txt", matching_key="(?-xism:^Non-ISO.*ASCII\\b.*\\btext\\b)" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) File-type of p001: Non-ISO extended-ASCII English text; (txt) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) result line from file(1): p002: HTML document text Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_re("HTML document text") matches key "(?-xism:^HTML document text\b)", result="html" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (map_full_type_to_short_type) => true, "HTML document text" matches, result="html", matching_key="(?-xism:^HTML document text\\b)" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) File-type of p002: HTML document text; (html) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) decompose_part: p001 - atomic Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) decompose_part: p002 - atomic Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) prolong_timer parts_decode: remaining time = 480 s Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (bypass_header_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) check_header: 0, OK Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (bypass_header_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) Checking for banned types and filenames Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (bypass_banned_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup: (scalar) matches, result="DEFAULT" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (banned_filename), 1 matches for "emelgoza@posadas.com", results: "(constant:DEFAULT)"=>"DEFAULT" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) collect banned table[0]: emelgoza@posadas.com, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8a2d244) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) starting banned checks - traversing message structure tree Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) check_for_banned (p003,p001) multipart/alternative | text/plain,.txt Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) doing banned check for emelgoza@posadas.com on multipart/alternative | text/plain,.txt Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_re(["multipart/alternative","text/plain",".txt"]), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (check_bann:emelgoza@posadas.com) => undef, ["multipart/alternative","text/plain",".txt"] does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) p.path emelgoza@posadas.com: "P=p003,L=1,M=multipart/alternative | P=p001,L=1/1,M=text/plain,T=txt" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) check_for_banned (p003,p002) multipart/alternative | text/html,.html Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) doing banned check for emelgoza@posadas.com on multipart/alternative | text/html,.html Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_re(["multipart/alternative","text/html",".html"]), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (check_bann:emelgoza@posadas.com) => undef, ["multipart/alternative","text/html",".html"] does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) p.path emelgoza@posadas.com: "P=p003,L=1,M=multipart/alternative | P=p002,L=1/2,M=text/html,T=html" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) banned check: any=0, all=N (1) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) virus_presence cached, skipping virus_scan Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (bypass_virus_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (bypass_spam_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) wbl: checking sender Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (blacklist_recip) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: reservaciones3@olympus-tours.com, reservaciones3@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(reservaciones3@olympus-tours.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (blacklist_sender) => undef, "reservaciones3@olympus-tours.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (whitelist_recip) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: reservaciones3@olympus-tours.com, reservaciones3@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(reservaciones3@olympus-tours.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (whitelist_sender) => undef, "reservaciones3@olympus-tours.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com) matches keys: "."=>ARRAY(0x8a2d61c) Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (score_sender), 1 matches for "emelgoza@posadas.com", results: "."=>[Amavis::Lookup::RE=ARRAY(0x8a2d424),HASH(0x8a2d490)] Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_re("reservaciones3@olympus-tours.com"), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: reservaciones3@olympus-tours.com, reservaciones3@, olympus-tours.com, .olympus-tours.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(reservaciones3@olympus-tours.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (score_sender) => undef, "reservaciones3@olympus-tours.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) spam_presence cached, skipping spam_scan Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) enqueue: stat is not numeric: "" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup: (scalar) matches, result="-999" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (spam_tag_level) => true, "emelgoza@posadas.com" matches, result="-999", matching_key="(constant:-999)" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup: (scalar) matches, result="5" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (spam_tag2_level) => true, "emelgoza@posadas.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (spam_tag3_level) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup: (scalar) matches, result="5" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (spam_kill_level) => true, "emelgoza@posadas.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (bypass_spam_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) final_destiny PASS, recip emelgoza@posadas.com Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) do_notify_and_quar: ccat=CleanTag (1,1) ("1,1":CleanTag, "1":Clean, "0":CatchAll), q_mth=, qar_mth= Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) skip admin notification, no administrators Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) do_notify_and_quarantine - done Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) defang? ccat "0": Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) header: Received: from localhost ([127.0.0.1])\n\tby localhost (olympus-tours.com [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id GKvcAHWXil03 for ;\n\tFri, 28 Sep 2007 21:51:43 -0500 (CDT)\n Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (local_domains) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) query_keys: emelgoza@posadas.com, emelgoza@, posadas.com, .posadas.com, .com, . Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup_hash(emelgoza@posadas.com), no matches Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (bypass_spam_checks) => undef, "emelgoza@posadas.com" does not match Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup: (scalar) matches, result="-999" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (spam_tag_level) => true, "emelgoza@posadas.com" matches, result="-999", matching_key="(constant:-999)" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup: (scalar) matches, result="5" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) lookup (spam_tag2_level) => true, "emelgoza@posadas.com" matches, result="5", matching_key="(constant:5)" Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) headers CLUSTERING: NEW CLUSTER : score=-95.71, tag=0, tag2=0, local=0, bl=, s= Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) header: X-Virus-Scanned: amavisd-new at hotels-olympus.com\n Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) headers CLUSTERING: done all 1 recips in one go Sep 28 21:51:43 olympus-tours.com /usr/local/sbin/amavisd[1619]: (01619-03) (about to connect to [127.0.0.1]:10024) FWD via SMTP: ->