All pastes #372505 Raw Edit

Tab

public text v1 · immutable
#372505 ·published 2007-02-26 02:47 UTC
rendered paste body
Feb 26 13:44:05 mail.tabmow.info /usr/local/sbin/amavisd[2525]: TIMING [total 5 ms] - bdb-open: 5 (100%)100, rundown: 0 (0%)100
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: Net::Server: 2007/02/26-13:45:40 CONNECT TCP Peer: "192.168.0.105:62607" Local: "192.168.0.105:10024"
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: loaded base policy bank
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: lookup_ip_acl (inet_acl): key="192.168.0.105" matches "192.168.0.105", result=1
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: process_request: fileno sock=11, STDIN=0, STDOUT=1
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: switch_to_my_time     480 s, new request
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: process_request: suggested_protocol="" on TCP
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) SMTP> 220 [192.168.0.105] ESMTP amavisd-new service ready
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 4: was busy, 6.2 ms, total idle 0.000 s, busy 0.006 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.000 s, busy 0.006 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) SMTP< LHLO tabmow.tabby.com.au\r\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_my_time     480 s, SMTP LHLO received
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250-[192.168.0.105]
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250-VRFY
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250-PIPELINING
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250-SIZE
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250-ENHANCEDSTATUSCODES
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250-8BITMIME
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250-DSN
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250 XFORWARD NAME ADDR PROTO HELO
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 6: was busy, 1.6 ms, total idle 0.000 s, busy 0.008 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.001 s, busy 0.008 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP< XFORWARD NAME=mailout14.yourhostingaccount.com ADDR=65.254.253.118\r\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_my_time     480 s, SMTP XFORWARD received
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250 2.5.0 Ok XFORWARD
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 6: was busy, 0.7 ms, total idle 0.001 s, busy 0.009 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.009 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP< XFORWARD PROTO=ESMTP HELO=mailout14.yourhostingaccount.com\r\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_my_time     480 s, SMTP XFORWARD received
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250 2.5.0 Ok XFORWARD
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 6: was busy, 0.5 ms, total idle 0.001 s, busy 0.009 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.009 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP< MAIL FROM:<SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> SIZE=3513\r\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_my_time     480 s, SMTP MAIL received
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) check_mail_begin_task: task_count=1
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) TempDir::prepare: creating directory /var/amavis/tmp/amavis-20070226T134540-02524
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) TempDir::prepare_file: creating file /var/amavis/tmp/amavis-20070226T134540-02524/email.txt
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) TempDir::prepare_file: layers: stdio
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_ip_acl (mynetworks): key="65.254.253.118", no match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (debug_sender) => undef, "SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250 2.1.0 Sender <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> OK
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 6: was busy, 3.5 ms, total idle 0.001 s, busy 0.013 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.001 s, busy 0.013 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP< RCPT TO:<terry@tabmow.tabby.com.au> ORCPT=rfc822;terry@tabmow.info\r\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_my_time     480 s, SMTP RCPT received
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250 2.1.5 Recipient <terry@tabmow.tabby.com.au> OK
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 6: was busy, 0.9 ms, total idle 0.001 s, busy 0.013 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 5: was idle, 0.4 ms, total idle 0.001 s, busy 0.013 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP< DATA\r\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_my_time     480 s, SMTP DATA received
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP::10024 /var/amavis/tmp/amavis-20070226T134540-02524: <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> -> <terry@tabmow.tabby.com.au> SIZE=3513 Received: from tabmow.tabby.com.au ([192.168.0.105]) by localhost (mail.tabmow.info [192.168.0.105]) (amavisd-new, port 10024) with LMTP for <terry@tabmow.tabby.com.au>; Mon, 26 Feb 2007 13:45:40 +1100 (EST)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 354 End data with <CR><LF>.<CR><LF>
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, receiving data
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_my_time     480 s, data-end received
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP< .<CR><LF>
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Actual message size 3512 B, declared 3513 B
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) setting body type: 7BIT (h=0, b=0)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) body hash: b686d574659c889c281e9a6d0add4a4f
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Original mail size: 3512; quota set to: 1756000 bytes
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Checking: 8TWugj0c8Ryq [65.254.253.118] <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> -> <terry@tabmow.tabby.com.au>
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: terry@tabmow.tabby.com.au, terry@, tabmow.tabby.com.au, .tabmow.tabby.com.au, .tabby.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(terry@tabmow.tabby.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (bypass_virus_checks) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Extracting mime components
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Issued a new file name: p001
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Charging 867 bytes to remaining quota 1756000 (out of 1756000, (0%)) - by mime_decode
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) p001 1 Content-Type: text/plain, size: 867 B, name:
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer mime_decode-1: remaining time = 480 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) decode_parts: level=1, #parts=1 : p001
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) run_command: [2543] /usr/local/bin/file p001 </dev/null 2>&1
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) result line from file(1): p001: ASCII English text, with very long lines\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_re("ASCII English text, with very long lines") matches key "(?i-xsm:^(ASCII|text)\b)", result="asc"
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (map_full_type_to_short_type) => true,  "ASCII English text, with very long lines" matches, result="asc", matching_key="(?i-xsm:^(ASCII|text)\\b)"
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) File-type of p001: ASCII English text, with very long lines; (asc)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) do_ascii: Decoding part p001
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) do_ascii: Setting sigaction handler, was 0
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) timer set to 320 s (was 480 s)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) do_ascii: Decoding part p001 (0 items), uulib V0.5pl20
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer do_ascii: timer set to 480 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) decompose_part: p001 - atomic
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer parts_decode: remaining time = 480 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: terry@tabmow.tabby.com.au, terry@, tabmow.tabby.com.au, .tabmow.tabby.com.au, .tabby.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(terry@tabmow.tabby.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (bypass_header_checks) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) check_header: 0, OK
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: terry@tabmow.tabby.com.au, terry@, tabmow.tabby.com.au, .tabmow.tabby.com.au, .tabby.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(terry@tabmow.tabby.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (bypass_header_checks) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Checking for banned types and filenames
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: terry@tabmow.tabby.com.au, terry@, tabmow.tabby.com.au, .tabmow.tabby.com.au, .tabby.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(terry@tabmow.tabby.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (bypass_banned_checks) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="DEFAULT"
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (banned_filename), 1 matches for "terry@tabmow.tabby.com.au", results: "(constant:DEFAULT)"=>"DEFAULT"
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) collect banned table[0]: terry@tabmow.tabby.com.au, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8bc2f84)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) starting banned checks - traversing message structure tree
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) check_for_banned (p001) text/plain,.asc
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) doing banned check for terry@tabmow.tabby.com.au on text/plain,.asc
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_re(["text/plain",".asc"]), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (check_bann:terry@tabmow.tabby.com.au) => undef, ["text/plain",".asc"] does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) p.path terry@tabmow.tabby.com.au: "P=p001,L=1,M=text/plain,T=asc"
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) banned check: any=0, all=N (1)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_re("MAIL"), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (keep_decoded_original) => undef, "MAIL" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Using ClamAV-clamd: (built-in interface)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) timer set to 320 s (was 480 s)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) ask_av (ClamAV-clamd): query template1: CONTSCAN {}\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Using (ClamAV-clamd) on dir: CONTSCAN /var/amavis/tmp/amavis-20070226T134540-02524/parts\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) timer set to 10 s (was 320 s)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) ClamAV-clamd: Connecting to socket  /var/run/clamav/clamd
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) ClamAV-clamd: Sending CONTSCAN /var/amavis/tmp/amavis-20070226T134540-02524/parts\n to UNIX socket /var/run/clamav/clamd
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer ask_daemon_internal: timer set to 256 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) ask_av (ClamAV-clamd) result: /var/amavis/tmp/amavis-20070226T134540-02524/parts: OK\n
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) ask_av (ClamAV-clamd): /var/amavis/tmp/amavis-20070226T134540-02524/parts CLEAN
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) ClamAV-clamd result: clean
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer run_av: timer set to 480 s
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: terry@tabmow.tabby.com.au, terry@, tabmow.tabby.com.au, .tabmow.tabby.com.au, .tabby.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(terry@tabmow.tabby.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (bypass_virus_checks) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: terry@tabmow.tabby.com.au, terry@, tabmow.tabby.com.au, .tabmow.tabby.com.au, .tabby.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(terry@tabmow.tabby.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (bypass_spam_checks) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) wbl: checking sender <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au>
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (blacklist_recip<terry@tabmow.tabby.com.au>) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au, srs0=bjhell=ic=colesmyer.com.au=terry.sposato@comtron.com.au, srs0=bjhell=ic=colesmyer.com.au=terry.sposato@, comtron.com.au, .comtron.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (blacklist_sender) => undef, "SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (whitelist_recip<terry@tabmow.tabby.com.au>) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au, srs0=bjhell=ic=colesmyer.com.au=terry.sposato@comtron.com.au, srs0=bjhell=ic=colesmyer.com.au=terry.sposato@, comtron.com.au, .comtron.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (whitelist_sender) => undef, "SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: terry@tabmow.tabby.com.au, terry@, tabmow.tabby.com.au, .tabmow.tabby.com.au, .tabby.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(terry@tabmow.tabby.com.au) matches keys: "."=>ARRAY(0x8bc338c)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (score_sender), 1 matches for "terry@tabmow.tabby.com.au", results: "."=>[Amavis::Lookup::RE=ARRAY(0x8bc317c),HASH(0x8bc31e8)]
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_re("SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au"), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au, srs0=bjhell=ic=colesmyer.com.au=terry.sposato@comtron.com.au, srs0=bjhell=ic=colesmyer.com.au=terry.sposato@, comtron.com.au, .comtron.com.au, .com.au, .au, .
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au), no matches
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (score_sender<SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au>) => undef, "SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au" does not match
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) SpamControl: calling spam scanner
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) spam_scan: DSPAM not available, skipping it
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) timer set to 320 s for SA (was 480 s)
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) calling SA parse, SA version 3.1.8
Feb 26 13:45:40 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) CALLING SA check
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer spam_scan_sa_finish: timer set to 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) spam_scan: score=-0.393 autolearn=ham tests=[AWL=-0.393]
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer spam_scan: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="-9999"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_tag_level) => true,  "terry@tabmow.tabby.com.au" matches, result="-9999", matching_key="(constant:-9999)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="5"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_tag2_level) => true,  "terry@tabmow.tabby.com.au" matches, result="5", matching_key="(constant:5)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_tag3_level) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="8"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_kill_level) => true,  "terry@tabmow.tabby.com.au" matches, result="8", matching_key="(constant:8)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) query_keys: terry@tabmow.tabby.com.au, terry@, tabmow.tabby.com.au, .tabmow.tabby.com.au, .tabby.com.au, .com.au, .au, .
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_hash(terry@tabmow.tabby.com.au), no matches
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (bypass_spam_checks) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) final_destiny PASS, recip terry@tabmow.tabby.com.au
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) do_notify_and_quar: ccat=CleanTag (1,1) ("1,1":CleanTag, "1":Clean, "0":CatchAll), q_mth=, qar_mth=
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="-9999"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_tag_level) => true,  "terry@tabmow.tabby.com.au" matches, result="-9999", matching_key="(constant:-9999)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="5"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_tag2_level) => true,  "terry@tabmow.tabby.com.au" matches, result="5", matching_key="(constant:5)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="8"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_kill_level) => true,  "terry@tabmow.tabby.com.au" matches, result="8", matching_key="(constant:8)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) skip admin notification, no administrators
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) do_notify_and_quarantine - done
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) defang? ccat "0":
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) header: Received: from tabmow.tabby.com.au ([192.168.0.105])\n\tby localhost (mail.tabmow.info [192.168.0.105]) (amavisd-new, port 10024)\n\twith LMTP id 8TWugj0c8Ryq for <terry@tabmow.tabby.com.au>;\n\tMon, 26 Feb 2007 13:45:40 +1100 (EST)\n
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_acl(terry@tabmow.tabby.com.au), no match
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (local_domains) => undef, "terry@tabmow.tabby.com.au" does not match
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="-9999"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_tag_level) => true,  "terry@tabmow.tabby.com.au" matches, result="-9999", matching_key="(constant:-9999)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="5"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_tag2_level) => true,  "terry@tabmow.tabby.com.au" matches, result="5", matching_key="(constant:5)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) headers CLUSTERING: NEW CLUSTER <terry@tabmow.tabby.com.au>: score=-0.393, tag=1, tag2=0, local=0, bl=, s=
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) header: X-Virus-Scanned: amavisd-new at tabmow.info\n
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) headers CLUSTERING: done all 1 recips in one go
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) (about to connect to [192.168.0.105]:10025) FWD via SMTP: <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> -> <terry@tabmow.tabby.com.au>
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Remote host presents itself as: mail.tabmow.info, handles DSN, ORCPT
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer fwd-connect: timer set to 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) AUTH not needed, user='', MTA offers 'LOGIN PLAIN'
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer fwd-mail-from: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) sending RCPT TO:<terry@tabmow.tabby.com.au> ORCPT=rfc822;terry@tabmow.info
Net::SMTP::recipient: unknown option(s) ORCPT - ignored at (eval 65) line 252
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) response to RCPT TO for <terry@tabmow.tabby.com.au>: "250 2.1.5 Ok"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer fwd-rcpt-to: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer fwd-data-cmd: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) response to DATA: "354 End data with <CR><LF>.<CR><LF>"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) write_header: 0, Amavis::Out::SMTP=HASH(0xa86b238)
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer fwd-data-contents: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer fwd-data-end: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) response to data end: "250 2.0.0 Ok: queued as 9D147170DC"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer fwd-rundown-1: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) FWD via SMTP: <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> -> <terry@tabmow.tabby.com.au>, 250 2.6.0 Ok, id=02524-01, from MTA([192.168.0.105]:10025): 250 2.0.0 Ok: queued as 9D147170DC
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer forwarding: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) one_response_for_all <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.6.0 Ok, id=02524-01, from MTA([192.168.0.105]:10025): 250 2.0.0 Ok: queued as 9D147170DC'
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) ndn_needed=0, exit=0, 250 2.6.0 Ok, id=02524-01, from MTA([192.168.0.105]:10025): 250 2.0.0 Ok: queued as 9D147170DC
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup: (scalar) matches, result="10"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup (spam_dsn_cutoff_level_bysender) => true,  "SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au" matches, result="10", matching_key="(constant:10)"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) dsn: from MTA 250 CleanTag <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> -> <terry@tabmow.tabby.com.au>: on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=1
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) DSN: SUCC from MTA 250 CleanTag, no DSN requested: <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> -> <terry@tabmow.tabby.com.au>
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer delivery-notification: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: from = WPEXCH02.colesmyer.ad.cmltd.net.au ([172.25.51.59].../WPEXCH02.colesmyer.ad.cmltd.net.au//172.25.51.59
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: by = wpexchsmtp02.colesmyer.ad.cmltd.net.au /wpexchsmtp02.colesmyer.ad.cmltd.net.au//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: with = Microsoft SMTPSVC/Microsoft SMTPSVC//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: ; = Mon, 26 Feb 2007 13:45:00 +1100/Mon, 26 Feb 2007 13:45:00 +1100//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) fish_out_ip_from_received: 172.25.51.59, WPEXCH02.colesmyer.ad.cmltd.net.au ([172.25.51.59])
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_ip_acl (publicnetworks): key="172.25.51.59" matches "!172.16.0.0/12", result=0
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: from = wpexchsmtp02.colesmyer.ad.cmltd.net.au (localhost .../wpexchsmtp02.colesmyer.ad.cmltd.net.au/localhost/127.0.0.1
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: by = upsmtpgw1.colesmyer.com.au /upsmtpgw1.colesmyer.com.au//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: with = ESMTP /ESMTP //
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: id = l1Q2jSVa008739\t/l1Q2jSVa008739\t//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: for = <terry@comtron.com.au>/<terry@comtron.com.au>//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: ; = Mon, 26 Feb 2007 13:45:32 +1100/Mon, 26 Feb 2007 13:45:32 +1100//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) fish_out_ip_from_received: 127.0.0.1, wpexchsmtp02.colesmyer.ad.cmltd.net.au (localhost [127.0.0.1])\t
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_ip_acl (publicnetworks): key="127.0.0.1" matches "!127.0.0.0/8", result=0
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: from = upsmtpgw1.colesmyer.com.au (upsmtpgw1.colesmyer.co.../upsmtpgw1.colesmyer.com.au/upsmtpgw1.colesmyer.com.au/172.22.51.45
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: by = upsmtpgw3.colesmyer.com.au /upsmtpgw3.colesmyer.com.au//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: with = ESMTP /ESMTP //
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: id = l1Q2jWrs009159\t/l1Q2jWrs009159\t//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: for = <terry@comtron.com.au>/<terry@comtron.com.au>//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: ; = Mon, 26 Feb 2007 13:45:33 +1100/Mon, 26 Feb 2007 13:45:33 +1100//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) fish_out_ip_from_received: 172.22.51.45, upsmtpgw1.colesmyer.com.au (upsmtpgw1.colesmyer.com.au [172.22.51.45])\t
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_ip_acl (publicnetworks): key="172.22.51.45" matches "!172.16.0.0/12", result=0
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: from = upsmtpgw3.colesmyer.com.au ([203.5.136.45])\t/upsmtpgw3.colesmyer.com.au//203.5.136.45
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: by = mailinc10.yourhostingaccount.com /mailinc10.yourhostingaccount.com//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: with = esmtp /esmtp //
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: id = 1HLVrr-0006re-4y\t/1HLVrr-0006re-4y\t//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: for = terry@comtron.com.au/terry@comtron.com.au//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) parse_received: ; = Sun, 25 Feb 2007 21:45:35 -0500/Sun, 25 Feb 2007 21:45:35 -0500//
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) fish_out_ip_from_received: 203.5.136.45, upsmtpgw3.colesmyer.com.au ([203.5.136.45])\t
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) lookup_ip_acl (publicnetworks): key="203.5.136.45" matches "::FFFF:0:0/96", result=1
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) best_try_originator_ip: 203.5.136.45
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) Passed CLEAN, [65.254.253.118] [203.5.136.45] <SRS0=BjHEll=IC=colesmyer.com.au=Terry.Sposato@comtron.com.au> -> <terry@tabmow.tabby.com.au>, Message-ID: <90B23FAA28445B4A8674DCAD835FC3DA078652D2@WPEXCH02.colesmyer.ad.cmltd.net.au>, mail_id: 8TWugj0c8Ryq, Hits: -0.393, queued_as: 9D147170DC, 2521 ms
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer main_log_entry: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) updating snmp variables
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) prolong_timer check done: remaining time = 478 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP response for <terry@tabmow.tabby.com.au>: "250 2.6.0 Ok, id=02524-01, from MTA([192.168.0.105]:10025): 250 2.0.0 Ok: queued as 9D147170DC"
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 250 2.6.0 Ok, id=02524-01, from MTA([192.168.0.105]:10025): 250 2.0.0 Ok: queued as 9D147170DC
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) TempDir::strip: /var/amavis/tmp/amavis-20070226T134540-02524
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) rmdir_recursively: /var/amavis/tmp/amavis-20070226T134540-02524/parts, excl=1
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) TIMING [total 2527 ms] - SMTP greeting: 6 (0%)0, SMTP LHLO: 1 (0%)0, SMTP pre-MAIL: 3 (0%)0, mkdir tempdir: 1 (0%)0, create email.txt: 1 (0%)0, SMTP pre-DATA-flush: 4 (0%)1, SMTP DATA: 94 (4%)4, check_init: 1 (0%)4, digest_hdr: 1 (0%)4, digest_body: 0 (0%)4, gen_mail_id: 0 (0%)4, mkdir parts: 0 (0%)4, mime_decode: 11 (0%)5, get-file-type1: 22 (1%)6, decompose_part: 2 (0%)6, parts_decode: 0 (0%)6, check_header: 4 (0%)6, AV-scan-1: 10 (0%)6, spam-wb-list: 315 (12%)19, SA msg read: 1 (0%)19, SA parse: 3 (0%)19, SA check: 1879 (74%)93, update_cache: 5 (0%)94, decide_mail_destiny: 2 (0%)94, fwd-connect: 26 (1%)95, fwd-mail-from: 2 (0%)95, fwd-rcpt-to: 23 (1%)96, fwd-data-cmd: 1 (0%)96, write-header: 2 (0%)96, fwd-data-contents: 1 (0%)96, fwd-data-end: 4 (0%)96, fwd-rundown: 12 (0%)96, prepare-dsn: 2 (0%)96, main_log_entry: 85 (3%)100, update_snmp: 1 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 2 (0%)100, unlink-1-files: 1 (0%)100, rundown: 0 (0%)100
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 6: was busy, 2512.9 ms, total idle 0.001 s, busy 2.526 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.002 s, busy 2.526 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP< QUIT\r\n
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_my_time     480 s, SMTP QUIT received
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) LMTP> 221 2.0.0 [192.168.0.105] amavisd-new closing transmission channel
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) switch_to_client_time 480 s, smtp response sent
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) SMTP session over, timer stopped
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) exiting process_request
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) post_process_request_hook: timer was not running
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) idle_proc, bye: was busy, 3.5 ms, total idle 0.002 s, busy 2.530 s
Feb 26 13:45:42 mail.tabmow.info /usr/local/sbin/amavisd[2524]: (02524-01) load: 100 %, total idle 0.002 s, busy 2.530 s