Ticket #552: bad-header-whitelist-drop.txt

File bad-header-whitelist-drop.txt, 50.5 KB (added by mortonda@…, 9 years ago)

debug log showing the whitelist and drop.

Line 
1
2
3Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-03) Net::Server: 2010/06/22-11:58:40 CONNECT TCP Peer: "127.0.0.1:52207" Local: "127.0.0.1:10024"
4Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-03) idle_proc, hi : was idle, 159748.1 ms, total idle 592.116 s, busy 4.240 s
5Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-03) loaded base policy bank
6Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-03) lookup_ip_acl: key="127.0.0.1" matches "127.0.0.1", result=1
7Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-03) prolong_timer after new request - timer reset: remaining time = 480 s
8Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-03) process_request: suggested_protocol="" on TCP
9Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) SMTP> 220 [127.0.0.1] ESMTP amavisd-maia service ready
10Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 4: was busy, 2.0 ms, total idle 592.116 s, busy 4.242 s
11Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 5: was idle, 2.4 ms, total idle 592.118 s, busy 4.242 s
12Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after reading SMTP command: remaining time = 480 s
13Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) SMTP< EHLO localhost.localdomain\r\n
14Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250-[127.0.0.1]
15Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250-PIPELINING
16Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250-SIZE
17Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250-8BITMIME
18Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250-ENHANCEDSTATUSCODES
19Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO
20Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 6: was busy, 0.8 ms, total idle 592.118 s, busy 4.243 s
21Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 5: was idle, 0.5 ms, total idle 592.119 s, busy 4.243 s
22Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after reading SMTP command: remaining time = 480 s
23Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP< MAIL FROM:<testWMVBXGT0@example.com>\r\n
24Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after MAIL FROM received - timer reset: remaining time = 480 s
25Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (debug_sender) => undef, "testwmvbxgt0@example.com" does not match
26Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250 2.1.0 Sender testWMVBXGT0@example.com OK
27Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 6: was busy, 1.0 ms, total idle 592.119 s, busy 4.244 s
28Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 5: was idle, 0.4 ms, total idle 592.119 s, busy 4.244 s
29Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after reading SMTP command: remaining time = 480 s
30Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP< RCPT TO:<mortonda@dgrmm.net>\r\n
31Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
32Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
33Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
34Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(message_size_limit), no field, "mortonda@dgrmm.net" result=undef
35Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(message_size_limit), no field, "mortonda@dgrmm.net" result=undef
36Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(message_size_limit), no field, "mortonda@dgrmm.net" result=undef
37Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (message_size_limit) => undef, "mortonda@dgrmm.net" does not match
38Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250 2.1.5 Recipient mortonda@dgrmm.net OK
39Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 6: was busy, 1.3 ms, total idle 592.119 s, busy 4.245 s
40Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 5: was idle, 0.4 ms, total idle 592.120 s, busy 4.245 s
41Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after reading SMTP command: remaining time = 480 s
42Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP< DATA\r\n
43Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after DATA received - timer reset: remaining time = 480 s
44Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP::10024 /var/amavisd/tmp/amavis-20100622T114844-05925: <testwmvbxgt0@example.com> -> <mortonda@dgrmm.net> Received: from localhost.localdomain ([127.0.0.1]) by localhost (host.domain.tld [127.0.0.1]) (amavisd-maia, port 10024) with ESMTP id 05925-04 for <mortonda@dgrmm.net>; Tue, 22 Jun 2010 11:58:40 -0500 (CDT)
45Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 354 End data with <CR><LF>.<CR><LF>
46Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP< .\r\n
47Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) body hash: b93117979e7c43ecb28c4af4503490f3
48Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Original mail size: 62449; quota set to: 31224500 bytes
49Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Checking: <testwmvbxgt0@example.com> -> <mortonda@dgrmm.net>
50Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [connect_to_sql] Connecting to SQL database server
51Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [connect_to_sql] Trying 'DBI:mysql:maia_1_0:localhost'
52Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [connect_to_sql] 'DBI:mysql:maia_1_0:localhost' succeeded
53Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [connect] Database type is MySQL
54Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] False negative management is ENABLED
55Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Virus scanning is ENABLED
56Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Spam filtering is ENABLED
57Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Bad header checking is ENABLED
58Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Banned files checking is ENABLED
59Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] User autocreation is ENABLED
60Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Internal authentication mechanism is DISABLED
61Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Stats tracking is ENABLED
62Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Spam traps are ENABLED
63Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Mail larger than 1000000 bytes will be BOUNCED
64Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] The system default user (@.) WILL NOT apply to non-local recipients
65Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [read_system_config] Blowfish encryption is DISABLED
66Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [get_mysql_size_limit] MySQL max_allowed_packet size is 16776192 bytes
67Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) cached b93117979e7c43ecb28c4af4503490f3 from <testwmvbxgt0@example.com> (1,0,1)
68Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) cache entry age: . c=20100622T165558 a=20100622T165558
69Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Extracting mime components
70Jun 22 11:58:40 host.domain.tld ./amavisd-maia[5925]: (05925-04) Issued a new file name: p001
71Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) WARN: MIME::Parser error: couldn't parse head; error near:; helo=nascimento); by reseller12.hrwebservices.net with esmtpsa (TLSv1:AES256-SHA:256); (Exim 4.69); (envelope-from <geral@espacodearquitectura.com>); id 1OOsIb-00073N-KY; for mortonda@dgrmm.net; Wed, 16 Jun 20...
72Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Charging 59960 bytes to remaining quota 31224500 (out of 31224500, (0%)) - by mime_decode
73Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) p001 1 Content-Type: text/plain, size: 59960 B, name:
74Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after mime_decode-1: remaining time = 480 s
75Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) decode_parts: level=1, #parts=1 : p001
76Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) run_command: [6013] /usr/bin/file p001 </dev/null 2>&1
77Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) result line from file(1): p001: ASCII text
78Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_re(ASCII text) matches key "(?i-xsm:^(ASCII|text)\b)", result=asc
79Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (map_full_type_to_short_type) => true,  "ASCII text" matches, result="asc", matching_key="(?i-xsm:^(ASCII|text)\\b)"
80Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) File-type of p001: ASCII text; (asc)
81Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) do_ascii: Decoding part p001 (1 items), uulib V0.5pl20
82Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) do_ascii(0): state=0x10, enc=base64, est.size=43817, name=UNKNOWN.001
83Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Issued a new file name: p002
84Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Charging 44385 bytes to remaining quota 31164540 (out of 31224500, (0%)) - by do_ascii
85Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_re(ASCII text) matches key "(?i-xsm:^(ASCII(?! cpio)|text|uuencoded|xxencoded|binhex))", result=1
86Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (keep_decoded_original) => true,  "ASCII text" matches, result="1", matching_key="(?i-xsm:^(ASCII(?! cpio)|text|uuencoded|xxencoded|binhex))"
87Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) file type is ASCII text, retain original p001
88Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) decompose_part: p001 - source retained
89Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) decode_parts: level=2, #parts=1 : p002
90Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) run_command: [6014] /usr/bin/file p002 </dev/null 2>&1
91Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) result line from file(1): p002: HTML document text
92Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_re(HTML document text) matches key "(?-xism:^HTML document text\b)", result=html
93Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (map_full_type_to_short_type) => true,  "HTML document text" matches, result="html", matching_key="(?-xism:^HTML document text\\b)"
94Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) File-type of p002: HTML document text; (html)
95Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) decompose_part: p002 - atomic
96Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after parts_decode: remaining time = 480 s
97Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) query_keys: mortonda@dgrmm.net, mortonda@, dgrmm.net, .dgrmm.net, .net, .
98Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_hash(mortonda@dgrmm.net), no matches
99Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (local_domains) => undef, "mortonda@dgrmm.net" does not match
100Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) query_keys: mortonda@dgrmm.net, @dgrmm.net, @.dgrmm.net, @.net, @.
101Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql "mortonda@dgrmm.net", query args: "mortonda@dgrmm.net", "@dgrmm.net", "@.dgrmm.net", "@.net", "@."
102Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql select: SELECT *,users.id FROM users,policy WHERE (users.policy_id=policy.id) AND (users.email IN (?,?,?,?,?)) ORDER BY users.priority DESC
103Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql: "mortonda@dgrmm.net" matches catchall, local=>undef
104Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql(mortonda@dgrmm.net) matches, result=(id=>"2", priority=>"12", policy_id=>"2", email=>"mortonda@dgrmm.net", maia_user_id=>"2", maia_domain_id=>"0", id=>"2", policy_name=>"mortonda@dgrmm.net", virus_lover=>"N", spam_lover=>"N", banned_files_lover=>"N", bad_header_lover=>"N", bypass_virus_checks=>"N", bypass_spam_checks=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", discard_viruses=>"N", discard_spam=>"N", discard_banned_files=>"N", discard_bad_headers=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag_level=>"1", spam_tag2_level=>"5", spam_kill_level=>"5", id=>"2")
105Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql(mortonda@dgrmm.net) matches, result=(id=>"39", priority=>"2", policy_id=>"43", email=>"@dgrmm.net", maia_user_id=>"34", maia_domain_id=>"13", id=>"39", policy_name=>"@dgrmm.net", virus_lover=>"Y", spam_lover=>"Y", banned_files_lover=>"Y", bad_header_lover=>"Y", bypass_virus_checks=>"Y", bypass_spam_checks=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", discard_viruses=>"N", discard_spam=>"N", discard_banned_files=>"N", discard_bad_headers=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag_level=>"999", spam_tag2_level=>"999", spam_kill_level=>"999", id=>"39")
106Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql(mortonda@dgrmm.net) matches, result=(id=>"1", priority=>"0", policy_id=>"1", email=>"@.", maia_user_id=>"1", maia_domain_id=>"1", id=>"1", policy_name=>"Default", virus_lover=>"Y", spam_lover=>"Y", banned_files_lover=>"Y", bad_header_lover=>"Y", bypass_virus_checks=>"Y", bypass_spam_checks=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", discard_viruses=>"N", discard_spam=>"N", discard_banned_files=>"N", discard_bad_headers=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag_level=>"999", spam_tag2_level=>"999", spam_kill_level=>"999", id=>"1", local=>-)
107Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bypass_header_checks) "mortonda@dgrmm.net" result=0
108Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (bypass_header_checks) => false, "mortonda@dgrmm.net" matches, result="0", matching_key="id=>"2", priority=>"12", policy_id=>"2", email=>"mortonda@dgrmm.net", maia_user_id=>"2", maia_domain_id=>"0", id=>"2", policy_name=>"mortonda@dgrmm.net", virus_lover=>"N", spam_lover=>"N", banned_files_lover=>"N", bad_header_lover=>"N", bypass_virus_checks=>"N", bypass_spam_checks=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", discard_viruses=>"N", discard_spam=>"N", discard_banned_files=>"N", discard_bad_headers=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag_level=>"1", spam_tag2_level=>"5", spam_kill_level=>"5", id=>"2""
109Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) no anti-virus code loaded, skipping virus_scan
110Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
111Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
112Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
113Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bypass_spam_checks) "mortonda@dgrmm.net" result=0
114Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (bypass_spam_checks) => false, "mortonda@dgrmm.net" matches, result="0", matching_key="/cached/"
115Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) wbl: checking sender <testwmvbxgt0@example.com>
116Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
117Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
118Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
119Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(id) "mortonda@dgrmm.net" result=2
120Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(id) "mortonda@dgrmm.net" result=39
121Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(id) "mortonda@dgrmm.net" result=1
122Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup, 3 matches for "mortonda@dgrmm.net", results: "/cached/"=>"2", "/cached/"=>"39", "/cached/"=>"1"
123Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) wbl: (SQL) recip <mortonda@dgrmm.net>, 3 matches
124Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [wblist_lookup] Sender: 'testwmvbxgt0@example.com', From: '', Recipient: 'mortonda@dgrmm.net'
125Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [filter_pattern] Original: 'patrick_mrchan@yahoo.com.hk', Filtered: 'patrick_mrchan\@yahoo\.com\.hk'
126Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [wblist_lookup] Testing regexp '/^patrick_mrchan\@yahoo\.com\.hk$/'
127Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [filter_pattern] Original: 'notromda@users.sourceforge.net', Filtered: 'notromda\@users\.sourceforge\.net'
128Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [wblist_lookup] Testing regexp '/^notromda\@users\.sourceforge\.net$/'
129Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [filter_pattern] Original: 'lkhkjhlkjhlkjh@example.com', Filtered: 'lkhkjhlkjhlkjh\@example\.com'
130Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [wblist_lookup] Testing regexp '/^lkhkjhlkjhlkjh\@example\.com$/'
131Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [filter_pattern] Original: '*@esxample.com', Filtered: '.*\@esxample\.com'
132Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [wblist_lookup] Testing regexp '/^.*\@esxample\.com$/'
133Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [filter_pattern] Original: 'test*@example.com', Filtered: 'test.*\@example\.com'
134Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [wblist_lookup] Testing regexp '/^test.*\@example\.com$/'
135Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [wblist_lookup] Sender 'testwmvbxgt0@example.com' from '' matches pattern 'test*@example.com', result: 'W'
136Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) wbl: (SQL) recip <mortonda@dgrmm.net>, rid=2, got: "W"
137Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) wbl: (SQL) recip <mortonda@dgrmm.net> whitelisted sender <testwmvbxgt0@example.com>
138Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) query_keys: mortonda@dgrmm.net, mortonda@, dgrmm.net, .dgrmm.net, .net, .
139Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_hash(mortonda@dgrmm.net), no matches
140Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (score_sender) => undef, "mortonda@dgrmm.net" does not match
141Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) wbl: whitelisted sender <testwmvbxgt0@example.com>
142Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) sender white/blacklisted, skipping spam_scan
143Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [recipient_is_local] Recipient mortonda@dgrmm.net is local
144Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) query_keys: mortonda@dgrmm.net, mortonda@, dgrmm.net, .dgrmm.net, .net, .
145Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_hash(mortonda@dgrmm.net), no matches
146Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup => undef, "mortonda@dgrmm.net" does not match
147Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [recipient_can_be_autocreated] Recipient mortonda@dgrmm.net can be auto-created
148Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [autocreate_users] Recipient mortonda@dgrmm.net already exists
149Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [recipient_is_local] Recipient mortonda@dgrmm.net is local
150Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [get_recipient_id] Recipient mortonda@dgrmm.net (id = 2)
151Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [store_mail] Stored mail item 692877 (61629 bytes)
152Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [recipient_is_local] Recipient mortonda@dgrmm.net is local
153Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [get_recipient_id] Recipient mortonda@dgrmm.net (id = 2)
154Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [set_mail_status] Sender of mail item (id = 692877) is whitelisted by recipient 2
155Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
156Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
157Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
158Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(spam_kill_level) "mortonda@dgrmm.net" result=5
159Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (spam_kill_level) => true,  "mortonda@dgrmm.net" matches, result="5", matching_key="/cached/"
160Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) BAD HEADER from <testwmvbxgt0@example.com>: MIME error: error: couldn't parse head; error near:; helo=nascimento); by reseller12.hrwebservices.net with esmtpsa (TLSv1:AES256-SHA:256); (Exim 4.69); (envelope-from <geral@espacodearquitectura.com>); id 1OOsIb-00073N-KY; for mortonda@dgrmm.net; Wed, 16 Jun 20...
161Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [recipient_is_local] Recipient mortonda@dgrmm.net is local
162Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [get_recipient_id] Recipient mortonda@dgrmm.net (id = 2)
163Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [set_mail_status] Recording mail item 692877 as INVALID MAIL HEADER for recipient 2
164Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
165Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
166Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
167Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bad_header_lover) "mortonda@dgrmm.net" result=0
168Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (bad_header_lovers) => false, "mortonda@dgrmm.net" matches, result="0", matching_key="/cached/"
169Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) do_virus: looking for per-recipient quarantine and admins
170Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
171Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
172Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
173Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bad_header_quarantine_to), no field, "mortonda@dgrmm.net" result=undef
174Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bad_header_quarantine_to), no field, "mortonda@dgrmm.net" result=undef
175Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bad_header_quarantine_to), no field, "mortonda@dgrmm.net" result=undef
176Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup: (scalar) matches, result="bad-header-quarantine"
177Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (bad_header_quarantine_to) => true,  "mortonda@dgrmm.net" matches, result="bad-header-quarantine", matching_key="(constant:bad-header-quarantine)"
178Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
179Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
180Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
181Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bad_header_admin), no field, "mortonda@dgrmm.net" result=undef
182Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bad_header_admin), no field, "mortonda@dgrmm.net" result=undef
183Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bad_header_admin), no field, "mortonda@dgrmm.net" result=undef
184Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_acl(mortonda@dgrmm.net), no match
185Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (bad_header_admin) => undef, "mortonda@dgrmm.net" does not match
186Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Skip admin notification, no administrators
187Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [recipient_is_local] Recipient mortonda@dgrmm.net is local
188Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
189Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
190Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
191Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(bypass_header_checks) "mortonda@dgrmm.net" result=0
192Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (bypass_header_checks) => false, "mortonda@dgrmm.net" matches, result="0", matching_key="/cached/"
193Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"N", banned_files_lover=>"N", bypass_banned_checks=>"N", bypass_header_checks=>"N", bypass_spam_checks=>"N", bypass_virus_checks=>"N", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"mortonda@dgrmm.net", id=>"2", maia_domain_id=>"0", maia_user_id=>"2", policy_id=>"2", policy_name=>"mortonda@dgrmm.net", priority=>"12", spam_kill_level=>"5", spam_lover=>"N", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"5", spam_tag_level=>"1", virus_lover=>"N")
194Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@dgrmm.net", id=>"39", maia_domain_id=>"13", maia_user_id=>"34", policy_id=>"43", policy_name=>"@dgrmm.net", priority=>"2", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
195Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql (cached): "mortonda@dgrmm.net" matches, result=(bad_header_lover=>"Y", banned_files_lover=>"Y", bypass_banned_checks=>"Y", bypass_header_checks=>"Y", bypass_spam_checks=>"Y", bypass_virus_checks=>"Y", discard_bad_headers=>"N", discard_banned_files=>"N", discard_spam=>"N", discard_viruses=>"N", email=>"@.", id=>"1", local=>-, maia_domain_id=>"1", maia_user_id=>"1", policy_id=>"1", policy_name=>"Default", priority=>"0", spam_kill_level=>"999", spam_lover=>"Y", spam_modifies_subj=>"N", spam_quarantine_to=>-, spam_tag2_level=>"999", spam_tag_level=>"999", virus_lover=>"Y")
196Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(warnbadhrecip), no field, "mortonda@dgrmm.net" result=undef
197Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(warnbadhrecip), no field, "mortonda@dgrmm.net" result=undef
198Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_sql_field(warnbadhrecip), no field, "mortonda@dgrmm.net" result=undef
199Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup (warnbadhrecip) => undef, "mortonda@dgrmm.net" does not match
200Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) do_virus: recipient notifications not required
201Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) DO_VIRUS - DONE
202Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after Take Action!: remaining time = 480 s
203Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) one_response_for_all <testwmvbxgt0@example.com>: all DISCARD, '250 2.6.0 Ok, message with invalid header discarded, id=05925-04 - MIME error: error: couldn't parse head; error near:; helo=nascimento); by reseller12.hrwebservices.net with esmtpsa (TLSv1:AES256-SHA:256); (Exim 4.69); (envelope-from <geral@espacodearquitectura.com>); id 1OOsIb-00073N-KY; for mortonda@dgrmm.net; Wed, 16 Jun 20...'
204Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) warnsender_with_pass= (,,,), dsn_needed=, exit=0, 250 2.6.0 Ok, message with invalid header discarded, id=05925-04 - MIME error: error: couldn't parse head; error near:; helo=nascimento); by reseller12.hrwebservices.net with esmtpsa (TLSv1:AES256-SHA:256); (Exim 4.69); (envelope-from <geral@espacodearquitectura.com>); id 1OOsIb-00073N-KY; for mortonda@dgrmm.net; Wed, 16 Jun 20...
205Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after delivery-notification: remaining time = 480 s
206Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: from = bl15-242-175.dsl.telepac.pt /bl15-242-175.dsl.telepac.pt//
207Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) fish_out_ip_from_received: , bl15-242-175.dsl.telepac.pt
208Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: from = reseller12.hrwebservices.net ([216.120.228.13])\t/reseller12.hrwebservices.net//216.120.228.13
209Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: by = relay1.hrnoc.net /relay1.hrnoc.net//
210Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: with = esmtp /esmtp //
211Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: id = 1OOsIk-0005Vz-Dg\t/1OOsIk-0005Vz-Dg\t//
212Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: for = mortonda@dgrmm.net/mortonda@dgrmm.net//
213Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: ; = Wed, 16 Jun 2010 09:05:07 -0400/Wed, 16 Jun 2010 09:05:07 -0400//
214Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) fish_out_ip_from_received: 216.120.228.13, reseller12.hrwebservices.net ([216.120.228.13])\t
215Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_ip_acl: key="216.120.228.13" matches "::FFFF:0:0/96", result=1
216Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) best_try_originator_ip: 216.120.228.13
217Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: from = bl15-242-175.dsl.telepac.pt /bl15-242-175.dsl.telepac.pt//
218Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) fish_out_ip_from_received: , bl15-242-175.dsl.telepac.pt
219Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: from = reseller12.hrwebservices.net ([216.120.228.13])\t/reseller12.hrwebservices.net//216.120.228.13
220Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: by = relay1.hrnoc.net /relay1.hrnoc.net//
221Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: with = esmtp /esmtp //
222Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: id = 1OOsIk-0005Vz-Dg\t/1OOsIk-0005Vz-Dg\t//
223Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: for = mortonda@dgrmm.net/mortonda@dgrmm.net//
224Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) parse_received: ; = Wed, 16 Jun 2010 09:05:07 -0400/Wed, 16 Jun 2010 09:05:07 -0400//
225Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) fish_out_ip_from_received: 216.120.228.13, reseller12.hrwebservices.net ([216.120.228.13])\t
226Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) lookup_ip_acl: key="216.120.228.13" matches "::FFFF:0:0/96", result=1
227Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) best_try_originator_ip: 216.120.228.13
228Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Blocked BAD-HEADER, [216.120.228.13] <testwmvbxgt0@example.com> -> <mortonda@dgrmm.net>, Hits: 2.163, 191 ms
229Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [cleanup] Unwanted mail item 692877 (no registered recipients)
230Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [delete_mail] Removing mail item 692877
231Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) Maia: [disconnect] Disconnecting from SQL database
232Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) sending SMTP response: "250 2.6.0 Ok, message with invalid header discarded, id=05925-04 - MIME error: error: couldn't parse head; error near:; helo=nascimento); by reseller12.hrwebservices.net with esmtpsa (TLSv1:AES256-SHA:256); (Exim 4.69); (envelope-from <geral@espacodearquitectura.com>); id 1OOsIb-00073N-KY; for mortonda@dgrmm.net; Wed, 16 Jun 20..."
233Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) timer stopped after DATA end
234Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) strip_tempdir: /var/amavisd/tmp/amavis-20100622T114844-05925
235Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) rmdir_recursively: /var/amavisd/tmp/amavis-20100622T114844-05925/parts, excl=1
236Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) TIMING [total 196 ms] - SMTP EHLO: 5 (2%), SMTP pre-MAIL: 1 (1%), SMTP pre-DATA-flush: 4 (2%), SMTP DATA: 44 (22%), body_hash: 1 (0%), maia_connect: 2 (1%), maia_read_system_config: 2 (1%), maia_get_mysql_size_limit: 1 (0%), mime_decode: 10 (5%), get-file-type1: 14 (7%), decompose_part: 7 (4%), get-file-type1: 11 (6%), parts_decode: 0 (0%), lookup_sql: 4 (2%), spam-wb-list: 9 (4%), update_cache: 0 (0%), maia_autocreate_users: 2 (1%), maia_store_mail: 24 (12%), maia_set_mail_status: 6 (3%), maia_set_mail_status: 23 (12%), deal_with_bad_headers: 9 (5%), deal_with_mail_size: 0 (0%), checking_sender_ip: 0 (0%), Take Action!: 0 (0%), main_log_entry: 13 (6%), update_snmp: 0 (0%), maia_delete_mail: 3 (2%), maia_cleanup: 0 (0%), maia_disconnect: 1 (0%), unlink-2-files: 1 (1%), rundown: 0 (0%)
237Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 250 2.6.0 Ok, message with invalid header discarded, id=05925-04 - MIME error: error: couldn't parse head; error near:; helo=nascimento); by reseller12.hrwebservices.net with esmtpsa (TLSv1:AES256-SHA:256); (Exim 4.69); (envelope-from <geral@espacodearquitectura.com>); id 1OOsIb-00073N-KY; for mortonda@dgrmm.net; Wed, 16 Jun 20...
238Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 6: was busy, 188.7 ms, total idle 592.120 s, busy 4.434 s
239Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, 5: was idle, 0.3 ms, total idle 592.120 s, busy 4.434 s
240Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) prolong_timer after reading SMTP command: remaining time = 0 s
241Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP< QUIT\r\n
242Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-maia closing transmission channel
243Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) extra modules loaded: /usr/local/lib/perl/5.8.8/auto/NetAddr/IP/Util/inet_n2dx.al, /usr/local/lib/perl/5.8.8/auto/NetAddr/IP/Util/ipv6_n2d.al, /usr/local/lib/perl/5.8.8/auto/NetAddr/IP/Util/ipv6_n2x.al, Mail/SpamAssassin/Bayes/CombineChi.pm, Mail/SpamAssassin/Locales.pm, Mail/SpamAssassin/Plugin/Bayes.pm, Mail/SpamAssassin/Plugin/BodyEval.pm, Mail/SpamAssassin/Plugin/Check.pm, Mail/SpamAssassin/Plugin/DNSEval.pm, Mail/SpamAssassin/Plugin/FreeMail.pm, Mail/SpamAssassin/Plugin/HTMLEval.pm, Mail/SpamAssassin/Plugin/HTTPSMismatch.pm, Mail/SpamAssassin/Plugin/HeaderEval.pm, Mail/SpamAssassin/Plugin/ImageInfo.pm, Mail/SpamAssassin/Plugin/MIMEEval.pm, Mail/SpamAssassin/Plugin/RelayEval.pm, Mail/SpamAssassin/Plugin/URIDetail.pm, Mail/SpamAssassin/Plugin/URIEval.pm, Mail/SpamAssassin/Plugin/VBounce.pm, Mail/SpamAssassin/Plugin/WLBLEval.pm
244Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) post_process_request_hook: timer stopped
245Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) idle_proc, bye: was busy, 4.8 ms, total idle 592.120 s, busy 4.439 s
246Jun 22 11:58:41 host.domain.tld ./amavisd-maia[5925]: (05925-04) load: 1 %, total idle 592.120 s, busy 4.439 s
247
248
249
250
251