May 28 12:21:25 seawall.homeport.org amavisd[98946]: starting. amavisd at seawall.homeport.org amavisd-new-20030616-p6 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Perl version 5.006001 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Amavis::Conf 1.15 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Archive::Tar 1.07 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Archive::Zip 1.08 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Compress::Zlib 1.31 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Convert::TNEF 0.17 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Convert::UUlib 0.31 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module MIME::Entity 5.404 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module MIME::Parser 5.406 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module MIME::Tools 5.411 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Mail::Header 1.60 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Mail::Internet 1.60 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Mail::SpamAssassin 2.60 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Net::Cmd 2.24 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Net::DNS 0.42 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Net::SMTP 2.26 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Net::Server 0.85 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Time::HiRes 1.51 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Module Unix::Syslog 0.100 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found myself: /usr/local/sbin/amavisd -c /usr/local/etc/amavisd.conf May 28 12:21:25 seawall.homeport.org amavisd[98946]: Lookup::SQL code NOT loaded May 28 12:21:25 seawall.homeport.org amavisd[98946]: Lookup::LDAP code NOT loaded May 28 12:21:25 seawall.homeport.org amavisd[98946]: AMCL-in protocol code loaded May 28 12:21:25 seawall.homeport.org amavisd[98946]: SMTP-in protocol code loaded May 28 12:21:25 seawall.homeport.org amavisd[98946]: ANTI-VIRUS code loaded May 28 12:21:25 seawall.homeport.org amavisd[98946]: ANTI-SPAM code loaded May 28 12:21:25 seawall.homeport.org amavisd[98946]: Net::Server: 2004/05/28-12:21:25 Amavis (type Net::Server::PreForkSimple) starting! pid(98946) May 28 12:21:25 seawall.homeport.org amavisd[98946]: Net::Server: Binding to UNIX socket file /var/amavis/amavisd.sock using SOCK_STREAM May 28 12:21:25 seawall.homeport.org amavisd[98946]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Net::Server: Setting gid to "1003 1003" May 28 12:21:25 seawall.homeport.org amavisd[98946]: Net::Server: Setting uid to "1002" May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $file at /usr/bin/file May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $arc at /usr/local/bin/arc May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $gzip at /usr/bin/gzip May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $bzip2 at /usr/bin/bzip2 May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $lzop at /usr/local/bin/lzop May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $lha at /usr/local/bin/lha May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $unarj at /usr/local/bin/unarj May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $uncompress at /usr/bin/uncompress May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $unfreeze at /usr/local/bin/unfreeze May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $unrar at /usr/local/bin/unrar May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $zoo at /usr/local/bin/zoo May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found $cpio at /usr/bin/cpio May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: KasperskyLab AntiViral Toolkit Pro (AVP) May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: KasperskyLab AVPDaemonClient May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: H+BEDV AntiVir or CentralCommand Vexira Antivirus May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: Command AntiVirus for Linux May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: Symantec CarrierScan via Symantec CommandLineScanner May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: Symantec AntiVirus Scan Engine May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: Dr.Web Antivirus for Linux/FreeBSD/Solaris May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: F-Secure Antivirus May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: CAI InoculateIT May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: MkS_Vir for Linux (beta) May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: MkS_Vir daemon May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: ESET Software NOD32 May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: ESET Software NOD32 - Client/Server Version May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: Norman Virus Control v5 / Linux May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: Panda Antivirus for Linux May 28 12:21:25 seawall.homeport.org amavisd[98946]: Found primary av scanner NAI McAfee AntiVirus (uvscan) at /usr/local/bin/uvscan May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: VirusBuster May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: CyberSoft VFind May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: Ikarus AntiVirus for Linux May 28 12:21:25 seawall.homeport.org amavisd[98946]: No primary av scanner: BitDefender May 28 12:21:25 seawall.homeport.org amavisd[98946]: No secondary av scanner: Clam Antivirus - clamscan May 28 12:21:25 seawall.homeport.org amavisd[98946]: No secondary av scanner: FRISK F-Prot Antivirus May 28 12:21:25 seawall.homeport.org amavisd[98946]: No secondary av scanner: Trend Micro FileScanner May 28 12:21:25 seawall.homeport.org amavisd[98946]: SpamControl: initializing Mail::SpamAssassin debug: Score set 0 chosen. debug: running in taint mode? yes debug: Running in taint mode, removing unsafe env vars, and resetting PATH debug: PATH included '/usr/local/sbin', keeping. debug: PATH included '/usr/local/bin', keeping. debug: PATH included '/usr/sbin', keeping. debug: PATH included '/sbin', keeping. debug: PATH included '/usr/bin', keeping. debug: PATH included '/bin', keeping. debug: Final PATH set to: /usr/local/sbin:/usr/local/bin:/usr/sbin:/sbin:/usr/bin:/bin debug: ignore: test message to precompile patterns and load modules debug: using "/usr/local/share/spamassassin" for default rules dir debug: using "/usr/local/etc/mail/spamassassin" for site rules dir debug: using "/var/amavis/.spamassassin/user_prefs" for user prefs file debug: bayes: 98946 tie-ing to DB file R/O /var/amavis/.spamassassin/bayes_toks debug: bayes: 98946 tie-ing to DB file R/O /var/amavis/.spamassassin/bayes_seen debug: bayes: found bayes db version 2 debug: Score set 3 chosen. debug: Initialising learner debug: is Net::DNS::Resolver available? yes debug: trying (3) nytimes.com... debug: looking up MX for 'nytimes.com' debug: MX for 'nytimes.com' exists? 1 debug: MX lookup of nytimes.com succeeded => Dns available (set dns_available to hardcode) debug: is DNS available? 1 debug: all '*From' addrs: ignore@compiling.spamassassin.taint.org debug: running header regexp tests; score so far=0 debug: running body-text per-line regexp tests; score so far=2.077 debug: bayes corpus size: nspam = 38050, nham = 53144 debug: uri tests: Done uriRE debug: tokenize: header tokens for *F = "U*ignore D*compiling.spamassassin.taint.org D*spamassassin.taint.org D*taint.org D*org" debug: tokenize: header tokens for *m = " 1085761285 50044 spamassassin_spamd_init " debug: bayes token 'H*F:D*org' => 0.0367110752093613 debug: bayes token 'somewhat' => 0.0628375163743604 debug: bayes: score = 0.010601396327133 debug: bayes: 98946 untie-ing debug: bayes: 98946 untie-ing db_toks debug: bayes: 98946 untie-ing db_seen debug: Razor2 is available debug: entering helper-app run mode debug: Using results from Razor v2.36 debug: Found Razor2 part: part=0 engine=4 ct=0 cf=0 debug: leaving helper-app run mode Razor-Log: Computed razorhome from env: /var/amavis/.razor Razor-Log: Found razorhome: /var/amavis/.razor Razor-Log: No /var/amavis/.razor/razor-agent.conf found, skipping. Razor-Log: No razor-agent.conf found, using defaults. May 28 12:21:27.684417 check[98946]: [ 1] [bootup] Logging initiated LogDebugLevel=9 to stdout May 28 12:21:27.685478 check[98946]: [ 5] computed razorhome=/var/amavis/.razor, conf=, ident=/var/amavis/.razor/identity May 28 12:21:27.686005 check[98946]: [ 8] Client supported_engines: 1 2 3 4 May 28 12:21:27.686990 check[98946]: [ 8] prep_mail done: mail 1 headers=102, mime0=1376 May 28 12:21:27.687848 check[98946]: [ 5] read_file: 1 items read from /var/amavis/.razor/servers.discovery.lst May 28 12:21:27.688582 check[98946]: [ 5] read_file: 2 items read from /var/amavis/.razor/servers.nomination.lst May 28 12:21:27.689451 check[98946]: [ 5] read_file: 2 items read from /var/amavis/.razor/servers.catalogue.lst May 28 12:21:27.690404 check[98946]: [ 9] Assigning defaults to joy.cloudmark.com May 28 12:21:27.690902 check[98946]: [ 9] Assigning defaults to folly.cloudmark.com May 28 12:21:27.691496 check[98946]: [ 9] Assigning defaults to thrill.cloudmark.com May 28 12:21:27.691982 check[98946]: [ 9] Assigning defaults to pride.cloudmark.com May 28 12:21:27.694245 check[98946]: [ 5] read_file: 16 items read from /var/amavis/.razor/server.pride.cloudmark.com.conf May 28 12:21:27.695955 check[98946]: [ 5] read_file: 16 items read from /var/amavis/.razor/server.pride.cloudmark.com.conf May 28 12:21:27.697638 check[98946]: [ 5] read_file: 16 items read from /var/amavis/.razor/server.thrill.cloudmark.com.conf May 28 12:21:27.699282 check[98946]: [ 5] read_file: 16 items read from /var/amavis/.razor/server.thrill.cloudmark.com.conf May 28 12:21:27.701091 check[98946]: [ 5] read_file: 13 items read from /var/amavis/.razor/server.solace.cloudmark.com.conf May 28 12:21:27.702543 check[98946]: [ 5] read_file: 13 items read from /var/amavis/.razor/server.solace.cloudmark.com.conf May 28 12:21:27.704089 check[98946]: [ 5] read_file: 14 items read from /var/amavis/.razor/server.shock.cloudmark.com.conf May 28 12:21:27.705602 check[98946]: [ 5] read_file: 14 items read from /var/amavis/.razor/server.shock.cloudmark.com.conf May 28 12:21:27.707209 check[98946]: [ 5] read_file: 14 items read from /var/amavis/.razor/server.truth.cloudmark.com.conf May 28 12:21:27.708741 check[98946]: [ 5] read_file: 14 items read from /var/amavis/.razor/server.truth.cloudmark.com.conf May 28 12:21:27.710369 check[98946]: [ 5] read_file: 15 items read from /var/amavis/.razor/server.wonder.cloudmark.com.conf May 28 12:21:27.711942 check[98946]: [ 5] read_file: 15 items read from /var/amavis/.razor/server.wonder.cloudmark.com.conf May 28 12:21:27.712533 check[98946]: [ 5] 76317 seconds before closest server discovery May 28 12:21:27.713014 check[98946]: [ 6] thrill.cloudmark.com is a Catalogue Server srl 5003; computed min_cf=6, Server se: D8 May 28 12:21:27.713442 check[98946]: [ 8] Computed supported_engines: 4 May 28 12:21:27.713787 check[98946]: [ 8] Using next closest server thrill.cloudmark.com:2703, cached info srl 5003 May 28 12:21:27.714068 check[98946]: [ 8] mail 1 has no subject May 28 12:21:27.717035 check[98946]: [ 6] preproc: mail 1.0 went from 1376 bytes to 1339 May 28 12:21:27.717361 check[98946]: [ 6] computing sigs for mail 1.0, len 1339 May 28 12:21:27.720661 check[98946]: [ 6] skipping whitelist file (empty?): /var/amavis/.razor/razor-whitelist May 28 12:21:27.720974 check[98946]: [ 5] Connecting to thrill.cloudmark.com ... May 28 12:21:27.904740 check[98946]: [ 8] Connection established May 28 12:21:27.905140 check[98946]: [ 4] thrill.cloudmark.com >> 36 server greeting: sn=C&srl=5003&a=l&a=cg&ep4=7542-10 May 28 12:21:27.906253 check[98946]: [ 4] thrill.cloudmark.com << 25 May 28 12:21:27.906450 check[98946]: [ 6] cn=razor-agents&cv=2.36 May 28 12:21:27.907159 check[98946]: [ 6] thrill.cloudmark.com is a Catalogue Server srl 5003; computed min_cf=6, Server se: D8 May 28 12:21:27.907604 check[98946]: [ 8] Computed supported_engines: 4 May 28 12:21:27.907977 check[98946]: [ 8] mail 1.0 e4 sig: xFaZIZUVHk90OQfARnenjx5BZTMA May 28 12:21:27.908395 check[98946]: [ 8] preparing 1 queries May 28 12:21:27.908860 check[98946]: [ 8] sending 1 batches May 28 12:21:27.909246 check[98946]: [ 4] thrill.cloudmark.com << 52 May 28 12:21:27.909436 check[98946]: [ 6] a=c&e=4&ep4=7542-10&s=xFaZIZUVHk90OQfARnenjx5BZTMA May 28 12:21:28.226267 check[98946]: [ 4] thrill.cloudmark.com >> 5 May 28 12:21:28.226495 check[98946]: [ 6] response to sent.2 p=0 May 28 12:21:28.227436 check[98946]: [ 6] mail 1.0 e=4 sig=xFaZIZUVHk90OQfARnenjx5BZTMA: sig not found. May 28 12:21:28.227726 check[98946]: [ 7] method 4: mail 1.0: no-contention part, spam=0 May 28 12:21:28.227934 check[98946]: [ 7] method 4: mail 1: all non-contention parts not spam, mail not spam May 28 12:21:28.228137 check[98946]: [ 3] mail 1 is not known spam. May 28 12:21:28.228378 check[98946]: [ 5] disconnecting from server thrill.cloudmark.com May 28 12:21:28.228775 check[98946]: [ 4] thrill.cloudmark.com << 5 May 28 12:21:28.228962 check[98946]: [ 6] a=q debug: Razor2 results: spam? 0 highest cf score: 0 debug: running raw-body-text per-line regexp tests; score so far=2.077 debug: running uri tests; score so far=2.077 debug: uri tests: Done uriRE debug: running full-text regexp tests; score so far=2.077 debug: Razor2 is available debug: DCCifd is not available: no r/w dccifd socket found. debug: Current PATH is: /usr/local/sbin:/usr/local/bin:/usr/sbin:/sbin:/usr/bin:/bin debug: DCC is not available: no executable dccproc found. debug: Pyzor is not available: pyzor not found debug: all '*To' addrs: debug: RBL: success for 1 of 1 queries debug: running meta tests; score so far=2.077 debug: is spam? score=0.553 required=5 tests=BAYES_01,DATE_MISSING,NO_REAL_NAME May 28 12:21:28 seawall.homeport.org amavisd[98946]: SpamControl: done May 28 12:21:47 seawall.homeport.org amavisd[98948]: lookup_ip_acl: key="127.0.0.1" matches "127.0.0.1", result=1 May 28 12:21:47 seawall.homeport.org amavisd[98948]: prolong_timer after new request - timer reset: remaining time = 300 s May 28 12:21:47 seawall.homeport.org amavisd[98948]: SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready May 28 12:21:47 seawall.homeport.org amavisd[98948]: prolong_timer after reading SMTP command: remaining time = 300 s May 28 12:21:47 seawall.homeport.org amavisd[98948]: SMTP< EHLO seawall.homeport.org\r\n May 28 12:21:47 seawall.homeport.org amavisd[98948]: ESMTP> 250-[127.0.0.1] May 28 12:21:47 seawall.homeport.org amavisd[98948]: ESMTP> 250-PIPELINING May 28 12:21:47 seawall.homeport.org amavisd[98948]: ESMTP> 250-SIZE May 28 12:21:47 seawall.homeport.org amavisd[98948]: ESMTP> 250-8BITMIME May 28 12:21:47 seawall.homeport.org amavisd[98948]: ESMTP> 250 ENHANCEDSTATUSCODES May 28 12:21:47 seawall.homeport.org amavisd[98948]: prolong_timer after reading SMTP command: remaining time = 300 s May 28 12:21:47 seawall.homeport.org amavisd[98948]: ESMTP< MAIL FROM: SIZE=366\r\n May 28 12:21:47 seawall.homeport.org amavisd[98948]: prolong_timer after MAIL FROM received - timer reset: remaining time = 300 s May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP> 250 2.1.0 Sender dbs@homeport.org OK May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after reading SMTP command: remaining time = 300 s May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP< RCPT TO:\r\n May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP> 250 2.1.5 Recipient dbs@homeport.org OK May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after reading SMTP command: remaining time = 300 s May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP< DATA\r\n May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after DATA received - timer reset: remaining time = 300 s May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP::10024 /var/amavis/amavis-20040528T122147-98948: -> Received: SIZE=366 from seawall.homeport.org ([127.0.0.1]) by localhost (seawall.homeport.org [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 98948-01 for ; Fri, 28 May 2004 12:21:47 -0400 (EDT) May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP> 354 End data with . May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP< .\r\n May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) body hash: 17606261522eb87a1ce8023a53aa260b May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) Original mail size: 355; quota set to: 177500 bytes May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) Checking: -> May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) Extracting mime components May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) Issued a new file name: part-00001 May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) mime_decode: Content-type: text/plain, name: May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) Charging 8 bytes to remaining quota 177500 (out of 177500, (0%)) - by mime_decode May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after mime_decode-1: remaining time = 300 s May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) Checking for banned MIME types and names May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) check_for_banned - mime-type: text/plain May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) lookup_RE: key="text/plain", no match May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) decode_parts: level=1, #parts=1 : part-00001 May 28 12:21:47 seawall.homeport.org amavisd[98948]: (98948-01) run_command: [98952] /usr/bin/file /var/amavis/amavis-20040528T122147-98948/parts/part-00001 &1 May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) run_av: /usr/local/bin/uvscan status=0 (0 ),...s/amavis-20040528T122147-98948/parts/*\nScanning file /var/amavis/amavis-20040528T122147-98948/parts/part-00001\nScanning file /var/amavis/amavis-20040528T122147-98948/parts/part-00001/part-00001\n\nSummary report on /var/amavis/amavis-20040528T122147-98948/parts/*\nFile(s)\n...Total files: ........... ...2\n...Clean: ................. ...2\n...Possibly Infected: ..... ...0\nThank you for choosing to evaluate VirusScan from Network Associates.\nThis version of the software is for Evaluation Purposes Only and may be\nused for up to 30 days to determine if it meets your requirements. To\nlicense the software, or to obtain assistance during the evaluation\nprocess, please call (408) 988-3832. If you choose not to license the\nsoftware, you need to remove it from your system. All use of this\nsoftware is conditioned upon compliance with the license terms set forth\nin the README.TXT file. May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) run_av: clean May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after virus_scan: remaining time = 300 s May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) white_black_list: checking sender May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_RE: key="dbs@homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_hash: key="dbs@homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_hash: key="dbs@", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_hash: key="homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_hash: key=".homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_hash: key=".org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_hash: key=".", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) CALLING NoMailAudit::check debug: is Net::DNS::Resolver available? yes debug: IP is reserved, not looking up PTR debug: received-header: parsed as [ ip=10.0.0.11 rdns=10.0.0.11 helo=homeport.org by=seawall.homeport.org ident= ] debug: received-header: 'from' 10.0.0.11 has reserved IP debug: received-header: 'by' seawall.homeport.org has public IP 136.248.127.98 debug: received-header: relay 10.0.0.11 trusted? yes debug: all '*From' addrs: dbs@homeport.org debug: running header regexp tests; score so far=0 debug: running body-text per-line regexp tests; score so far=0.16 debug: bayes: 98948 tie-ing to DB file R/O /var/amavis/.spamassassin/bayes_toks debug: bayes: 98948 tie-ing to DB file R/O /var/amavis/.spamassassin/bayes_seen debug: bayes: found bayes db version 2 debug: bayes corpus size: nspam = 38050, nham = 53144 debug: uri tests: Done uriRE debug: tokenize: header tokens for X-Envelope-From = "" debug: tokenize: header tokens for *m = "seawall homeport org " debug: tokenize: header tokens for *F = "U*dbs D*homeport.org D*org" debug: tokenize: header tokens for To = "" debug: tokenize: header tokens for *r = " homeport.org (unknown [10.0.0]) by seawall.homeport.org (Postfix) ; " debug: bayes token 'HX-Envelope-From:sk:dbs@hom' => 8.32688438322241e-05 debug: bayes token 'H*F:U*dbs' => 0.00140621800320375 debug: bayes token 'H*r:10.0.0' => 0.0161051601795741 debug: bayes token 'H*r:sk:dbs@hom' => 0.0168592948489374 debug: bayes token 'H*F:D*homeport.org' => 0.0195852254486422 debug: bayes token 'N:H*r:NN.N.N' => 0.0281127745398464 debug: bayes token 'H*F:D*org' => 0.0367110752093613 debug: bayes token 'H*m:seawall' => 0.949162909111152 debug: bayes: score = 0.00710080053456791 debug: bayes: 98948 untie-ing debug: bayes: 98948 untie-ing db_toks debug: bayes: 98948 untie-ing db_seen debug: Razor2 is available debug: entering helper-app run mode Razor-Log: Computed razorhome from env: /var/amavis/.razor Razor-Log: Found razorhome: /var/amavis/.razor Razor-Log: No /var/amavis/.razor/razor-agent.conf found, skipping. Razor-Log: No razor-agent.conf found, using defaults. May 28 12:21:49.306622 check[98948]: [ 1] [bootup] Logging initiated LogDebugLevel=9 to stdout May 28 12:21:49.308726 check[98948]: [ 5] computed razorhome=/var/amavis/.razor, conf=, ident=/var/amavis/.razor/identity May 28 12:21:49.310242 check[98948]: [ 8] Client supported_engines: 1 2 3 4 May 28 12:21:49.313171 check[98948]: [ 8] prep_mail done: mail 1 headers=416, mime0=44 May 28 12:21:49.314879 check[98948]: [ 5] read_file: 1 items read from /var/amavis/.razor/servers.discovery.lst May 28 12:21:49.316471 check[98948]: [ 5] read_file: 2 items read from /var/amavis/.razor/servers.nomination.lst May 28 12:21:49.317713 check[98948]: [ 5] read_file: 2 items read from /var/amavis/.razor/servers.catalogue.lst May 28 12:21:49.319248 check[98948]: [ 9] Assigning defaults to joy.cloudmark.com May 28 12:21:49.320193 check[98948]: [ 9] Assigning defaults to folly.cloudmark.com May 28 12:21:49.321094 check[98948]: [ 9] Assigning defaults to thrill.cloudmark.com May 28 12:21:49.321977 check[98948]: [ 9] Assigning defaults to pride.cloudmark.com May 28 12:21:49.325120 check[98948]: [ 5] read_file: 16 items read from /var/amavis/.razor/server.pride.cloudmark.com.conf May 28 12:21:49.327536 check[98948]: [ 5] read_file: 16 items read from /var/amavis/.razor/server.pride.cloudmark.com.conf May 28 12:21:49.329808 check[98948]: [ 5] read_file: 16 items read from /var/amavis/.razor/server.thrill.cloudmark.com.conf May 28 12:21:49.332046 check[98948]: [ 5] read_file: 16 items read from /var/amavis/.razor/server.thrill.cloudmark.com.conf May 28 12:21:49.334104 check[98948]: [ 5] read_file: 13 items read from /var/amavis/.razor/server.solace.cloudmark.com.conf May 28 12:21:49.336147 check[98948]: [ 5] read_file: 13 items read from /var/amavis/.razor/server.solace.cloudmark.com.conf May 28 12:21:49.338287 check[98948]: [ 5] read_file: 14 items read from /var/amavis/.razor/server.shock.cloudmark.com.conf May 28 12:21:49.340383 check[98948]: [ 5] read_file: 14 items read from /var/amavis/.razor/server.shock.cloudmark.com.conf May 28 12:21:49.342422 check[98948]: [ 5] read_file: 14 items read from /var/amavis/.razor/server.truth.cloudmark.com.conf May 28 12:21:49.344497 check[98948]: [ 5] read_file: 14 items read from /var/amavis/.razor/server.truth.cloudmark.com.conf May 28 12:21:49.346825 check[98948]: [ 5] read_file: 15 items read from /var/amavis/.razor/server.wonder.cloudmark.com.conf May 28 12:21:49.348888 check[98948]: [ 5] read_file: 15 items read from /var/amavis/.razor/server.wonder.cloudmark.com.conf May 28 12:21:49.350101 check[98948]: [ 5] 63798 seconds before closest server discovery May 28 12:21:49.351084 check[98948]: [ 6] thrill.cloudmark.com is a Catalogue Server srl 5003; computed min_cf=6, Server se: D8 May 28 12:21:49.352026 check[98948]: [ 8] Computed supported_engines: 4 May 28 12:21:49.352786 check[98948]: [ 8] Using next closest server thrill.cloudmark.com:2703, cached info srl 5003 May 28 12:21:49.353565 check[98948]: [ 8] mail 1 Subject: test 11 May 28 12:21:49.357176 check[98948]: [ 6] preproc: mail 1.0 went from 44 bytes to 6 May 28 12:21:49.357978 check[98948]: [ 6] computing sigs for mail 1.0, len 6 May 28 12:21:49.362193 check[98948]: [ 6] skipping whitelist file (empty?): /var/amavis/.razor/razor-whitelist May 28 12:21:49.363365 check[98948]: [ 5] Connecting to thrill.cloudmark.com ... May 28 12:21:49.553488 check[98948]: [ 8] Connection established May 28 12:21:49.554353 check[98948]: [ 4] thrill.cloudmark.com >> 36 server greeting: sn=C&srl=5003&a=l&a=cg&ep4=7542-10 May 28 12:21:49.555798 check[98948]: [ 4] thrill.cloudmark.com << 25 May 28 12:21:49.556790 check[98948]: [ 6] cn=razor-agents&cv=2.36 May 28 12:21:49.557656 check[98948]: [ 6] thrill.cloudmark.com is a Catalogue Server srl 5003; computed min_cf=6, Server se: D8 May 28 12:21:49.558132 check[98948]: [ 8] Computed supported_engines: 4 May 28 12:21:49.559349 check[98948]: [ 8] mail 1.0 e4 sig: T8NtFHklPX_bMyU4sA8_0oYZTKoA May 28 12:21:49.560253 check[98948]: [ 8] preparing 1 queries May 28 12:21:49.560729 check[98948]: [ 8] sending 1 batches May 28 12:21:49.561172 check[98948]: [ 4] thrill.cloudmark.com << 52 May 28 12:21:49.561383 check[98948]: [ 6] a=c&e=4&ep4=7542-10&s=T8NtFHklPX_bMyU4sA8_0oYZTKoA May 28 12:21:49.809204 check[98948]: [ 4] thrill.cloudmark.com >> 13 May 28 12:21:49.809892 check[98948]: [ 6] response to sent.2 p=1&cf=-100 May 28 12:21:49.811158 check[98948]: [ 6] mail 1.0 e=4 sig=T8NtFHklPX_bMyU4sA8_0oYZTKoA: Not spam: cf -100 < min_cf 6 May 28 12:21:49.811476 check[98948]: [ 7] method 4: mail 1.0: no-contention part, spam=0 May 28 12:21:49.811697 check[98948]: [ 7] method 4: mail 1: all non-contention parts not spam, mail not spam May 28 12:21:49.811987 check[98948]: [ 3] mail 1 is not known spam. May 28 12:21:49.812240 check[98948]: [ 5] disconnecting from server thrill.cloudmark.com May 28 12:21:49.812687 check[98948]: [ 4] thrill.cloudmark.com << 5 May 28 12:21:49.812893 check[98948]: [ 6] a=q debug: Using results from Razor v2.36 debug: Found Razor2 part: part=0 engine=4 ct=0 cf=-100 debug: leaving helper-app run mode debug: Razor2 results: spam? 0 highest cf score: 0 debug: running raw-body-text per-line regexp tests; score so far=0.16 debug: running uri tests; score so far=0.16 debug: uri tests: Done uriRE debug: running full-text regexp tests; score so far=0.16 debug: Razor2 is available debug: DCCifd is not available: no r/w dccifd socket found. debug: DCC is not available: no executable dccproc found. debug: Pyzor is not available: pyzor not found debug: all '*To' addrs: dbs@homeport.org debug: DNS MX records found: 2 debug: RBL: success for 1 of 1 queries debug: running meta tests; score so far=0.977 debug: auto-learn? ham=0.1, spam=12, body-hits=0, head-hits=0.977 debug: auto-learn: currently using scoreset 3. recomputing score based on scoreset 1. debug: Score set 1 chosen. debug: auto-learn: original score: 0.977, recomputed score: 2.059 debug: Score set 3 chosen. debug: auto-learn? no: inside auto-learn thresholds debug: is spam? score=-3.923 required=5 tests=BAYES_00,MSGID_FROM_MTA_BACKUP,NO_REAL_NAME May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) RETURNED FROM NoMailAudit::check, time left: 0 s May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after spam_scan_SA: remaining time = 300 s May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) spam_scan: hits=-3.923 tests=BAYES_00,MSGID_FROM_MTA_BACKUP,NO_REAL_NAME May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after spam_scan: remaining time = 300 s May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup: (scalar) matches, result="5.1" May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) header: Received: from seawall.homeport.org ([127.0.0.1])\n by localhost (seawall.homeport.org [127.0.0.1]) (amavisd-new, port 10024)\n with ESMTP id 98948-01 for ;\n Fri, 28 May 2004 12:21:47 -0400 (EDT)\n May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) header: X-Virus-Scanned: by amavisd-new at homeport.org\n May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup_acl: key="dbs@homeport.org", no match May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup: (scalar) matches, result="-99" May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) lookup: (scalar) matches, result="5.1" May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) headers CLUSTERING: NEW CLUSTER : hits=-3.9, tag=0, tag2=0, subj=0, local=0, bl=0 May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) headers CLUSTERING: done all 1 recips in one go May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) FWD via SMTP: [127.0.0.1:10025] -> May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) Remote host introduces itself as: seawall.homeport.org May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after fwd-connect: remaining time = 300 s May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after fwd-mail-from: remaining time = 300 s May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after fwd-rcpt-to: remaining time = 300 s May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) response to DATA: "354 End data with ." May 28 12:21:49 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after fwd-data: remaining time = 300 s May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after fwd-data-end: remaining time = 300 s May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) response to data end: "250 Ok: queued as E717939A52" May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after fwd-rundown-1: remaining time = 300 s May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) mail_via_smtp: 250 2.6.0 Ok, id=98948-01, from MTA: 250 Ok: queued as E717939A52 May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) prolong_timer after forwarding: remaining time = 300 s May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) one_response_for_all : success, dsn_needed=0, '250 2.6.0 Ok, id=98948-01, from MTA: 250 Ok: queued as E717939A52' May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) warnsender_with_pass=, dsn_needed=0, exit=0, 250 2.6.0 Ok, id=98948-01, from MTA: 250 Ok: queued as E717939A52 May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) Passed, -> , Message-ID: <20040528162137.61E1D39A0E@seawall.homeport.org>, Hits: -3.923 May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) sending SMTP response: "250 2.6.0 Ok, id=98948-01, from MTA: 250 Ok: queued as E717939A52" May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) timer stopped after DATA end May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) rmdir_recursively: /var/amavis/amavis-20040528T122147-98948/parts, excl=1 May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) TIMING [total 2348 ms] - SMTP EHLO: 12 (1%), SMTP pre-MAIL: 3 (0%), mkdir tempdir: 2 (0%), create email.txt: 2 (0%), SMTP pre-DATA-flush: 15 (1%), SMTP DATA: 86 (4%), body hash: 2 (0%), mkdir parts: 3 (0%), mime_decode: 38 (2%), get-file-type: 42 (2%), decompose_part: 9 (0%), parts: 0 (0%), AV-scan-1: 1150 (49%), SA msg read: 9 (0%), SA parse: 5 (0%), SA check: 759 (32%), fwd-connect: 38 (2%), fwd-mail-from: 5 (0%), fwd-rcpt-to: 6 (0%), write-header: 8 (0%), fwd-data: 0 (0%), fwd-data-end: 104 (4%), fwd-rundown: 28 (1%), unlink-1-files: 20 (1%), rundown: 2 (0%) May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP> 250 2.6.0 Ok, id=98948-01, from MTA: 250 Ok: queued as E717939A52 May 28 12:21:50 seawall.homeport.org amavisd[98948]: (98948-01) ESMTP: NOTICE: client broke the connection without a QUIT