Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) SMTP> 220 [10.240.240.10] ESMTP [MIKROVISATA Mail Scanner] Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 4: was busy, 4.9 ms, total idle 431.167 s, busy 161.241 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 5: was idle, 0.3 ms, total idle 431.167 s, busy 161.241 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) SMTP< EHLO mx1.mikrovisata.net\r Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, SMTP EHLO received Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250-[10.240.240.10] Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250-VRFY Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250-PIPELINING Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250-SIZE Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250-ENHANCEDSTATUSCODES Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250-8BITMIME Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250-DSN Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 6: was busy, 2.6 ms, total idle 431.167 s, busy 161.244 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 5: was idle, 0.3 ms, total idle 431.168 s, busy 161.244 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP< XFORWARD NAME=aase79.neoplus.adsl.tpnet.pl ADDR=83.5.216.79\r Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, SMTP XFORWARD received Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250 2.5.0 Ok XFORWARD Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 6: was busy, 1.4 ms, total idle 431.168 s, busy 161.246 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 5: was idle, 0.3 ms, total idle 431.168 s, busy 161.246 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP< XFORWARD PROTO=SMTP HELO=aase79.neoplus.adsl.tpnet.pl\r Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, SMTP XFORWARD received Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250 2.5.0 Ok XFORWARD Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 6: was busy, 1.4 ms, total idle 431.168 s, busy 161.247 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 5: was idle, 0.3 ms, total idle 431.168 s, busy 161.247 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP< MAIL FROM: SIZE=1055\r Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, SMTP MAIL received Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) check_mail_begin_task: task_count=24 Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_ip_acl (mynetworks): key="83.5.216.79", no match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (debug_sender) => undef, "jainvolya@skippyproductions.com.au" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250 2.1.0 Sender OK Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 6: was busy, 3.5 ms, total idle 431.168 s, busy 161.250 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 5: was idle, 0.3 ms, total idle 431.168 s, busy 161.250 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP< RCPT TO: ORCPT=rfc822;atrakta@atrakta.lt\r Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, SMTP RCPT received Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250 2.1.5 Recipient OK Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 6: was busy, 1.8 ms, total idle 431.168 s, busy 161.252 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 5: was idle, 0.3 ms, total idle 431.169 s, busy 161.252 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP< DATA\r Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, SMTP DATA received Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP::10020 /var/mailscan/tmp/amavis-20080206T153705-11907: -> SIZE=1055 Received: from mx1.mikrovisata.net ([10.240.240.20]) by mailscan.mikrovisata.net (mailscan.mikrovisata.net [10.240.240.10]) (amavisd-new, port 10020) with ESMTP for ; Wed, 6 Feb 2008 15:46:57 +0200 (EET) Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 354 End data with . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, receiving data Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, data-end received Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP< . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_acl(atrakta@atrakta.lt), no match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: atrakta@atrakta.lt, atrakta@, atrakta.lt, .atrakta.lt, .lt, . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(atrakta@atrakta.lt) matches key "atrakta.lt", result=1 Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (local_domains) => true, "atrakta@atrakta.lt" matches, result="1", matching_key="atrakta.lt" Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) body type: unlabeled, but 8-bit header (h=1, b=0) Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) body hash: 1578ec23bd60f6ddca594183fc8c259a Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) Original mail size: 1055; quota set to: 527500 bytes Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) Checking: h1toIIKpnMuy MX-IN [83.5.216.79] -> Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) 2822.From: Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: atrakta@atrakta.lt, atrakta@, atrakta.lt, .atrakta.lt, .lt, . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(atrakta@atrakta.lt), no matches Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (bypass_virus_checks) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: atrakta@atrakta.lt, atrakta@, atrakta.lt, .atrakta.lt, .lt, . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(atrakta@atrakta.lt), no matches Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (bypass_header_checks) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) check_header: 2, Non-encoded 8-bit data (char D0 hex): Received: ...4.62 (FreeBSD))\\n\\tid 1JNGf\\320-0001NL-K2; Wed[...] Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: atrakta@atrakta.lt, atrakta@, atrakta.lt, .atrakta.lt, .lt, . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(atrakta@atrakta.lt), no matches Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (bypass_header_checks) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) Checking for banned types and filenames Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: atrakta@atrakta.lt, atrakta@, atrakta.lt, .atrakta.lt, .lt, . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(atrakta@atrakta.lt), no matches Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (bypass_banned_checks) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="DEFAULT" Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (banned_filename), 1 matches for "atrakta@atrakta.lt", results: "(constant:DEFAULT)"=>"DEFAULT" Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) collect banned table[0]: atrakta@atrakta.lt, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8e3bd54) Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) starting banned checks - traversing message structure tree Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) banned check: any=0, all=N (1) Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) Issued a new file name: p001 Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) presenting full original message to scanners as /var/mailscan/tmp/amavis-20080206T153705-11907/parts/p001 Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) Using ClamAV-clamd: (built-in interface) Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) timer set to 200 s (was 300 s) Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ask_av (ClamAV-clamd): query template1: CONTSCAN {} Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) Using (ClamAV-clamd) on dir: CONTSCAN /var/mailscan/tmp/amavis-20080206T153705-11907/parts Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) timer set to 10 s (was 200 s) Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ClamAV-clamd: Connecting to socket /var/mailscan/clamd.sock Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ClamAV-clamd: Sending CONTSCAN /var/mailscan/tmp/amavis-20080206T153705-11907/parts\n to UNIX socket /var/mailscan/clamd.sock Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer ask_daemon_internal: timer set to 160 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ask_av (ClamAV-clamd) result: /var/mailscan/tmp/amavis-20080206T153705-11907/parts: OK Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ask_av (ClamAV-clamd): /var/mailscan/tmp/amavis-20080206T153705-11907/parts CLEAN Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ClamAV-clamd result: clean Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer run_av: timer set to 300 s Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: atrakta@atrakta.lt, atrakta@, atrakta.lt, .atrakta.lt, .lt, . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(atrakta@atrakta.lt), no matches Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (bypass_virus_checks) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_acl(atrakta@atrakta.lt), no match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (bypass_spam_checks) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) wbl: checking sender Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (blacklist_recip) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: jainvolya@skippyproductions.com.au, jainvolya@, skippyproductions.com.au, .skippyproductions.com.au, .com.au, .au, . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(jainvolya@skippyproductions.com.au), no matches Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (blacklist_sender) => undef, "jainvolya@skippyproductions.com.au" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (whitelist_recip) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: jainvolya@skippyproductions.com.au, jainvolya@, skippyproductions.com.au, .skippyproductions.com.au, .com.au, .au, . Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(jainvolya@skippyproductions.com.au), no matches Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (whitelist_sender) => undef, "jainvolya@skippyproductions.com.au" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (score_recip) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) SpamControl: calling spam scanner Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) spam_scan: DSPAM not available, skipping it Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) timer set to 200 s for SA (was 300 s) Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) calling SA parse, SA version 3.2.3, 3.002003, data as GLOB Feb 6 15:46:57 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) CALLING SA check Feb 6 15:46:58 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) _WARN: dcc: dccifd -> check skipped: Illegal seek Can't call method "print" on an undefined value at /opt/csw/share/perl/csw/Mail/SpamAssassin/Plugin/DCC.pm line 429. at /opt/csw/share/perl/csw/Mail/SpamAssassin/Plugin/DCC.pm line 471. Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer spam_scan_sa_finish: timer set to 293 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) spam_scan: score=14.471 autolearn=disabled tests=[RAZOR2_CF_RANGE_51_100=0.5,RAZOR2_CF_RANGE_E8_51_100=1.5,RAZOR2_CHECK=0.5,RCVD_IN_PBL=0.509,RCVD_IN_SORBS_DUL=1.615,RCVD_IN_XBL=2.896,STOX_REPLY_TYPE=0.001,URIBL_BLACK=1.961,URIBL_JP_SURBL=2.857,URIBL_OB_SURBL=2.132] Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer spam_scan: remaining time = 293 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) enqueue: stat is not numeric: "" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_tag_level) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="6.31" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_tag2_level) => true, "atrakta@atrakta.lt" matches, result="6.31", matching_key="(constant:6.31)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_tag3_level) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="9.31" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_kill_level) => true, "atrakta@atrakta.lt" matches, result="9.31", matching_key="(constant:9.31)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_acl(atrakta@atrakta.lt), no match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (bypass_spam_checks) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: atrakta@atrakta.lt, atrakta@, atrakta.lt, .atrakta.lt, .lt, . Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(atrakta@atrakta.lt), no matches Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_lovers) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) blocking contents category is (6) for atrakta@atrakta.lt Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) blocking ccat=6, SMTP response: 554 5.7.0 Reject, id=11907-24 - SPAM Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) do_notify_and_quar: ccat=Spam (6,0) ("6":Spam, "5":Spammy, "4,2":BadHdr8bit, "4":BadHdr, "1,1":CleanTag, "1":Clean, "0":CatchAll) ccat_block=(6), q_mth=local:spam/%m.gz, qar_mth= Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_tag_level) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="6.31" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_tag2_level) => true, "atrakta@atrakta.lt" matches, result="6.31", matching_key="(constant:6.31)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="9.31" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_kill_level) => true, "atrakta@atrakta.lt" matches, result="9.31", matching_key="(constant:9.31)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="spam-quarantine" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_quarantine_to) => true, "atrakta@atrakta.lt" matches, result="spam-quarantine", matching_key="(constant:spam-quarantine)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="15" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_quarantine_cutoff_level) => true, "atrakta@atrakta.lt" matches, result="15", matching_key="(constant:15)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) query_keys: atrakta@atrakta.lt, atrakta@, atrakta.lt, .atrakta.lt, .lt, . Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_hash(atrakta@atrakta.lt), no matches Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_admin) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_quarantine_bysender_to) => undef, "jainvolya@skippyproductions.com.au" does not match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: X-Amavis-Alert: BAD HEADER Non-encoded 8-bit data (char D0 hex): Received:\n\t...4.62 (FreeBSD))\\n\\tid 1JNGf\\320-0001NL-K2; Wed[...] Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: X-Spam-Flag: YES Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: X-Spam-Score: 14.471 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: X-Spam-Level: ************** Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: X-Spam-Status: Yes, score=14.471 tag=x tag2=6.31 kill=9.31\n\ttests=[RAZOR2_CF_RANGE_51_100=0.5, RAZOR2_CF_RANGE_E8_51_100=1.5,\n\tRAZOR2_CHECK=0.5, RCVD_IN_PBL=0.509, RCVD_IN_SORBS_DUL=1.615,\n\tRCVD_IN_XBL=2.896, STOX_REPLY_TYPE=0.001, URIBL_BLACK=1.961,\n\tURIBL_JP_SURBL=2.857, URIBL_OB_SURBL=2.132] Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: X-Quarantine-ID: Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: X-Envelope-To: Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: X-Envelope-From: Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: Received: from mx1.mikrovisata.net ([10.240.240.20])\n\tby mailscan.mikrovisata.net (mailscan.mikrovisata.net [10.240.240.10]) (amavisd-new, port 10020)\n\twith ESMTP id h1toIIKpnMuy for ;\n\tWed, 6 Feb 2008 15:46:57 +0200 (EET) Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) DO_QUARANTINE, sender: Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) local delivery: -> , mbx=/var/mailscan/quarantine/spam/h1toIIKpnMuy.gz Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: Delivered-To: spam-quarantine Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: Return-Path: Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) write_header: 0, Amavis::IO::Zlib=HASH(0xb059dbc) Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) one_response_for_all : success, r=0,b=0,d=0, ndn_needed=0, '250 2.6.0 Ok, delivered to /var/mailscan/quarantine/spam/h1toIIKpnMuy.gz, id=11907-24' Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) DO_QUARANTINE done Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) SPAM, -> , Yes, score=14.471 tag=x tag2=6.31 kill=9.31 tests=[RAZOR2_CF_RANGE_51_100=0.5, RAZOR2_CF_RANGE_E8_51_100=1.5, RAZOR2_CHECK=0.5, RCVD_IN_PBL=0.509, RCVD_IN_SORBS_DUL=1.615, RCVD_IN_XBL=2.896, STOX_REPLY_TYPE=0.001, URIBL_BLACK=1.961, URIBL_JP_SURBL=2.857, URIBL_OB_SURBL=2.132], autolearn=disabled, quarantine h1toIIKpnMuy (spam-quarantine) Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) skip admin notification, no administrators Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) do_notify_and_quarantine - done Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer aux_quarantine: remaining time = 293 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="15" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_dsn_cutoff_level_bysender) => true, "jainvolya@skippyproductions.com.au" matches, result="15", matching_key="(constant:15)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) dsn: . 554 Spam -> : on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=, mta_resp: "554 5.7.0 Reject, id=11907-24 - SPAM" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="15" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_dsn_cutoff_level) => true, "atrakta@atrakta.lt" matches, result="15", matching_key="(constant:15)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) DSN: NOTIFICATION: Action:failed, LOCAL 554 Spam, -> Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: Subject: Considered UNSOLICITED BULK EMAIL, apparently from you Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) string_to_mime_entity Subject: Considered UNSOLICITED BULK EMAIL, apparently from you Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: In-Reply-To: <001d01c868c6$b99ebb20$3a99ce6f@nvczp> Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) string_to_mime_entity In-Reply-To: <001d01c868c6$b99ebb20$3a99ce6f@nvczp> Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) header: Message-ID: Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) string_to_mime_entity Message-ID: Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) string_to_mime_entity: attaching original message headers Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) one_response_for_all : mixed, r=0,b=1,d=0, ndn_needed=1, '250 2.5.0 Ok, id=11907-24, BOUNCE' Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) notif=Y, suppressed=0, ndn_needed=1, exit=99, 250 2.5.0 Ok, id=11907-24, BOUNCE Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) (about to connect to [10.240.240.30]:10025) SEND via SMTP: <> -> Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp creating socket by IO::Socket::INET: 10.240.240.30 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: needline=1, flush=0, wr=0, timeout=30 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: receiving Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop read 57 chars< 220 mx2.mikrovisata.net ESMTP [MIKROVISATA Mail System]\r Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp resp to greeting: 220 mx2.mikrovisata.net ESMTP [MIKROVISATA Mail System] Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp cmd> EHLO mailscan.mikrovisata.net Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: needline=0, flush=1, wr=1, timeout=293 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: sending Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop send 31> EHLO mailscan.mikrovisata.net\r Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: needline=1, flush=0, wr=0, timeout=293 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: receiving Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop read 216 chars< 250-mx2.mikrovisata.net\r\n250-PIPELINING\r\n250-SIZE 15728640\r\n250-ETRN\r\n250-STARTTLS\r\n250-AUTH PLAIN LOGIN CRAM-MD5 DIGEST-MD5\r\n250-AUTH=PLAIN LOGIN CRAM-MD5 DIGEST-MD5\r\n250-ENHANCEDSTATUSCODES\r\n250-8BITMIME\r\n250 DSN\r Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp resp to EHLO: 250 mx2.mikrovisata.net\nPIPELINING\nSIZE 15728640\nETRN\nSTARTTLS\nAUTH PLAIN LOGIN CRAM-MD5 DIGEST-MD5\nAUTH=PLAIN LOGIN CRAM-MD5 DIGEST-MD5\nENHANCEDSTATUSCODES\n8BITMIME\nDSN Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) Remote host presents itself as: mx2.mikrovisata.net, handles DSN, handles PIPELINING Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) AUTH not needed, user='', MTA offers 'PLAIN LOGIN CRAM-MD5 DIGEST-MD5' Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp cmd> MAIL FROM:<> ENVID=AM..20080206T134704Z@mailscan.mikrovisata.net Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp cmd> RCPT TO: ORCPT=rfc822;jainvolya@skippyproductions.com.au Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp cmd> DATA Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: needline=0, flush=1, wr=1, timeout=120 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: sending Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop send 166> MAIL FROM:<> ENVID=AM..20080206T134704Z@mailscan.mikrovisata.net\r\nRCPT TO: ORCPT=rfc822;jainvolya@skippyproductions.com.au\r\nDATA\r Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: needline=1, flush=0, wr=0, timeout=293 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: receiving Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop read 65 chars< 250 2.1.0 Ok\r\n250 2.1.5 Ok\r\n354 End data with .\r Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp resp to MAIL (pip): 250 2.1.0 Ok Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp resp to RCPT (pip) (): 250 2.1.5 Ok, id=11907-24, from MTA([10.240.240.30]:10025): 250 2.1.5 Ok Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp resp to DATA: 354 End data with . Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) write_header: 1, Amavis::Out::SMTP::Protocol=HASH(0xb0d66e0) Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp cmd> QUIT Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: needline=0, flush=1, wr=1, timeout=293 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: sending Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop send 3739> Content-Type: multipart/report; report-type=delivery-status;\r\n boundary="----------=_1202305624-11907-0"\r\nContent-Transfer-Encoding: 7bit\r\nMIME-Version: 1.0\r\nSubject: Considered UNSOLICITED BULK EMAIL [...] Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: needline=1, flush=0, wr=0, timeout=293 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop: receiving Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rw_loop read 49 chars< 250 2.0.0 Ok: queued as BEA36382\r\n221 2.0.0 Bye\r Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp resp to data-dot (): 250 2.0.0 Ok: queued as BEA36382 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) smtp resp to QUIT: 221 2.0.0 Bye Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer fwd-end-chkpnt: timer set to 293 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) SEND via SMTP: <> -> ,ENVID=AM..20080206T134704Z@mailscan.mikrovisata.net 250 2.6.0 Ok, id=11907-24, from MTA([10.240.240.30]:10025): 250 2.0.0 Ok: queued as BEA36382 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) one_response_for_all <>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.0.0 Ok: queued as BEA36382' Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer delivery-notification: remaining time = 293 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.notice] (11907-24) Blocked SPAM, MX-IN [83.5.216.79] -> , quarantine: spam/h1toIIKpnMuy.gz, Message-ID: <001d01c868c6$b99ebb20$3a99ce6f@nvczp>, mail_id: h1toIIKpnMuy, Hits: 14.471, size: 1055, 7338 ms Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_tag_level) => undef, "atrakta@atrakta.lt" does not match Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="6.31" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_tag2_level) => true, "atrakta@atrakta.lt" matches, result="6.31", matching_key="(constant:6.31)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup: (scalar) matches, result="9.31" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup (spam_kill_level) => true, "atrakta@atrakta.lt" matches, result="9.31", matching_key="(constant:9.31)" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.notice] (11907-24) Blocked SPAM, -> , Hits: 14.471, tag=0, tag2=6.31, kill=9.31, L/Y/Y/Y Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer main_log_entry: remaining time = 293 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) updating snmp variables Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) prolong_timer check done: remaining time = 293 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) sending SMTP response: "250 2.5.0 Ok, id=11907-24, BOUNCE" Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 250 2.5.0 Ok, id=11907-24, BOUNCE Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) TempDir::strip: /var/mailscan/tmp/amavis-20080206T153705-11907 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) rmdir_recursively: /var/mailscan/tmp/amavis-20080206T153705-11907/parts, excl=1 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) TIMING [total 7350 ms] - SMTP greeting: 5 (0%)0, SMTP EHLO: 3 (0%)0, SMTP pre-MAIL: 5 (0%)0, SMTP pre-DATA-flush: 7 (0%)0, SMTP DATA: 1 (0%)0, check_init: 2 (0%)0, digest_hdr: 1 (0%)0, digest_body: 1 (0%)0, gen_mail_id: 1 (0%)0, check_header: 6 (0%)0, AV-scan-1: 11 (0%)1, spam-wb-list: 5 (0%)1, SA parse: 4 (0%)1, SA check: 7172 (98%)98, update_cache: 8 (0%)98, decide_mail_destiny: 4 (0%)98, open-mbx: 26 (0%)99, write-header: 4 (0%)99, save-to-local-mailbox: 1 (0%)99, prepare-dsn: 28 (0%)99, fwd-connect: 6 (0%)99, fwd-mail-pip: 5 (0%)99, fwd-rcpt-pip: 1 (0%)99, fwd-data-chkpnt: 0 (0%)99, write-header: 1 (0%)99, fwd-data-contents: 9 (0%)100, fwd-end-chkpnt: 14 (0%)100, main_log_entry: 13 (0%)100, update_snmp: 2 (0%)100, SMTP pre-response: 1 (0%)100, SMTP response: 1 (0%)100, unlink-1-files: 1 (0%)100, rundown: 1 (0%)100 Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 6: was busy, 7333.8 ms, total idle 431.169 s, busy 168.586 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, 5: was idle, 0.3 ms, total idle 431.169 s, busy 168.586 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP< QUIT\r Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, SMTP QUIT received Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) ESMTP> 221 2.0.0 [10.240.240.10] Bye Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_client_time 120 s, smtp response sent Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) SMTP session over, timer stopped Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) exiting process_request Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) post_process_request_hook: timer was not running Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, bye: was busy, 3.4 ms, total idle 431.169 s, busy 168.589 s Feb 6 15:47:04 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) load: 28 %, total idle 431.169 s, busy 168.589 s Feb 6 15:47:22 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) Net::Server: 2008/02/06-15:47:22 CONNECT TCP Peer: "10.240.240.20:61273" Local: "10.240.240.10:10020" Feb 6 15:47:22 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) idle_proc, hi : was idle, 17915.8 ms, total idle 449.085 s, busy 168.589 s Feb 6 15:47:22 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) loaded policy bank "MX-IN" Feb 6 15:47:22 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) lookup_ip_acl (inet_acl): key="10.240.240.20" matches "10.240.240.20", result=1 Feb 6 15:47:22 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) process_request: fileno sock=14, STDIN=0, STDOUT=1 Feb 6 15:47:22 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) switch_to_my_time 300 s, new request Feb 6 15:47:22 mailscan amavis[11907]: [ID 702911 local5.info] (11907-24) process_request: suggested_protocol="" on TCP