Content-Type: message/rfc822 Content-Transfer-Encoding: 7bit Content-Disposition: attachmentReceived: by 10.14.221.8 with SMTP id q8mr1709858eep.28.1352969458264; Thu, 15 Nov 2012 00:50:58 -0800 (PST) Received: from host.1xmarketinggroup.com (host.1xmarketinggroup.com. [173.199.159.128]) by mx.google.com with ESMTPS id c2si5378902eeo.35.2012.11.15.00.50.54 (version=TLSv1/SSLv3 cipher=OTHER); Thu, 15 Nov 2012 00:50:57 -0800 (PST) Received: from root by host.1xmarketinggroup.com with local (Exim 4.80) (envelope-from ) id 1TYv99-0008TV-AP for root@host.1xmarketinggroup.com; Thu, 15 Nov 2012 02:50:53 -0600 Received: by 10.60.49.134 with SMTP id u6csp836oen; Thu, 15 Nov 2012 00:50:59 -0800 (PST) Return-Path: From: "Cron Daemon" To: Subject: Cron /usr/local/cpanel/scripts/upcp --cron Date: Thu, 15 Nov 2012 02:50:03 -0600 Message-ID: MIME-Version: 1.0 Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: 7bit X-Mailer: Microsoft Outlook 14.0 Thread-Index: AQKpDamlugLcgs1MyWWGfBaQUYOOlQ== [20121115.025003] Detected cron=1 (cron mode set from command line) ---------------------------------------------------------------------------- ------------------------ => Log opened from /usr/local/cpanel/scripts/upcp at Thu Nov 15 02:50:03 2012 [20121115.025003] Detected cron=1 (cron mode set from command line) => Log closed Thu Nov 15 02:50:03 2012 [20121115.025003] Running Standardized hooks [20121115.025003] mtime on upcp is 1350060632 (Fri Oct 12 11:50:32 2012) ---------------------------------------------------------------------------- ------------------------ => Log opened from /usr/local/cpanel/scripts/updatenow at Thu Nov 15 02:50:03 2012 [20121115.025003] Detected version '11.34.0.9' from version file. [20121115.025004] Target version set to '11.34.0.9' [20121115.025004] Up to date (11.34.0.9) => Log closed Thu Nov 15 02:50:04 2012 => Log closed Thu Nov 15 02:50:04 2012 ---------------------------------------------------------------------------- ------------------------ => Log opened from /usr/local/cpanel/scripts/maintenance at Thu Nov 15 02:50:04 2012 [20121115.025004] Processing: Setting clock [20121115.025004] - Processing command `/usr/local/cpanel/scripts/rdate` [20121115.025004] [32595] Virtual environment detected - rdate skipped [20121115.025004] Processing: Checking for immutable files [20121115.025004] - Processing command `/usr/local/cpanel/scripts/check_immutable_files` [20121115.025004] [32596] Not testing for immutable files, because the test was last run approximately 2 days ago (less than 10 days) [20121115.025004] Processing: Assuring OS package management system is setup properly [20121115.025004] - Processing command `/usr/local/cpanel/scripts/checksyspkgsetup` [20121115.025004] [32597] Checking package system [20121115.025004] [32597] Checking that the RPM DB is OK... [20121115.025005] [32597] OK: RPM DB is responding to queries [20121115.025005] [32597] Testing if it's possible to install a simple RPM [20121115.025005] [32597] Now removing the RPM [20121115.025009] [32597] checksyspkgsetup: setup ok [20121115.025009] Processing: Checking CloudLinux installation [20121115.025009] - Processing command `/usr/local/cpanel/bin/cloudlinux_update` [20121115.025009] Processing: Updating system packages [20121115.025009] - Processing command `/usr/local/cpanel/scripts/rpmup` [20121115.025009] [32604] Loaded plugins: fastestmirror [20121115.025009] [32604] Determining fastest mirrors [20121115.025009] [32604] Excluding Packages in global exclude list [20121115.025010] [32604] Finished [20121115.025010] [32604] Setting up Update Process [20121115.025011] [32604] Resolving Dependencies [20121115.025011] [32604] --> Running transaction check [20121115.025011] [32604] ---> Package kernel-headers.i386 0:2.6.18-308.20.1.el5 set to be updated [20121115.025012] [32604] --> Finished Dependency Resolution [20121115.025012] [32604] [20121115.025012] [32604] Dependencies Resolved [20121115.025012] [32604] [20121115.025012] [32604] ============================================================================ ==== [20121115.025012] [32604] Package Arch Version Repository Size [20121115.025012] [32604] ============================================================================ ==== [20121115.025012] [32604] Updating: [20121115.025012] [32604] kernel-headers i386 2.6.18-308.20.1.el5 updates 1.3 M [20121115.025012] [32604] [20121115.025012] [32604] Transaction Summary [20121115.025012] [32604] ============================================================================ ==== [20121115.025012] [32604] Install 0 Package(s) [20121115.025012] [32604] Upgrade 1 Package(s) [20121115.025012] [32604] [20121115.025012] [32604] Total download size: 1.3 M [20121115.025012] [32604] Downloading Packages: [20121115.025012] [32604] Running rpm_check_debug [20121115.025012] [32604] Running Transaction Test [20121115.025012] [32604] Finished Transaction Test [20121115.025012] [32604] Transaction Test Succeeded [20121115.025012] [32604] Running Transaction [20121115.025013] [32604] [20121115.025013] Updating : kernel-headers 1/2 [20121115.025013] [32604] [20121115.025013] Cleanup : kernel-headers 2/2 [20121115.025013] [32604] [20121115.025013] [32604] Updated: [20121115.025013] [32604] kernel-headers.i386 0:2.6.18-308.20.1.el5 [20121115.025013] [32604] [20121115.025013] [32604] Complete! [20121115.025013] - Processing command `/usr/local/cpanel/scripts/sysup` [20121115.025013] [32607] centos 5 (Linux) detected. [20121115.025014] [32607] All Needed RPMS are installed. [20121115.025014] Processing: Running platform specific optimizations [20121115.025014] - Processing command `/usr/local/cpanel/scripts/vps_optimizer` [20121115.025014] Processing: Checking for a valid C Compiler. [20121115.025014] - Processing command `/usr/local/cpanel/scripts/checkccompiler` [20121115.025015] [32614] C compiler (/usr/bin/gcc) OK [20121115.025015] [32614] Tuned C compiler not available because it is not enabled [20121115.025015] Processing: Making sure all needed perl modules are installed [20121115.025015] - Processing command `/usr/local/cpanel/install/perlmods` [20121115.025016] [32652] The following modules are now being installed/updated/verified: Locales,version,CDB_File,Module::Build,BSD::Resource,Class::Std,Digest::MD5: :File,Expect,Encode::Guess,Encode::MIME::Name,Encode::Detect::Detector,Data: :Dump,File::Copy::Recursive,File::ReadBackwards,File::Find::Rule,IO::Tty,Sys ::Hostname::Long,local::lib,AppConfig,Template,YAML::Syck,JSON::Syck,cPanel: :MemTest,List::MoreUtils,DateTime::Locale,DateTime::TimeZone,DateTime,DB_Fil e,HTTP::Date,Scalar::Util,MIME::Base64,URI,Net::FTP,HTML::Tagset,HTML::Parse r,HTML::HeadParser,LWP,Bundle::LWP,DBI,Crypt::SSLeay,CPAN::SQLite,Data::Dump er,Digest::MD5,Digest::SHA1,Encode,ExtUtils::Constant,ExtUtils::Install,ExtU tils::ParseXS,File::Touch,Filesys::Df,Filesys::Virtual,Filter::Util::Call,Ge topt::Long,Getopt::Param::Tiny,IO::Interactive::Tiny,Compress::Raw::Zlib,Aut hen::Libwrap,Net::FTPSSL,Net::SSL,Net::SSLeay,IO::Compress::Gzip,IO::Scalar, IO::Socket::INET6,IO::Socket::SSL,IO::Stty,IO::Uncompress::Gunzip,Lchown,Lis t::Util,MD5,Net::DNS,Net::OSCAR,Net::LibIDN,Pod::Perldoc,Socket6,Storable,Sy s::Syslog,TAP::Harness,Term::ReadKey,Term::ReadLine::Perl,Time::HiRes,Tree:: MultiNode,Unix::PID,Unix::PID::Tiny,XML::LibXML::Common,XML::LibXML,XML::Par ser,XML::SAX,XML::Simple,lib::restrict,Crypt::Passwd::XS,DBD::mysql,Filesys: :Statvfs,Crypt::GPG,Class::Accessor,Class::Accessor::Fast,File::MMagic::XS,E mail::Valid,ExtUtils::MakeMaker,Mail::SRS,Acme::Spork,Archive::Tar,Archive:: Tar::Streamed,Archive::Zip,MIME::Lite,Business::OnlinePayment::AuthorizeNet, Business::UPS,CGI,Class::Std::Utils,Compress::Bzip2,Compress::Zlib,DBIx::MyP arsePP,DBD::SQLite2,Date::Parse,File::Tail,GD::Graph,GD::Text::Align,Memoize ,Geo::IPfree,HTTP::Daemon::App,IO::Socket::ByteCounter,Image::Size,Mail::Dom ainKeys,Error,NetAddr::IP,Net::DNS::Resolver::Programmable,Mail::SPF,Mail::S PF::Query,Mail::DKIM,IP::Country,Graph::Easy,Graph::Flowchart,Mail::SpamAssa ssin,URI::Escape,File::Find::Rule::Filesys::Virtual,File::Slurp,Net::DAV::Se rver,Net::Daemon,Net::Daemon::SSL,Net::LDAP,Net::LDAP::Schema,Net::LDAP::Ser ver,Net::IP::Match::Regexp,OLE::Storage_Lite,Parse::RecDescent,Quota,SVG::TT ::Graph,Safe::Hole,Text::CSV,Spreadsheet::ParseExcel,Spreadsheet::WriteExcel ,String::CRC32,SQL::Statement,Set::Crontab,Tie::IxHash,Tie::ShadowHash,Tie:: DBI,URI::URL,Bundle::Interchange,Devel::PPPort,Linux::Inotify2 [20121115.025016] [32652] Checking C compiler....C compiler (/usr/bin/cc) OK [20121115.025016] [32652] Tuned C compiler not available because it is not enabled....Done [20121115.025016] [32652] CPAN fallback is disabled since /var/cpanel/conserve_memory exists, and cpanm is available. [20121115.025016] [32652] Method: Using Perl Expect, Installer: cpanm [20121115.025016] [32652] You have make /usr/bin/make [20121115.025017] [32652] Falling back to HTTP::Tiny 0.009 [20121115.025017] [32652] You have /bin/tar: tar (GNU tar) 1.15.1 [20121115.025017] [32652] You have /usr/bin/unzip [20121115.025017] [32652] You have Cpanel::HttpRequest 2.1 [20121115.025017] [32652] Testing connection speed...(using fast method)...Done [20121115.025017] [32652] Ping:1 (ticks) Testing connection speed to cpan.mirrors.tds.net using pureperl...(59100.00 bytes/s)...Done [20121115.025017] [32652] Ping:1 (ticks) Testing connection speed to mirrors.servercentral.net using pureperl...(56100.00 bytes/s)...Done [20121115.025017] [32652] Ping:0 (ticks) Testing connection speed to cpan.uchicago.edu using pureperl...(61900.00 bytes/s)...Done [20121115.025017] [32652] Ping:2 (ticks) Testing connection speed to cpan.mirror.rafal.ca using pureperl...(19166.67 bytes/s)...Done [20121115.025017] [32652] Ping:1 (ticks) Testing connection speed to cpan.netnitco.net using pureperl...(3535.29 bytes/s)...Done [20121115.025017] [32652] Five usable mirrors located [20121115.025017] [32652] Ping:0 (ticks) Testing connection speed to 74.200.212.130 using pureperl...(57900.00 bytes/s)...Done [20121115.025017] [32652] Ping:0 (ticks) Testing connection speed to 216.104.43.138 using pureperl...(57900.00 bytes/s)...Done [20121115.025017] [32652] Ping:0 (ticks) Testing connection speed to 67.159.2.2 using pureperl...(57900.00 bytes/s)...Done [20121115.025017] [32652] Three usable fallback mirrors located [20121115.025017] [32652] Fetching http://cpan.uchicago.edu/pub/CPAN/index.html (connected:0).......(request attempt 1/12)...Resolving cpan.uchicago.edu...(resolve attempt 1/65)......connecting to 128.135.10.29...@128.135.10.29......connected......receiving...100%......req uest success......Done [20121115.025017] [32652] Mirror Check passed for cpan.uchicago.edu (/index.html) [20121115.025017] [32652] Searching on cpanmetadb ... [20121115.025017] [32652] Fetching http://cpanmetadb.cpanel.net/v1.0/package/Locales,version,CDB_File,Module::B uild,BSD::Resource,Class::Std,Digest::MD5::File,Expect,Encode::Guess,Encode: :MIME::Name,Encode::Detect::Detector,Data::Dump,File::Copy::Recursive,File:: ReadBackwards,File::Find::Rule,IO::Tty,Sys::Hostname::Long,local::lib,AppCon fig,Template,YAML::Syck,JSON::Syck,cPanel::MemTest,List::MoreUtils,DateTime: :Locale,DateTime::TimeZone,DateTime,DB_File,HTTP::Date,Scalar::Util,MIME::Ba se64,URI,Net::FTP,HTML::Tagset,HTML::Parser,HTML::HeadParser,LWP,Bundle::LWP ,DBI,Crypt::SSLeay,CPAN::SQLite,Data::Dumper,Digest::MD5,Digest::SHA1,Encode ,ExtUtils::Constant,ExtUtils::Install,ExtUtils::ParseXS,File::Touch,Filesys: :Df,Filesys::Virtual,Filter::Util::Call,Getopt::Long,Getopt::Param::Tiny,IO: :Interactive::Tiny,Compress::Raw::Zlib,Authen::Libwrap,Net::FTPSSL,Net::SSL, Net::SSLeay,IO::Compress::Gzip,IO::Scalar,IO::Socket::INET6,IO::Socket::SSL, IO::Stty,IO::Uncompress::Gunzip,Lchown,List::Util,MD5,Net::DNS,Net::OSCAR,Ne t::LibIDN,Pod::Perldoc,Socket6,Storable,Sys::Syslog,TAP::Harness,Term::ReadK ey,Term::ReadLine::Perl,Time::HiRes,Tree::MultiNode,Unix::PID,Unix::PID::Tin y,XML::LibXML::Common,XML::LibXML,XML::Parser,XML::SAX,XML::Simple,lib::rest rict,Crypt::Passwd::XS,DBD::mysql,Filesys::Statvfs,Crypt::GPG,Class::Accesso r,Class::Accessor::Fast,File::MMagic::XS?cpanel_version=11.34.0.9&cpanel_tie r=release (connected:0).......(request attempt 1/12)...Resolving cpanmetadb.cpanel.net...(resolve attempt 1/65)......searching for mirrors (mirror search attempt 1/3)......5 usable mirrors located. (less then expected)......mirror search success......connecting to 208.74.125.50...@208.74.125.50......connected......receiving...100%......req uest success......Done [20121115.025017] [32652] Searching on cpanmetadb ... [20121115.025017] [32652] Fetching http://cpanmetadb.cpanel.net/v1.0/package/Email::Valid,ExtUtils::MakeMaker,M ail::SRS,Acme::Spork,Archive::Tar,Archive::Tar::Streamed,Archive::Zip,MIME:: Lite,Business::OnlinePayment::AuthorizeNet,Business::UPS,CGI,Class::Std::Uti ls,Compress::Bzip2,Compress::Zlib,DBIx::MyParsePP,DBD::SQLite2,Date::Parse,F ile::Tail,GD::Graph,GD::Text::Align,Memoize,Geo::IPfree,HTTP::Daemon::App,IO ::Socket::ByteCounter,Image::Size,Mail::DomainKeys,Error,NetAddr::IP,Net::DN S::Resolver::Programmable,Mail::SPF,Mail::SPF::Query,Mail::DKIM,IP::Country, Graph::Easy,Graph::Flowchart,Mail::SpamAssassin,URI::Escape,File::Find::Rule ::Filesys::Virtual,File::Slurp,Net::DAV::Server,Net::Daemon,Net::Daemon::SSL ,Net::LDAP,Net::LDAP::Schema,Net::LDAP::Server,Net::IP::Match::Regexp,OLE::S torage_Lite,Parse::RecDescent,Quota,SVG::TT::Graph,Safe::Hole,Text::CSV,Spre adsheet::ParseExcel,Spreadsheet::WriteExcel,String::CRC32,SQL::Statement,Set ::Crontab,Tie::IxHash,Tie::ShadowHash,Tie::DBI,URI::URL,Bundle::Interchange, Devel::PPPort,Linux::Inotify2?cpanel_version=11.34.0.9&cpanel_tier=release (connected:1).......(request attempt 1/12)...@208.74.125.50......connected......receiving...100%......request success......Done [20121115.025018] [32652] Source: fastest CPAN mirror ... Locales is up to date. (0.27) [20121115.025018] [32652] Source: fastest CPAN mirror ... version is up to date. (0.9901) [20121115.025018] [32652] Source: fastest CPAN mirror ... CDB_File is up to date. (0.97) [20121115.025018] [32652] Source: fastest CPAN mirror ... Module::Build is up to date. (0.4003) [20121115.025018] [32652] Source: fastest CPAN mirror ... BSD::Resource is up to date. (1.2904) [20121115.025018] [32652] Source: fastest CPAN mirror ... Class::Std is up to date. (0.011) [20121115.025018] [32652] Source: fastest CPAN mirror ... Digest::MD5::File is up to date. (0.08) [20121115.025018] [32652] Source: fastest CPAN mirror ... Expect is up to date. (1.21) [20121115.025018] [32652] Source: fastest CPAN mirror ... Encode::Guess is up to date. (2.05) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried Encode-2.47. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... Encode::Detect::Detector is up to date. (1.01) [20121115.025018] [32652] Source: fastest CPAN mirror ... Data::Dump is up to date. (1.21) [20121115.025018] [32652] Source: fastest CPAN mirror ... File::Copy::Recursive is up to date. (0.38) [20121115.025018] [32652] Source: fastest CPAN mirror ... File::ReadBackwards is up to date. (1.05) [20121115.025018] [32652] Source: fastest CPAN mirror ... File::Find::Rule is up to date. (0.33) [20121115.025018] [32652] Source: fastest CPAN mirror ... IO::Tty is up to date. (1.10) [20121115.025018] [32652] Source: fastest CPAN mirror ... Sys::Hostname::Long is up to date. (1.4) [20121115.025018] [32652] Source: fastest CPAN mirror ... local::lib is up to date. (1.008004) [20121115.025018] [32652] Source: fastest CPAN mirror ... AppConfig is up to date. (1.66) [20121115.025018] [32652] Source: fastest CPAN mirror ... Template is up to date. (2.24) [20121115.025018] [32652] Source: fastest CPAN mirror ... YAML::Syck is up to date. (1.21) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried YAML-Syck-1.21. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... cPanel::MemTest is up to date. (0.3) [20121115.025018] [32652] Source: fastest CPAN mirror ... List::MoreUtils is up to date. (0.33) [20121115.025018] [32652] Source: fastest CPAN mirror ... DateTime::Locale is up to date. (0.45) [20121115.025018] [32652] Source: fastest CPAN mirror ... DateTime::TimeZone is up to date. (1.54) [20121115.025018] [32652] Source: fastest CPAN mirror ... DateTime is up to date. (0.77) [20121115.025018] [32652] Source: fastest CPAN mirror ... DB_File is up to date. (1.827) [20121115.025018] [32652] Source: fastest CPAN mirror ... HTTP::Date is up to date. (6.02) [20121115.025018] [32652] Source: fastest CPAN mirror ... Scalar::Util is up to date. (1.25) [20121115.025018] [32652] Source: fastest CPAN mirror ... MIME::Base64 is up to date. (3.13) [20121115.025018] [32652] Source: fastest CPAN mirror ... URI is up to date. (1.60) [20121115.025018] [32652] Source: fastest CPAN mirror ... Net::FTP is up to date. (2.77) [20121115.025018] [32652] Source: fastest CPAN mirror ... HTML::Tagset is up to date. (3.20) [20121115.025018] [32652] Source: fastest CPAN mirror ... HTML::Parser is up to date. (3.69) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried HTML-Parser-3.69. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... LWP is up to date. (6.04) [20121115.025018] [32652] Source: fastest CPAN mirror ... Bundle::LWP is up to date. (5.835) [20121115.025018] [32652] Source: fastest CPAN mirror ... DBI is up to date. (1.622) [20121115.025018] [32652] Source: fastest CPAN mirror ... Crypt::SSLeay is up to date. (0.64) [20121115.025018] [32652] Source: fastest CPAN mirror ... CPAN::SQLite is up to date. (0.202) [20121115.025018] [32652] Source: fastest CPAN mirror ... Data::Dumper is up to date. (2.136) [20121115.025018] [32652] Source: fastest CPAN mirror ... Digest::MD5 is up to date. (2.52) [20121115.025018] [32652] Source: fastest CPAN mirror ... Digest::SHA1 is up to date. (2.13) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried Encode-2.47. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... ExtUtils::Constant is up to date. (0.23) [20121115.025018] [32652] Source: fastest CPAN mirror ... ExtUtils::Install is up to date. (1.54) [20121115.025018] [32652] Source: fastest CPAN mirror ... ExtUtils::ParseXS is up to date. (3.15) [20121115.025018] [32652] Source: fastest CPAN mirror ... File::Touch is up to date. (0.08) [20121115.025018] [32652] Source: fastest CPAN mirror ... Filesys::Df is up to date. (0.92) [20121115.025018] [32652] Source: fastest CPAN mirror ... Filesys::Virtual is up to date. (0.06) [20121115.025018] [32652] Source: fastest CPAN mirror ... Filter::Util::Call is up to date. (1.45) [20121115.025018] [32652] Source: fastest CPAN mirror ... Getopt::Long is up to date. (2.38) [20121115.025018] [32652] Source: fastest CPAN mirror ... Getopt::Param::Tiny is up to date. (0.5) [20121115.025018] [32652] Source: fastest CPAN mirror ... IO::Interactive::Tiny is up to date. (0.2) [20121115.025018] [32652] Source: fastest CPAN mirror ... Compress::Raw::Zlib is up to date. (2.058) [20121115.025018] [32652] Source: fastest CPAN mirror ... Authen::Libwrap is up to date. (0.22) [20121115.025018] [32652] Source: fastest CPAN mirror ... Net::FTPSSL is up to date. (0.22) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried Crypt-SSLeay-0.64. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... Net::SSLeay is up to date. (1.49) [20121115.025018] [32652] Source: fastest CPAN mirror ... IO::Compress::Gzip is up to date. (2.058) [20121115.025018] [32652] Source: fastest CPAN mirror ... IO::Scalar is up to date. (2.110) [20121115.025018] [32652] Source: fastest CPAN mirror ... IO::Socket::INET6 is up to date. (2.69) [20121115.025018] [32652] Source: fastest CPAN mirror ... IO::Socket::SSL is up to date. (1.77) [20121115.025018] [32652] Source: fastest CPAN mirror ... IO::Stty is up to date. (0.03) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried IO-Compress-2.058. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... Lchown is up to date. (1.01) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried Scalar-List-Utils-1.25. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... MD5 is up to date. (2.03) [20121115.025018] [32652] Source: fastest CPAN mirror ... Net::DNS is up to date. (0.68) [20121115.025018] [32652] Source: fastest CPAN mirror ... Net::OSCAR is up to date. (1.928) [20121115.025018] [32652] Source: fastest CPAN mirror ... Net::LibIDN is up to date. (0.12) [20121115.025018] [32652] Source: fastest CPAN mirror ... Pod::Perldoc is up to date. (3.17) [20121115.025018] [32652] Source: fastest CPAN mirror ... Socket6 is up to date. (0.23) [20121115.025018] [32652] Source: fastest CPAN mirror ... Storable is up to date. (2.39) [20121115.025018] [32652] Source: fastest CPAN mirror ... Sys::Syslog is up to date. (0.32) [20121115.025018] [32652] Source: fastest CPAN mirror ... TAP::Harness is up to date. (3.25) [20121115.025018] [32652] Source: fastest CPAN mirror ... Term::ReadKey is up to date. (2.30) [20121115.025018] [32652] Source: fastest CPAN mirror ... Term::ReadLine::Perl is up to date. (1.0303) [20121115.025018] [32652] Source: fastest CPAN mirror ... Time::HiRes is up to date. (1.9725) [20121115.025018] [32652] Source: fastest CPAN mirror ... Tree::MultiNode is up to date. (1.0.13) [20121115.025018] [32652] Source: fastest CPAN mirror ... Unix::PID is up to date. (0.23) [20121115.025018] [32652] Source: fastest CPAN mirror ... Unix::PID::Tiny is up to date. (0.9) [20121115.025018] [32652] Source: fastest CPAN mirror ... XML::LibXML::Common is up to date. (2.0012) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried XML-LibXML-2.0012. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... XML::Parser is up to date. (2.41) [20121115.025018] [32652] Source: fastest CPAN mirror ... XML::SAX is up to date. (0.99) [20121115.025018] [32652] Source: fastest CPAN mirror ... XML::Simple is up to date. (2.20) [20121115.025018] [32652] Source: fastest CPAN mirror ... lib::restrict is up to date. (0.0.5) [20121115.025018] [32652] Source: fastest CPAN mirror ... Crypt::Passwd::XS is up to date. (0.600) [20121115.025018] [32652] Source: fastest CPAN mirror ... DBD::mysql is up to date. (4.022) [20121115.025018] [32652] Source: fastest CPAN mirror ... Filesys::Statvfs is up to date. (0.82) [20121115.025018] [32652] Source: fastest CPAN mirror ... Crypt::GPG is up to date. (1.63) [20121115.025018] [32652] Source: fastest CPAN mirror ... Class::Accessor is up to date. (0.34) [20121115.025018] [32652] Source: fastest CPAN mirror ... Already tried Class-Accessor-0.34. Skipping. [20121115.025018] [32652] Source: fastest CPAN mirror ... File::MMagic::XS is up to date. (0.09006) [20121115.025018] [32652] Source: fastest CPAN mirror ... Email::Valid is up to date. (0.190) [20121115.025018] [32652] Source: fastest CPAN mirror ... ExtUtils::MakeMaker is up to date. (6.62) [20121115.025018] [32652] Source: fastest CPAN mirror ... Mail::SRS is up to date. (0.31) [20121115.025018] [32652] Source: fastest CPAN mirror ... Acme::Spork is up to date. (0.0.8) [20121115.025018] [32652] Source: fastest CPAN mirror ... Archive::Tar is up to date. (1.90) [20121115.025018] [32652] Source: fastest CPAN mirror ... Archive::Tar::Streamed is up to date. (0.03) [20121115.025018] [32652] Source: fastest CPAN mirror ... Archive::Zip is up to date. (1.30) [20121115.025018] [32652] Source: fastest CPAN mirror ... MIME::Lite is up to date. (3.029) [20121115.025018] [32652] Source: fastest CPAN mirror ... Business::OnlinePayment::AuthorizeNet is up to date. (3.22) [20121115.025018] [32652] Source: fastest CPAN mirror ... Business::UPS is up to date. (2.01) [20121115.025018] [32652] Source: fastest CPAN mirror ... --> Working on CGI [20121115.025018] [32652] Fetching http://cpan.uchicago.edu/pub/CPAN///authors/id/M/MA/MARKSTOS/CGI.pm-3.63.tar .gz ... Fetching http://cpan.uchicago.edu/pub/CPAN/authors/id/M/MA/MARKSTOS/CGI.pm-3.63.tar.g z (connected:1).......(request attempt 1/12)...Resolving cpan.uchicago.edu...(resolve attempt 1/65)......connecting to 128.135.10.29...@128.135.10.29......connected......receiving...53%...100%... ...request success......Done [20121115.025018] [32652] OK [20121115.025018] [32652] Unpacking CGI.pm-3.63.tar.gz [20121115.025018] [32652] CGI.pm-3.63/ [20121115.025018] [32652] CGI.pm-3.63/README [20121115.025018] [32652] CGI.pm-3.63/examples/ [20121115.025018] [32652] CGI.pm-3.63/examples/javascript.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/popup.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/make_links.pl [20121115.025018] [32652] CGI.pm-3.63/examples/caution.xbm [20121115.025018] [32652] CGI.pm-3.63/examples/frameset.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/clickable_image.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/save_state.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/internal_links.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/tryit.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/crash.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/monty.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/customize.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/nph-clock.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/index.html [20121115.025018] [32652] CGI.pm-3.63/examples/file_upload.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/diff_upload.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/wilogo.gif [20121115.025018] [32652] CGI.pm-3.63/examples/cookie.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/dna.small.gif [20121115.025018] [32652] CGI.pm-3.63/examples/multiple_forms.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/nph-multipart.cgi [20121115.025018] [32652] CGI.pm-3.63/examples/WORLD_WRITABLE/ [20121115.025018] [32652] CGI.pm-3.63/examples/WORLD_WRITABLE/18.157.1.253.sav [20121115.025018] [32652] CGI.pm-3.63/META.json [20121115.025018] [32652] CGI.pm-3.63/Makefile.PL [20121115.025018] [32652] CGI.pm-3.63/MANIFEST [20121115.025018] [32652] CGI.pm-3.63/META.yml [20121115.025018] [32652] CGI.pm-3.63/cgi_docs.html [20121115.025018] [32652] CGI.pm-3.63/t/ [20121115.025018] [32652] CGI.pm-3.63/t/start_end_start.t [20121115.025018] [32652] CGI.pm-3.63/t/Dump.t [20121115.025018] [32652] CGI.pm-3.63/t/push.t [20121115.025018] [32652] CGI.pm-3.63/t/cookie.t [20121115.025018] [32652] CGI.pm-3.63/t/checkbox_group.t [20121115.025018] [32652] CGI.pm-3.63/t/gen-tests/ [20121115.025018] [32652] CGI.pm-3.63/t/gen-tests/gen-start-end-tags.pl [20121115.025018] [32652] CGI.pm-3.63/t/fast.t [20121115.025018] [32652] CGI.pm-3.63/t/html.t [20121115.025018] [32652] CGI.pm-3.63/t/upload_post_text.txt [20121115.025018] [32652] CGI.pm-3.63/t/delete.t [20121115.025018] [32652] CGI.pm-3.63/t/can.t [20121115.025018] [32652] CGI.pm-3.63/t/charset.t [20121115.025018] [32652] CGI.pm-3.63/t/utf8.t [20121115.025018] [32652] CGI.pm-3.63/t/unescapeHTML.t [20121115.025018] [32652] CGI.pm-3.63/t/user_agent.t [20121115.025018] [32652] CGI.pm-3.63/t/util-58.t [20121115.025018] [32652] CGI.pm-3.63/t/headers.t [20121115.025018] [32652] CGI.pm-3.63/t/upload.t [20121115.025018] [32652] CGI.pm-3.63/t/param_fetch.t [20121115.025018] [32652] CGI.pm-3.63/t/end_form.t [20121115.025018] [32652] CGI.pm-3.63/t/query_string.t [20121115.025018] [32652] CGI.pm-3.63/t/start_end_end.t [20121115.025018] [32652] CGI.pm-3.63/t/no_tabindex.t [20121115.025018] [32652] CGI.pm-3.63/t/util.t [20121115.025018] [32652] CGI.pm-3.63/t/request.t [20121115.025018] [32652] CGI.pm-3.63/t/rt-52469.t [20121115.025018] [32652] CGI.pm-3.63/t/hidden.t [20121115.025018] [32652] CGI.pm-3.63/t/multipart_init.t [20121115.025018] [32652] CGI.pm-3.63/t/uploadInfo.t [20121115.025018] [32652] CGI.pm-3.63/t/form.t [20121115.025018] [32652] CGI.pm-3.63/t/url.t [20121115.025018] [32652] CGI.pm-3.63/t/autoescape.t [20121115.025018] [32652] CGI.pm-3.63/t/init_test.txt [20121115.025018] [32652] CGI.pm-3.63/t/http.t [20121115.025018] [32652] CGI.pm-3.63/t/save_read_roundtrip.t [20121115.025018] [32652] CGI.pm-3.63/t/init.t [20121115.025018] [32652] CGI.pm-3.63/t/apache.t [20121115.025018] [32652] CGI.pm-3.63/t/switch.t [20121115.025018] [32652] CGI.pm-3.63/t/pretty.t [20121115.025018] [32652] CGI.pm-3.63/t/start_end_asterisk.t [20121115.025018] [32652] CGI.pm-3.63/t/tmpdir.t [20121115.025018] [32652] CGI.pm-3.63/t/carp.t [20121115.025018] [32652] CGI.pm-3.63/t/popup_menu.t [20121115.025018] [32652] CGI.pm-3.63/t/function.t [20121115.025018] [32652] CGI.pm-3.63/Changes [20121115.025018] [32652] CGI.pm-3.63/lib/ [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/ [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/Cookie.pm [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/Switch.pm [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/Fast.pm [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/Util.pm [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/Carp.pm [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/Pretty.pm [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/Push.pm [20121115.025018] [32652] CGI.pm-3.63/lib/CGI/Apache.pm [20121115.025018] [32652] CGI.pm-3.63/lib/CGI.pm [20121115.025018] [32652] Entering CGI.pm-3.63 [20121115.025018] [32652] Checking configure dependencies from META.yml [20121115.025018] [32652] Configuring CGI.pm-3.63 ... Running Makefile.PL [20121115.025018] [32652] info [cpanm] /root/.cpanel/datastore/Cpanel::Sys::Compiler::test_compile_cc [20121115.025018] [32652] [20121115.025018] [32652] perl-64bit-hack: enabled [20121115.025018] [32652] Checking if your kit is complete... [20121115.025018] [32652] Looks good [20121115.025019] [32652] Writing Makefile for CGI [20121115.025019] [32652] Writing MYMETA.yml and MYMETA.json [20121115.025019] [32652] OK [20121115.025019] [32652] Checking dependencies from MYMETA.yml ... [20121115.025019] [32652] Finding PREREQ from Makefile ... [20121115.025019] [32652] Checking if you have Test::More 0.98 ... Yes (0.98) [20121115.025019] [32652] Checking if you have File::Spec 0.82 ... Yes (3.33) [20121115.025019] [32652] Checking if you have FCGI 0.67 ... Yes (0.67) [20121115.025019] [32652] Checking if you have ExtUtils::MakeMaker 0 ... Yes (6.62) [20121115.025019] [32652] Checking if you have Test::More 0.98 ... Yes (0.98) [20121115.025019] [32652] Checking if you have File::Spec 0.82 ... Yes (3.33) [20121115.025019] [32652] Checking if you have FCGI 0.67 ... Yes (0.67) [20121115.025019] [32652] Building CGI.pm-3.63 ... cp lib/CGI/Switch.pm blib/lib/CGI/Switch.pm [20121115.025019] [32652] cp lib/CGI/Cookie.pm blib/lib/CGI/Cookie.pm [20121115.025019] [32652] cp lib/CGI/Pretty.pm blib/lib/CGI/Pretty.pm [20121115.025019] [32652] cp lib/CGI.pm blib/lib/CGI.pm [20121115.025019] [32652] cp lib/CGI/Carp.pm blib/lib/CGI/Carp.pm [20121115.025019] [32652] cp lib/CGI/Apache.pm blib/lib/CGI/Apache.pm [20121115.025019] [32652] cp lib/CGI/Util.pm blib/lib/CGI/Util.pm [20121115.025019] [32652] cp lib/CGI/Push.pm blib/lib/CGI/Push.pm [20121115.025019] [32652] cp lib/CGI/Fast.pm blib/lib/CGI/Fast.pm [20121115.025019] [32652] Manifying blib/man3/CGI::Switch.3 [20121115.025019] [32652] Manifying blib/man3/CGI::Cookie.3 [20121115.025019] [32652] Manifying blib/man3/CGI::Pretty.3 [20121115.025019] [32652] Manifying blib/man3/CGI.3 [20121115.025019] [32652] Manifying blib/man3/CGI::Carp.3 [20121115.025019] [32652] Manifying blib/man3/CGI::Apache.3 [20121115.025019] [32652] Manifying blib/man3/CGI::Util.3 [20121115.025019] [32652] Manifying blib/man3/CGI::Push.3 [20121115.025019] [32652] Manifying blib/man3/CGI::Fast.3 [20121115.025020] [32652] Manifying blib/man3/CGI::Switch.3 [20121115.025020] [32652] Manifying blib/man3/CGI::Cookie.3 [20121115.025020] [32652] Manifying blib/man3/CGI::Pretty.3 [20121115.025020] [32652] Manifying blib/man3/CGI.3 [20121115.025020] [32652] Manifying blib/man3/CGI::Carp.3 [20121115.025020] [32652] Manifying blib/man3/CGI::Apache.3 [20121115.025020] [32652] Manifying blib/man3/CGI::Util.3 [20121115.025020] [32652] Manifying blib/man3/CGI::Push.3 [20121115.025020] [32652] Manifying blib/man3/CGI::Fast.3 [20121115.025020] [32652] Installing /usr/lib/perl5/5.8.8/CGI.pm [20121115.025020] [32652] Installing /usr/share/man/man3/CGI.3 [20121115.025020] [32652] Appending installation info to /usr/lib/perl5/5.8.8/i686-linux/perllocal.pod [20121115.025020] [32652] OK [20121115.025020] [32652] Successfully installed CGI.pm-3.63 (upgraded from 3.62) [20121115.025020] [32652] Source: fastest CPAN mirror ... Class::Std::Utils is up to date. (0.0.3) [20121115.025020] [32652] Source: fastest CPAN mirror ... Compress::Bzip2 is up to date. (2.09) [20121115.025020] [32652] Source: fastest CPAN mirror ... Already tried IO-Compress-2.058. Skipping. [20121115.025020] [32652] Source: fastest CPAN mirror ... DBIx::MyParsePP is up to date. (0.50) [20121115.025020] [32652] Source: fastest CPAN mirror ... DBD::SQLite2 is up to date. (0.33) [20121115.025020] [32652] Source: fastest CPAN mirror ... Date::Parse is up to date. (2.30) [20121115.025020] [32652] Source: fastest CPAN mirror ... File::Tail is up to date. (0.99.3) [20121115.025020] [32652] Source: fastest CPAN mirror ... GD::Graph is up to date. (1.44) [20121115.025021] [32652] Source: fastest CPAN mirror ... GD::Text::Align is up to date. (1.18) [20121115.025021] [32652] Source: fastest CPAN mirror ... Memoize is up to date. (1.03) [20121115.025021] [32652] Source: fastest CPAN mirror ... Geo::IPfree is up to date. (1.122880) [20121115.025021] [32652] Source: fastest CPAN mirror ... HTTP::Daemon::App is up to date. (0.0.9) [20121115.025021] [32652] Source: fastest CPAN mirror ... IO::Socket::ByteCounter is up to date. (0.0.2) [20121115.025021] [32652] Source: fastest CPAN mirror ... Image::Size is up to date. (3.232) [20121115.025021] [32652] Source: fastest CPAN mirror ... Mail::DomainKeys is up to date. (1.0) [20121115.025021] [32652] Source: fastest CPAN mirror ... Error is up to date. (0.17018) [20121115.025021] [32652] Source: httpupdate.cpanel.net ... NetAddr::IP is up to date. (4.048) [20121115.025021] [32652] Source: fastest CPAN mirror ... Net::DNS::Resolver::Programmable is up to date. (v0.003) [20121115.025021] [32652] Source: httpupdate.cpanel.net ... Mail::SPF is up to date. (2.007002) [20121115.025021] [32652] Source: fastest CPAN mirror ... Mail::SPF::Query is up to date. (1.999.1) [20121115.025021] [32652] Source: fastest CPAN mirror ... Mail::DKIM is up to date. (0.39) [20121115.025021] [32652] Source: fastest CPAN mirror ... IP::Country is up to date. (2.27) [20121115.025021] [32652] Source: fastest CPAN mirror ... Graph::Easy is up to date. (0.72) [20121115.025021] [32652] Source: fastest CPAN mirror ... Graph::Flowchart is up to date. (0.11) [20121115.025021] [32652] Source: httpupdate.cpanel.net ... Mail::SpamAssassin is up to date. (3.003001) [20121115.025021] [32652] Source: fastest CPAN mirror ... Already tried URI-1.60. Skipping. [20121115.025021] [32652] Source: fastest CPAN mirror ... File::Find::Rule::Filesys::Virtual is up to date. (1.22) [20121115.025021] [32652] Source: fastest CPAN mirror ... File::Slurp is up to date. (9999.19) [20121115.025021] [32652] Source: fastest CPAN mirror ... Net::DAV::Server is up to date. (1.304) [20121115.025021] [32652] Source: fastest CPAN mirror ... Net::Daemon is up to date. (0.48) [20121115.025021] [32652] Source: fastest CPAN mirror ... Net::Daemon::SSL is up to date. (1.0) [20121115.025021] [32652] Source: fastest CPAN mirror ... Net::LDAP is up to date. (0.49) [20121115.025021] [32652] Source: fastest CPAN mirror ... Already tried perl-ldap-0.49. Skipping. [20121115.025021] [32652] Source: fastest CPAN mirror ... Net::LDAP::Server is up to date. (0.43) [20121115.025021] [32652] Source: fastest CPAN mirror ... Net::IP::Match::Regexp is up to date. (1.01) [20121115.025021] [32652] Source: fastest CPAN mirror ... OLE::Storage_Lite is up to date. (0.19) [20121115.025021] [32652] Source: fastest CPAN mirror ... Parse::RecDescent is up to date. (1.967009) [20121115.025021] [32652] Source: fastest CPAN mirror ... Quota is up to date. (1.6.7) [20121115.025021] [32652] Source: fastest CPAN mirror ... SVG::TT::Graph is up to date. (0.22) [20121115.025021] [32652] Source: fastest CPAN mirror ... Safe::Hole is up to date. (0.13) [20121115.025021] [32652] Source: fastest CPAN mirror ... Text::CSV is up to date. (1.21) [20121115.025021] [32652] Source: fastest CPAN mirror ... Spreadsheet::ParseExcel is up to date. (0.59) [20121115.025021] [32652] Source: fastest CPAN mirror ... Spreadsheet::WriteExcel is up to date. (2.37) [20121115.025021] [32652] Source: fastest CPAN mirror ... String::CRC32 is up to date. (1.4) [20121115.025021] [32652] Source: fastest CPAN mirror ... SQL::Statement is up to date. (1.401) [20121115.025021] [32652] Source: fastest CPAN mirror ... Set::Crontab is up to date. (1.03) [20121115.025021] [32652] Source: fastest CPAN mirror ... Tie::IxHash is up to date. (1.22) [20121115.025021] [32652] Source: fastest CPAN mirror ... Tie::ShadowHash is up to date. (1.00) [20121115.025021] [32652] Source: fastest CPAN mirror ... Tie::DBI is up to date. (1.05) [20121115.025021] [32652] Source: fastest CPAN mirror ... Already tried URI-1.60. Skipping. [20121115.025021] [32652] Source: fastest CPAN mirror ... Bundle::Interchange is up to date. (1.05) [20121115.025021] [32652] Source: fastest CPAN mirror ... Devel::PPPort is up to date. (3.20) [20121115.025021] [32652] Source: fastest CPAN mirror ... Linux::Inotify2 is up to date. (1.22) [20121115.025021] [32652] 1 distribution installed [20121115.025023] [32652] Using fast module check.....checked 160 modules in 2 second(s) [20121115.025023] [32652] Tested 160, 160 ok, 0 failed. [20121115.025023] [32652] Using fast module check.....checked 5 modules in 0 second(s) [20121115.025023] [32652] Cpanel::FastMath: [INSTALLED=1] [VERSION=0.3] [20121115.025023] [32652] Cpanel::POSIX::Tiny: [INSTALLED=1] [VERSION=1.1] [20121115.025023] [32652] Cpanel::Cleanup: [INSTALLED=1] [VERSION=0.4] [20121115.025023] [32652] Cpanel::Class: [INSTALLED=1] [VERSION=1.0.5] [20121115.025023] [32652] Cpanel::TaskQueue: [INSTALLED=1] [VERSION=0.307] [20121115.025024] [32652] The following modules are now being installed/updated/verified: Curses::UI,XML::LibXML::Common,XML::LibXML,XML::SAX,XML::SAX::Expat [20121115.025024] [32652] Checking C compiler....C compiler (/usr/bin/cc) OK (cached Thu Nov 15 02:50:16 2012) [20121115.025024] [32652] Tuned C compiler not available because it is not enabled....Done [20121115.025024] [32652] CPAN fallback is disabled since /var/cpanel/conserve_memory exists, and cpanm is available. [20121115.025024] [32652] Method: Using Perl Expect, Installer: cpanm [20121115.025024] [32652] You have make /usr/bin/make [20121115.025024] [32652] Falling back to HTTP::Tiny 0.009 [20121115.025024] [32652] You have /bin/tar: tar (GNU tar) 1.15.1 [20121115.025024] [32652] You have /usr/bin/unzip [20121115.025024] [32652] You have Cpanel::HttpRequest 2.1 [20121115.025024] [32652] Testing connection speed...(using fast method)...Done [20121115.025024] [32652] Ping:0 (ticks) Testing connection speed to cpan.uchicago.edu using pureperl...(cached .. 61900.00 bytes/s)...Done [20121115.025024] [32652] Ping:1 (ticks) Testing connection speed to cpan.mirrors.tds.net using pureperl...(cached .. 59100.00 bytes/s)...Done [20121115.025024] [32652] Ping:1 (ticks) Testing connection speed to mirrors.servercentral.net using pureperl...(cached .. 56100.00 bytes/s)...Done [20121115.025024] [32652] Ping:2 (ticks) Testing connection speed to cpan.mirror.rafal.ca using pureperl...(cached .. 19166.67 bytes/s)...Done [20121115.025024] [32652] Ping:1 (ticks) Testing connection speed to cpan.netnitco.net using pureperl...(cached .. 3535.29 bytes/s)...Done [20121115.025024] [32652] Five usable mirrors located [20121115.025024] [32652] Ping:0 (ticks) Testing connection speed to 67.159.2.2 using pureperl...(using cached speed)...Done [20121115.025024] [32652] Ping:0 (ticks) Testing connection speed to 74.200.212.130 using pureperl...(using cached speed)...Done [20121115.025024] [32652] Ping:0 (ticks) Testing connection speed to 216.104.43.138 using pureperl...(using cached speed)...Done [20121115.025024] [32652] Three usable fallback mirrors located [20121115.025024] [32652] Fetching http://cpan.uchicago.edu/pub/CPAN/index.html (connected:0).......(request attempt 1/12)...Resolving cpan.uchicago.edu...(resolve attempt 1/65)......connecting to 128.135.10.29...@128.135.10.29......connected......receiving...100%......req uest success......Done [20121115.025024] [32652] Mirror Check passed for cpan.uchicago.edu (/index.html) [20121115.025024] [32652] Searching on cpanmetadb ... [20121115.025024] [32652] Fetching http://cpanmetadb.cpanel.net/v1.0/package/Curses::UI,XML::LibXML::Common,XML ::LibXML,XML::SAX,XML::SAX::Expat?cpanel_version=11.34.0.9&cpanel_tier=relea se (connected:0).......(request attempt 1/12)...Using dns cache file /root/.HttpRequest/cpanmetadb.cpanel.net......searching for mirrors (mirror search attempt 1/3)......5 usable mirrors located. (less then expected)......mirror search success......connecting to 208.74.125.50...@208.74.125.50......connected......receiving...100%......req uest success......Done [20121115.025024] [32652] Source: fastest CPAN mirror ... Curses::UI is up to date. (0.9609) [20121115.025024] [32652] Source: fastest CPAN mirror ... XML::LibXML::Common is up to date. (2.0012) [20121115.025024] [32652] Source: fastest CPAN mirror ... Already tried XML-LibXML-2.0012. Skipping. [20121115.025024] [32652] Source: fastest CPAN mirror ... XML::SAX is up to date. (0.99) [20121115.025024] [32652] Source: fastest CPAN mirror ... XML::SAX::Expat is up to date. (0.40) [20121115.025024] [32652] Using fast module check.....checked 5 modules in 0 second(s) [20121115.025024] [32652] Tested 5, 5 ok, 0 failed. [20121115.025025] Processing: Ensuring license stats are up to date [20121115.025025] - Processing command `/usr/local/cpanel/bin/build_license_stats` [20121115.025025] [1230] Syntax OK [20121115.025025] Processing: Ensuring locale databases are up to date [20121115.025025] - Processing command `/usr/local/cpanel/bin/build_locale_databases --quiet` [20121115.025028] Processing: Migrating feature lists to current version (if needed) [20121115.025028] - Processing command `/usr/local/cpanel/bin/migrate_all_feature_lists_to_current` [20121115.025028] [1250] Migrated 0 feature list(s) [20121115.025028] Processing: Enforcing maildir=1 to assure we are not in mbox mode. [20121115.025028] - Processing command `/usr/local/cpanel/bin/force_maildir` [20121115.025028] Processing: Running futex check/fix [20121115.025028] - Processing command `/usr/local/cpanel/scripts/futexfix` [20121115.025028] Processing: Checking for main IP changes [20121115.025028] - Processing command `/usr/local/cpanel/scripts/mainipcheck` [20121115.025029] Processing: Sending usage data (if enabled) [20121115.025029] - Processing command `/usr/local/cpanel/scripts/send_anonymous_usage_data` [20121115.025029] [1260] Anonymous usage data accepted in 0s. [20121115.025029] Processing: Validating cPanel system users [20121115.025029] - Processing command `/usr/local/cpanel/scripts/checkusers` [20121115.025029] [1261] Homedir: /usr/local/cpanel [20121115.025029] Processing: Making sure /var/cpanel/sysinfo.conf is present [20121115.025029] - Processing command `/usr/local/cpanel/scripts/gensysinfo` [20121115.025029] Processing: Checking and repairing nameserver config [20121115.025029] - Processing command `/usr/local/cpanel/scripts/fixrndc` [20121115.025030] Processing: Setting up IP aliases startup [20121115.025030] - Processing command `/usr/local/cpanel/scripts/cpservice ipaliases enable 35` [20121115.025030] [1275] enable was successful [20121115.025030] Processing: Creating symlinks for common binaries [20121115.025030] - Processing command `/usr/local/cpanel/scripts/fixbinpath` [20121115.025030] Processing: Running env auto repair [20121115.025030] - Processing command `/usr/local/cpanel/scripts/vzzo-fixer` [20121115.025030] - Processing command `/usr/local/cpanel/scripts/quota_auto_fix` [20121115.025030] [1283] quota test: check passed [20121115.025030] - Processing command `/scripts/autorepair autorepair` [20121115.025030] [1286] Requesting script ... Done [20121115.025030] [1286] Auto Repair is running...Running Auto Repair routines [20121115.025030] [1286] Finished running Auto Repair routines [20121115.025030] [1286] ...Auto Repair is done. [20121115.025030] - Processing command `/usr/local/cpanel/scripts/cleanupcheck` [20121115.025030] - Processing command `/usr/local/cpanel/scripts/purge_old_config_caches` [20121115.025030] - Processing command `/usr/local/cpanel/scripts/cleansessions` [20121115.025031] - Processing command `/usr/local/cpanel/scripts/checkbashshell` [20121115.025031] Processing: Updating DNS Server [20121115.025031] - Processing command `/usr/local/cpanel/scripts/nameserverup` [20121115.025031] [1294] BIND is the current name server...Setting up rndc configuration... [20121115.025031] Setting up cronjobs [20121115.025031] Setting Up update_db_cache Crontab [20121115.025031] Setting Up update_maiman_cache Crontab [20121115.025031] Setting Up optimizefs Crontab [20121115.025031] Setting Up dcpumon Crontab [20121115.025031] - Processing command `/usr/local/cpanel/scripts/dnsqueuecron` [20121115.025031] Processing: Updating services and databases [20121115.025031] - Processing command `/usr/local/cpanel/scripts/findphpversion` [20121115.025031] [1310] PHP version file is up to date [20121115.025031] - Processing command `/usr/local/cpanel/scripts/checkerrorlogsafe` [20121115.025031] - Processing command `/usr/local/cpanel/scripts/listcheck` [20121115.025031] - Processing command `/usr/local/cpanel/scripts/checkfpkey` [20121115.025031] - Processing command `/usr/local/cpanel/scripts/fixspamassassinfailedupdate` [20121115.025040] - Processing command `/usr/local/cpanel/scripts/ftpquotacheck` [20121115.025040] [1322] Ftp Quota Check v1.8 [20121115.025040] [1322] ioprio_set: Operation not permitted [20121115.025040] [1322] Updating mitanks: ...Done [20121115.025040] [1322] Updating plymouth: ...Done [20121115.025040] - Processing command `/usr/local/cpanel/scripts/updateuserdomains` [20121115.025040] - Processing command `/usr/local/cpanel/scripts/build_maxemails_config` [20121115.025040] - Processing command `/usr/local/cpanel/scripts/updateuserdatacache --force` [20121115.025041] Processing: Running various cleanup scripts [20121115.025041] - Processing command `/usr/local/cpanel/scripts/resetmailmanurls` [20121115.025041] Processing: Checking MySQL to ensure we can connect [20121115.025041] - Processing command `/usr/local/cpanel/scripts/mysqlconnectioncheck` [20121115.025041] Processing: Checking PostgreSQL to ensure we can connect [20121115.025041] - Processing command `/usr/local/cpanel/bin/postgrescheck --check-auth --reset-pass-on-fail` [20121115.025041] [1330] 0 [20121115.025041] - Processing command `/usr/local/cpanel/scripts/disable_unused_xinetd` [20121115.025041] Repairing Mailman Permissions [20121115.025041] - Processing command `./check_perms -f --noarchives` [20121115.025042] [1333] Warning: Private archive directory is other-executable (o+x). [20121115.025042] [1333] This could allow other users on your system to read private archives. [20121115.025042] [1333] If you're on a shared multiuser system, you should consult the [20121115.025042] [1333] installation manual on how to fix this. [20121115.025042] [1333] No problems found [20121115.025042] Processing: Running sanity checks and notifications [20121115.025042] - Processing command `/usr/local/cpanel/scripts/chkpaths` [20121115.025042] - Processing command `/usr/local/cpanel/scripts/ndbmcheck` [20121115.025042] - Processing command `/usr/local/cpanel/scripts/hackcheck` [20121115.025042] - Processing command `/usr/local/cpanel/scripts/oopscheck` [20121115.025042] - Processing command `/usr/local/cpanel/scripts/fixetchosts` [20121115.025042] - Processing command `/usr/local/cpanel/scripts/ipcheck` [20121115.025042] - Processing command `/usr/local/cpanel/scripts/quotacheck` [20121115.025043] [1347] ioprio_set: Operation not permitted [20121115.025043] - Processing command `/usr/local/cpanel/scripts/email_archive_maintenance` [20121115.025043] - Processing command `/usr/local/cpanel/scripts/smartcheck` [20121115.025043] - Processing command `/usr/local/cpanel/scripts/compilerscheck` [20121115.025043] - Processing command `/usr/local/cpanel/scripts/updatephpconf` [20121115.025043] - Processing command `/usr/local/cpanel/scripts/checksuexecpatch` [20121115.025043] Processing: Updating email whitelists [20121115.025043] - Processing command `/usr/local/cpanel/bin/rim_bis_update --quiet` [20121115.025043] Processing: Purging old comet files [20121115.025043] - Processing command `/usr/local/cpanel/bin/purge_dead_comet_files --quiet` [20121115.025043] Processing: Updating ftp [20121115.025043] - Processing command `/usr/local/cpanel/scripts/ftpup` [20121115.025044] [1359] Waiting for proftpd to shutdown ... not running. [20121115.025044] [1359] pure-ftpd-1.0.36-1 is already installed. Use --force to reinstall. [20121115.025044] Processing: Updating bandmin [20121115.025044] - Processing command `/usr/local/cpanel/scripts/bandminup` [20121115.025044] [1362] bandmin-1.6.1-5 is already installed. Use --force to reinstall. [20121115.025044] Processing: Updating IMAP Server [20121115.025044] - Processing command `/usr/local/cpanel/scripts/imapup` [20121115.025044] [1364] dovecot-1.2.17-0cp is already installed. Use --force to reinstall. [20121115.025044] Processing: Updating SpamAssassin rules [20121115.025044] - Processing command `/usr/local/cpanel/scripts/sa-update_wrapper` [20121115.025044] [1366] Running sa-update (/usr/bin/sa-update).....Nov 15 02:50:44.862 [1367] dbg: logger: adding facilities: all [20121115.025044] [1366] Nov 15 02:50:44.862 [1367] dbg: logger: logging level is DBG [20121115.025044] [1366] Nov 15 02:50:44.862 [1367] dbg: generic: SpamAssassin version 3.3.1 [20121115.025044] [1366] Nov 15 02:50:44.862 [1367] dbg: generic: Perl 5.008008, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin [20121115.025044] [1366] Nov 15 02:50:44.862 [1367] dbg: config: timing enabled [20121115.025044] [1366] Nov 15 02:50:44.863 [1367] dbg: config: score set 0 chosen. [20121115.025044] [1366] Nov 15 02:50:44.868 [1367] dbg: dns: is Net::DNS::Resolver available? yes [20121115.025044] [1366] Nov 15 02:50:44.868 [1367] dbg: dns: Net::DNS version: 0.68 [20121115.025044] [1366] Nov 15 02:50:44.868 [1367] dbg: generic: sa-update version svn917659 [20121115.025044] [1366] Nov 15 02:50:44.868 [1367] dbg: generic: using update directory: /var/lib/spamassassin/3.003001 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: perl platform: 5.008008 linux [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: Digest::SHA1, version 2.13 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: HTML::Parser, version 3.69 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: Net::DNS, version 0.68 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: NetAddr::IP, version 4.048 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: Time::HiRes, version 1.9725 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: Archive::Tar, version 1.90 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: IO::Zlib, version 1.10 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: Digest::SHA1, version 2.13 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: MIME::Base64, version 3.13 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: DB_File, version 1.827 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: Net::SMTP, version 2.31 [20121115.025044] [1366] Nov 15 02:50:44.988 [1367] dbg: diag: [...] module installed: Mail::SPF, version 2.007002 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: IP::Country::Fast, version 604.001 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: Razor2::Client::Agent, version 2.84 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: Net::Ident, version 1.20 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: IO::Socket::INET6, version 2.69 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: IO::Socket::SSL, version 1.77 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: Compress::Zlib, version 2.058 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: Mail::DKIM, version 0.39 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: DBI, version 1.622 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: Getopt::Long, version 2.38 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: LWP::UserAgent, version 6.04 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: HTTP::Date, version 6.02 [20121115.025044] [1366] Nov 15 02:50:44.989 [1367] dbg: diag: [...] module installed: Encode::Detect, version 1.01 [20121115.025044] [1366] Nov 15 02:50:44.990 [1367] dbg: gpg: Searching for 'gpg' [20121115.025044] [1366] Nov 15 02:50:44.990 [1367] dbg: util: current PATH is: /usr/bin:/bin:/sbin:/usr/sbin:/usr/bin:/bin:/usr/local/bin:/sbin:/usr/sbin:/ usr/bin:/bin:/usr/local/bin [20121115.025044] [1366] Nov 15 02:50:44.990 [1367] dbg: util: executable for gpg was found at /usr/bin/gpg [20121115.025044] [1366] Nov 15 02:50:44.990 [1367] dbg: gpg: found /usr/bin/gpg [20121115.025045] [1366] Nov 15 02:50:45.002 [1367] dbg: gpg: release trusted key id list: 5E541DC959CB8BAC7C78DFDC4056A61A5244EC45 26C900A46DD40CD5AD24F6D7DEE01987265FA05B 0C2B1D7175B852C64B3CDC716C55397824F434CE [20121115.025045] [1366] Nov 15 02:50:45.069 [1367] dbg: channel: attempting channel updates.spamassassin.org [20121115.025045] [1366] Nov 15 02:50:45.069 [1367] dbg: channel: update directory /var/lib/spamassassin/3.003001/updates_spamassassin_org [20121115.025045] [1366] Nov 15 02:50:45.069 [1367] dbg: channel: channel cf file /var/lib/spamassassin/3.003001/updates_spamassassin_org.cf [20121115.025045] [1366] Nov 15 02:50:45.069 [1367] dbg: channel: channel pre file /var/lib/spamassassin/3.003001/updates_spamassassin_org.pre [20121115.025045] [1366] Nov 15 02:50:45.069 [1367] dbg: channel: metadata version = 1408637 [20121115.025045] [1366] Nov 15 02:50:45.074 [1367] dbg: dns: 1.3.3.updates.spamassassin.org => 1409116, parsed as 1409116 [20121115.025045] [1366] Nov 15 02:50:45.074 [1367] dbg: channel: preparing temp directory for new channel [20121115.025045] [1366] Nov 15 02:50:45.075 [1367] dbg: generic: update tmp directory /tmp/.spamassassin1367vf9soVtmp [20121115.025045] [1366] Nov 15 02:50:45.075 [1367] dbg: generic: lint checking site pre files once before attempting channel updates [20121115.025045] [1366] Nov 15 02:50:45.075 [1367] dbg: generic: SpamAssassin version 3.3.1 [20121115.025045] [1366] Nov 15 02:50:45.075 [1367] dbg: generic: Perl 5.008008, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin [20121115.025045] [1366] Nov 15 02:50:45.075 [1367] dbg: config: timing enabled [20121115.025045] [1366] Nov 15 02:50:45.075 [1367] dbg: config: score set 0 chosen. [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: dns: is Net::DNS::Resolver available? yes [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: dns: Net::DNS version: 0.68 [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: ignore: using a test message to lint rules [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: config: using "/etc/mail/spamassassin" for site rules pre files [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: config: read file /etc/mail/spamassassin/init.pre [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: config: read file /etc/mail/spamassassin/v310.pre [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: config: read file /etc/mail/spamassassin/v312.pre [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: config: read file /etc/mail/spamassassin/v320.pre [20121115.025045] [1366] Nov 15 02:50:45.077 [1367] dbg: config: read file /etc/mail/spamassassin/v330.pre [20121115.025045] [1366] Nov 15 02:50:45.078 [1367] dbg: config: using "/tmp/.spamassassin1367vf9soVtmp/doesnotexist" for sys rules pre files [20121115.025045] [1366] Nov 15 02:50:45.078 [1367] dbg: config: using "/tmp/.spamassassin1367vf9soVtmp/doesnotexist" for default rules dir [20121115.025045] [1366] Nov 15 02:50:45.078 [1367] dbg: config: using "/tmp/.spamassassin1367vf9soVtmp/doesnotexist/doesnotexist" for user prefs file [20121115.025045] [1366] Nov 15 02:50:45.078 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC [20121115.025045] [1366] Nov 15 02:50:45.082 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC [20121115.025045] [1366] Nov 15 02:50:45.084 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC [20121115.025045] [1366] Nov 15 02:50:45.086 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::DCC from @INC [20121115.025045] [1366] Nov 15 02:50:45.090 [1367] dbg: dcc: local tests only, disabling DCC [20121115.025045] [1366] Nov 15 02:50:45.091 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC [20121115.025045] [1366] Nov 15 02:50:45.092 [1367] dbg: pyzor: local tests only, disabling Pyzor [20121115.025045] [1366] Nov 15 02:50:45.092 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC [20121115.025045] [1366] Nov 15 02:50:45.094 [1367] dbg: razor2: local tests only, skipping Razor [20121115.025045] [1366] Nov 15 02:50:45.094 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC [20121115.025045] [1366] Nov 15 02:50:45.097 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC [20121115.025045] [1366] Nov 15 02:50:45.098 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC [20121115.025045] [1366] Nov 15 02:50:45.099 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC [20121115.025045] [1366] Nov 15 02:50:45.100 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC [20121115.025045] [1366] Nov 15 02:50:45.101 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC [20121115.025045] [1366] Nov 15 02:50:45.106 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC [20121115.025045] [1366] Nov 15 02:50:45.107 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC [20121115.025045] [1366] Nov 15 02:50:45.108 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Shortcircuit from @INC [20121115.025045] [1366] Nov 15 02:50:45.109 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC [20121115.025045] [1366] Nov 15 02:50:45.115 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC [20121115.025045] [1366] Nov 15 02:50:45.116 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC [20121115.025045] [1366] Nov 15 02:50:45.117 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC [20121115.025045] [1366] Nov 15 02:50:45.119 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC [20121115.025045] [1366] Nov 15 02:50:45.123 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC [20121115.025045] [1366] Nov 15 02:50:45.125 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC [20121115.025045] [1366] Nov 15 02:50:45.127 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC [20121115.025045] [1366] Nov 15 02:50:45.128 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC [20121115.025045] [1366] Nov 15 02:50:45.129 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC [20121115.025045] [1366] Nov 15 02:50:45.130 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Rule2XSBody from @INC [20121115.025045] [1366] Nov 15 02:50:45.132 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC [20121115.025045] [1366] Nov 15 02:50:45.133 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::PDFInfo from @INC [20121115.025045] [1366] Nov 15 02:50:45.136 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: config: finish parsing [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0xa4ece80) implements 'finish_parsing_end', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Rule2XSBody=HASH(0xa6c1934) implements 'finish_parsing_end', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0xa72bf0c) implements 'finish_parsing_end', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: replacetags: replacing tags [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: replacetags: done replacing tags [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: zoom: loading compiled ruleset from /var/lib/spamassassin/compiled/5.008/3.003001 [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: FreeMail: no freemail_domains entries defined, disabling plugin [20121115.025045] [1366] Nov 15 02:50:45.139 [1367] dbg: config: score set 0 chosen. [20121115.025045] [1366] Nov 15 02:50:45.140 [1367] dbg: message: main message type: text/plain [20121115.025045] [1366] Nov 15 02:50:45.140 [1367] dbg: message: ---- MIME PARSER START ---- [20121115.025045] [1366] Nov 15 02:50:45.140 [1367] dbg: message: parsing normal part [20121115.025045] [1366] Nov 15 02:50:45.140 [1367] dbg: message: ---- MIME PARSER END ---- [20121115.025045] [1366] Nov 15 02:50:45.140 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0xa5e6e40) implements 'check_start', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.140 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Rule2XSBody=HASH(0xa6c1934) implements 'check_start', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.141 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0xa5253d4) implements 'check_main', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.141 [1367] dbg: config: trusted_networks are not configured; it is recommended that you configure trusted_networks manually [20121115.025045] [1366] Nov 15 02:50:45.141 [1367] dbg: metadata: X-Spam-Relays-Trusted: [20121115.025045] [1366] Nov 15 02:50:45.141 [1367] dbg: metadata: X-Spam-Relays-Untrusted: [20121115.025045] [1366] Nov 15 02:50:45.141 [1367] dbg: metadata: X-Spam-Relays-Internal: [20121115.025045] [1366] Nov 15 02:50:45.141 [1367] dbg: metadata: X-Spam-Relays-External: [20121115.025045] [1366] Nov 15 02:50:45.141 [1367] dbg: message: no encoding detected [20121115.025045] [1366] Nov 15 02:50:45.142 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa3ebcb0) implements 'parsed_metadata', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.142 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Shortcircuit=HASH(0xa55d98c) implements 'parsed_metadata', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.142 [1367] dbg: dns: is_dns_available() last checked 1352969445.1 seconds ago; re-checking [20121115.025045] [1366] Nov 15 02:50:45.142 [1367] dbg: dns: is DNS available? 0 [20121115.025045] [1366] Nov 15 02:50:45.142 [1367] dbg: rules: local tests only, ignoring RBL eval [20121115.025045] [1366] Nov 15 02:50:45.143 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Shortcircuit=HASH(0xa55d98c) implements 'have_shortcircuited', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.143 [1367] dbg: dns: harvest_dnsbl_queries [20121115.025045] [1366] Nov 15 02:50:45.143 [1367] dbg: check: is spam? score=0 required=5 [20121115.025045] [1366] Nov 15 02:50:45.143 [1367] dbg: check: tests= [20121115.025045] [1366] Nov 15 02:50:45.143 [1367] dbg: check: subtests= [20121115.025045] [1366] Nov 15 02:50:45.144 [1367] dbg: timing: total 66 ms - init: 63 (95.0%), parse: 0.51 (0.8%), extract_message_metadata: 1.00 (1.5%), get_uri_detail_list: 0.70 (1.1%) [20121115.025045] [1366] Nov 15 02:50:45.144 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0x96b3d4c) implements 'finish_tests', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.144 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0xa5253d4) implements 'finish_tests', priority 0 [20121115.025045] [1366] Nov 15 02:50:45.144 [1367] dbg: generic: lint check of site pre files succeeded, continuing with channel updates [20121115.025045] [1366] Nov 15 02:50:45.157 [1367] dbg: channel: reading MIRRORED.BY file [20121115.025045] [1366] Nov 15 02:50:45.157 [1367] dbg: channel: found mirror http://sa-update.dnswl.org/ weight=1 [20121115.025045] [1366] Nov 15 02:50:45.157 [1367] dbg: channel: found mirror http://www.sa-update.pccc.com/ weight=5 [20121115.025045] [1366] Nov 15 02:50:45.158 [1367] dbg: channel: found mirror http://sa-update.secnap.net/ weight=5 [20121115.025045] [1366] Nov 15 02:50:45.158 [1367] dbg: channel: selected mirror http://www.sa-update.pccc.com [20121115.025045] [1366] Nov 15 02:50:45.173 [1367] dbg: http: GET request, http://www.sa-update.pccc.com/1409116.tar.gz [20121115.025045] [1366] Nov 15 02:50:45.366 [1367] dbg: http: GET request, http://www.sa-update.pccc.com/1409116.tar.gz.sha1 [20121115.025045] [1366] Nov 15 02:50:45.417 [1367] dbg: http: GET request, http://www.sa-update.pccc.com/1409116.tar.gz.asc [20121115.025045] [1366] Nov 15 02:50:45.459 [1367] dbg: http: IMS GET request, http://www.sa-update.pccc.com/MIRRORED.BY, Wed, 14 Nov 2012 08:50:54 GMT [20121115.025046] [1366] Nov 15 02:50:46.055 [1367] dbg: sha1: verification wanted: 205bb0db33a2f8e34f04434419b09ca65a9f946e [20121115.025046] [1366] Nov 15 02:50:46.055 [1367] dbg: sha1: verification result: 205bb0db33a2f8e34f04434419b09ca65a9f946e [20121115.025046] [1366] Nov 15 02:50:46.055 [1367] dbg: channel: populating temp content file [20121115.025046] [1366] Nov 15 02:50:46.056 [1367] dbg: gpg: populating temp signature file [20121115.025046] [1366] Nov 15 02:50:46.056 [1367] dbg: gpg: calling gpg [20121115.025046] [1366] Nov 15 02:50:46.192 [1367] dbg: gpg: gpg: Signature made Wed Nov 14 21:08:46 2012 CST using RSA key ID 24F434CE [20121115.025046] [1366] Nov 15 02:50:46.192 [1367] dbg: gpg: gpg: WARNING: signing subkey 24F434CE is not cross-certified [20121115.025046] [1366] Nov 15 02:50:46.192 [1367] dbg: gpg: gpg: please see http://www.gnupg.org/faq/subkey-cross-certify.html for more information [20121115.025046] [1366] Nov 15 02:50:46.192 [1367] dbg: gpg: [GNUPG:] SIG_ID byXqrd89D7G1Uy9fpOdodaKocDw 2012-11-15 1352948926 [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: [GNUPG:] GOODSIG 6C55397824F434CE updates.spamassassin.org Signing Key [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: gpg: Good signature from "updates.spamassassin.org Signing Key " [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: [GNUPG:] VALIDSIG 0C2B1D7175B852C64B3CDC716C55397824F434CE 2012-11-15 1352948926 0 3 0 1 2 00 5E541DC959CB8BAC7C78DFDC4056A61A5244EC45 [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: [GNUPG:] TRUST_UNDEFINED [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: gpg: WARNING: This key is not certified with a trusted signature! [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: gpg: There is no indication that the signature belongs to the owner. [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: Primary key fingerprint: 5E54 1DC9 59CB 8BAC 7C78 DFDC 4056 A61A 5244 EC45 [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: Subkey fingerprint: 0C2B 1D71 75B8 52C6 4B3C DC71 6C55 3978 24F4 34CE [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: found signature made by key 0C2B1D7175B852C64B3CDC716C55397824F434CE [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: gpg: key id 0C2B1D7175B852C64B3CDC716C55397824F434CE is release trusted [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: channel: file verification passed, testing update [20121115.025046] [1366] Nov 15 02:50:46.193 [1367] dbg: channel: extracting archive [20121115.025046] [1366] Nov 15 02:50:46.245 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/10_default_prefs.cf [20121115.025046] [1366] Nov 15 02:50:46.245 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/10_hasbase.cf [20121115.025046] [1366] Nov 15 02:50:46.246 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_advance_fee.cf [20121115.025046] [1366] Nov 15 02:50:46.246 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_aux_tlds.cf [20121115.025046] [1366] Nov 15 02:50:46.246 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_body_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.247 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_compensate.cf [20121115.025046] [1366] Nov 15 02:50:46.247 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_dnsbl_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.247 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_drugs.cf [20121115.025046] [1366] Nov 15 02:50:46.247 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_dynrdns.cf [20121115.025046] [1366] Nov 15 02:50:46.248 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_fake_helo_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.248 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_freemail.cf [20121115.025046] [1366] Nov 15 02:50:46.248 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_freemail_domains.cf [20121115.025046] [1366] Nov 15 02:50:46.248 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_head_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.249 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_html_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.249 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_imageinfo.cf [20121115.025046] [1366] Nov 15 02:50:46.249 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_mailspike.cf [20121115.025046] [1366] Nov 15 02:50:46.250 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_meta_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.250 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_net_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.250 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_phrases.cf [20121115.025046] [1366] Nov 15 02:50:46.250 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_porn.cf [20121115.025046] [1366] Nov 15 02:50:46.251 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_ratware.cf [20121115.025046] [1366] Nov 15 02:50:46.251 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_uri_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.251 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/20_vbounce.cf [20121115.025046] [1366] Nov 15 02:50:46.251 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/23_bayes.cf [20121115.025046] [1366] Nov 15 02:50:46.252 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_accessdb.cf [20121115.025046] [1366] Nov 15 02:50:46.252 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_antivirus.cf [20121115.025046] [1366] Nov 15 02:50:46.252 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_asn.cf [20121115.025046] [1366] Nov 15 02:50:46.252 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_dcc.cf [20121115.025046] [1366] Nov 15 02:50:46.253 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_dkim.cf [20121115.025046] [1366] Nov 15 02:50:46.253 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_hashcash.cf [20121115.025046] [1366] Nov 15 02:50:46.253 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_pyzor.cf [20121115.025046] [1366] Nov 15 02:50:46.254 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_razor2.cf [20121115.025046] [1366] Nov 15 02:50:46.254 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_replace.cf [20121115.025046] [1366] Nov 15 02:50:46.254 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_spf.cf [20121115.025046] [1366] Nov 15 02:50:46.254 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_textcat.cf [20121115.025046] [1366] Nov 15 02:50:46.255 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/25_uribl.cf [20121115.025046] [1366] Nov 15 02:50:46.255 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/30_text_de.cf [20121115.025046] [1366] Nov 15 02:50:46.256 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/30_text_fr.cf [20121115.025046] [1366] Nov 15 02:50:46.256 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/30_text_it.cf [20121115.025046] [1366] Nov 15 02:50:46.256 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/30_text_nl.cf [20121115.025046] [1366] Nov 15 02:50:46.257 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/30_text_pl.cf [20121115.025046] [1366] Nov 15 02:50:46.257 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/30_text_pt_br.cf [20121115.025046] [1366] Nov 15 02:50:46.258 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/50_scores.cf [20121115.025046] [1366] Nov 15 02:50:46.258 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/60_adsp_override_dkim.cf [20121115.025046] [1366] Nov 15 02:50:46.259 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/60_awl.cf [20121115.025046] [1366] Nov 15 02:50:46.259 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/60_shortcircuit.cf [20121115.025046] [1366] Nov 15 02:50:46.259 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/60_whitelist.cf [20121115.025046] [1366] Nov 15 02:50:46.260 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/60_whitelist_dkim.cf [20121115.025046] [1366] Nov 15 02:50:46.260 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/60_whitelist_spf.cf [20121115.025046] [1366] Nov 15 02:50:46.261 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/60_whitelist_subject.cf [20121115.025046] [1366] Nov 15 02:50:46.261 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/72_active.cf [20121115.025046] [1366] Nov 15 02:50:46.264 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/72_scores.cf [20121115.025046] [1366] Nov 15 02:50:46.264 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/73_sandbox_manual_scores.cf [20121115.025046] [1366] Nov 15 02:50:46.265 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/local.cf [20121115.025046] [1366] Nov 15 02:50:46.265 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/regression_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.265 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/STATISTICS-set0-72_scores.cf.txt [20121115.025046] [1366] Nov 15 02:50:46.266 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/STATISTICS-set1-72_scores.cf.txt [20121115.025046] [1366] Nov 15 02:50:46.266 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/STATISTICS-set2-72_scores.cf.txt [20121115.025046] [1366] Nov 15 02:50:46.266 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/STATISTICS-set3-72_scores.cf.txt [20121115.025046] [1366] Nov 15 02:50:46.267 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/sa-update-pubkey.txt [20121115.025046] [1366] Nov 15 02:50:46.267 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/languages [20121115.025046] [1366] Nov 15 02:50:46.268 [1367] dbg: extracting: /tmp/.spamassassin1367vf9soVtmp/user_prefs.template [20121115.025046] [1366] Nov 15 02:50:46.268 [1367] dbg: generic: SpamAssassin version 3.3.1 [20121115.025046] [1366] Nov 15 02:50:46.269 [1367] dbg: generic: Perl 5.008008, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin [20121115.025046] [1366] Nov 15 02:50:46.269 [1367] dbg: config: timing enabled [20121115.025046] [1366] Nov 15 02:50:46.269 [1367] dbg: config: score set 0 chosen. [20121115.025046] [1366] Nov 15 02:50:46.271 [1367] dbg: dns: is Net::DNS::Resolver available? yes [20121115.025046] [1366] Nov 15 02:50:46.271 [1367] dbg: dns: Net::DNS version: 0.68 [20121115.025046] [1366] Nov 15 02:50:46.271 [1367] dbg: ignore: using a test message to lint rules [20121115.025046] [1366] Nov 15 02:50:46.271 [1367] dbg: config: using "/etc/mail/spamassassin" for site rules pre files [20121115.025046] [1366] Nov 15 02:50:46.272 [1367] dbg: config: read file /etc/mail/spamassassin/init.pre [20121115.025046] [1366] Nov 15 02:50:46.272 [1367] dbg: config: read file /etc/mail/spamassassin/v310.pre [20121115.025046] [1366] Nov 15 02:50:46.272 [1367] dbg: config: read file /etc/mail/spamassassin/v312.pre [20121115.025046] [1366] Nov 15 02:50:46.272 [1367] dbg: config: read file /etc/mail/spamassassin/v320.pre [20121115.025046] [1366] Nov 15 02:50:46.272 [1367] dbg: config: read file /etc/mail/spamassassin/v330.pre [20121115.025046] [1366] Nov 15 02:50:46.272 [1367] dbg: config: using "/tmp/.spamassassin1367vf9soVtmp" for sys rules pre files [20121115.025046] [1366] Nov 15 02:50:46.272 [1367] dbg: config: using "/tmp/.spamassassin1367vf9soVtmp" for default rules dir [20121115.025046] [1366] Nov 15 02:50:46.273 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/10_default_prefs.cf [20121115.025046] [1366] Nov 15 02:50:46.273 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/10_hasbase.cf [20121115.025046] [1366] Nov 15 02:50:46.273 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_advance_fee.cf [20121115.025046] [1366] Nov 15 02:50:46.273 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_aux_tlds.cf [20121115.025046] [1366] Nov 15 02:50:46.273 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_body_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.273 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_compensate.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_dnsbl_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_drugs.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_dynrdns.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_fake_helo_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_freemail.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_freemail_domains.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_head_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_html_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.274 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_imageinfo.cf [20121115.025046] [1366] Nov 15 02:50:46.275 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_mailspike.cf [20121115.025046] [1366] Nov 15 02:50:46.275 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_meta_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.275 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_net_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.275 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_phrases.cf [20121115.025046] [1366] Nov 15 02:50:46.275 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_porn.cf [20121115.025046] [1366] Nov 15 02:50:46.275 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_ratware.cf [20121115.025046] [1366] Nov 15 02:50:46.275 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_uri_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.275 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/20_vbounce.cf [20121115.025046] [1366] Nov 15 02:50:46.276 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/23_bayes.cf [20121115.025046] [1366] Nov 15 02:50:46.276 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_accessdb.cf [20121115.025046] [1366] Nov 15 02:50:46.276 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_antivirus.cf [20121115.025046] [1366] Nov 15 02:50:46.276 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_asn.cf [20121115.025046] [1366] Nov 15 02:50:46.276 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_dcc.cf [20121115.025046] [1366] Nov 15 02:50:46.276 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_dkim.cf [20121115.025046] [1366] Nov 15 02:50:46.276 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_hashcash.cf [20121115.025046] [1366] Nov 15 02:50:46.276 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_pyzor.cf [20121115.025046] [1366] Nov 15 02:50:46.277 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_razor2.cf [20121115.025046] [1366] Nov 15 02:50:46.277 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_replace.cf [20121115.025046] [1366] Nov 15 02:50:46.277 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_spf.cf [20121115.025046] [1366] Nov 15 02:50:46.277 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_textcat.cf [20121115.025046] [1366] Nov 15 02:50:46.277 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/25_uribl.cf [20121115.025046] [1366] Nov 15 02:50:46.277 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/30_text_de.cf [20121115.025046] [1366] Nov 15 02:50:46.277 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/30_text_fr.cf [20121115.025046] [1366] Nov 15 02:50:46.277 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/30_text_it.cf [20121115.025046] [1366] Nov 15 02:50:46.278 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/30_text_nl.cf [20121115.025046] [1366] Nov 15 02:50:46.278 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/30_text_pl.cf [20121115.025046] [1366] Nov 15 02:50:46.278 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/30_text_pt_br.cf [20121115.025046] [1366] Nov 15 02:50:46.278 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/50_scores.cf [20121115.025046] [1366] Nov 15 02:50:46.278 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/60_adsp_override_dkim.cf [20121115.025046] [1366] Nov 15 02:50:46.278 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/60_awl.cf [20121115.025046] [1366] Nov 15 02:50:46.279 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/60_shortcircuit.cf [20121115.025046] [1366] Nov 15 02:50:46.279 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/60_whitelist.cf [20121115.025046] [1366] Nov 15 02:50:46.279 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/60_whitelist_dkim.cf [20121115.025046] [1366] Nov 15 02:50:46.279 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/60_whitelist_spf.cf [20121115.025046] [1366] Nov 15 02:50:46.279 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/60_whitelist_subject.cf [20121115.025046] [1366] Nov 15 02:50:46.279 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/72_active.cf [20121115.025046] [1366] Nov 15 02:50:46.279 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/72_scores.cf [20121115.025046] [1366] Nov 15 02:50:46.280 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/73_sandbox_manual_scores.cf [20121115.025046] [1366] Nov 15 02:50:46.280 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/local.cf [20121115.025046] [1366] Nov 15 02:50:46.280 [1367] dbg: config: read file /tmp/.spamassassin1367vf9soVtmp/regression_tests.cf [20121115.025046] [1366] Nov 15 02:50:46.282 [1367] dbg: config: using "/tmp/.spamassassin1367vf9soVtmp/doesnotexist" for user prefs file [20121115.025046] [1366] Nov 15 02:50:46.294 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC [20121115.025046] [1366] Nov 15 02:50:46.295 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC [20121115.025046] [1366] Nov 15 02:50:46.295 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC [20121115.025046] [1366] Nov 15 02:50:46.295 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::DCC from @INC [20121115.025046] [1366] Nov 15 02:50:46.295 [1367] dbg: dcc: local tests only, disabling DCC [20121115.025046] [1366] Nov 15 02:50:46.295 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC [20121115.025046] [1366] Nov 15 02:50:46.296 [1367] dbg: pyzor: local tests only, disabling Pyzor [20121115.025046] [1366] Nov 15 02:50:46.296 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC [20121115.025046] [1366] Nov 15 02:50:46.296 [1367] dbg: razor2: local tests only, skipping Razor [20121115.025046] [1366] Nov 15 02:50:46.296 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC [20121115.025046] [1366] Nov 15 02:50:46.296 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC [20121115.025046] [1366] Nov 15 02:50:46.297 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC [20121115.025046] [1366] Nov 15 02:50:46.297 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC [20121115.025046] [1366] Nov 15 02:50:46.297 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC [20121115.025046] [1366] Nov 15 02:50:46.297 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC [20121115.025046] [1366] Nov 15 02:50:46.298 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC [20121115.025046] [1366] Nov 15 02:50:46.298 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC [20121115.025046] [1366] Nov 15 02:50:46.298 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Shortcircuit from @INC [20121115.025046] [1366] Nov 15 02:50:46.298 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC [20121115.025046] [1366] Nov 15 02:50:46.298 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC [20121115.025046] [1366] Nov 15 02:50:46.299 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC [20121115.025046] [1366] Nov 15 02:50:46.299 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC [20121115.025046] [1366] Nov 15 02:50:46.299 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC [20121115.025046] [1366] Nov 15 02:50:46.299 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC [20121115.025046] [1366] Nov 15 02:50:46.300 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC [20121115.025046] [1366] Nov 15 02:50:46.300 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC [20121115.025046] [1366] Nov 15 02:50:46.300 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC [20121115.025046] [1366] Nov 15 02:50:46.300 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC [20121115.025046] [1366] Nov 15 02:50:46.300 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::Rule2XSBody from @INC [20121115.025046] [1366] Nov 15 02:50:46.301 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC [20121115.025046] [1366] Nov 15 02:50:46.301 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::PDFInfo from @INC [20121115.025046] [1366] Nov 15 02:50:46.301 [1367] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC [20121115.025046] [1366] Nov 15 02:50:46.333 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0xaafc50c) implements 'parse_config', priority 0 [20121115.025046] [1366] Nov 15 02:50:46.550 [1367] dbg: config: uri_detail adding (text =~ /(?-xism:(?i)\b(?:cli(?:quez\W|ck\Wa)ici\b|cli(?:cca\W|c\Wa|que\Wa)qu[^<.,a ]|klie?k(?:\Whi?er|ni(?:j|nite)\Wtu[tk]aj)\b))/) to T_KHOP_FOREIGN_CLICK [20121115.025046] [1366] Nov 15 02:50:46.550 [1367] dbg: config: uri_detail added T_KHOP_FOREIGN_CLICK [20121115.025046] [1366] Nov 15 02:50:46.552 [1367] dbg: config: uri_detail adding (raw !~ /(?-xism:canadapost\.)/) to T_AJB_CANPOST_BADLINK [20121115.025046] [1366] Nov 15 02:50:46.552 [1367] dbg: config: uri_detail adding (text =~ /(?-xism:(?:https?:\/\/|www\.)canadapost\.)/) to T_AJB_CANPOST_BADLINK [20121115.025046] [1366] Nov 15 02:50:46.552 [1367] dbg: config: uri_detail adding (type =~ /(?-xism:^a$)/) to T_AJB_CANPOST_BADLINK [20121115.025046] [1366] Nov 15 02:50:46.552 [1367] dbg: config: uri_detail added T_AJB_CANPOST_BADLINK [20121115.025046] [1366] Nov 15 02:50:46.552 [1367] dbg: config: uri_detail adding (raw !~ /(?-xism:youtube\.)/) to T_AJB_UTUBE_BADLINK [20121115.025046] [1366] Nov 15 02:50:46.552 [1367] dbg: config: uri_detail adding (text =~ /(?-xism:(?:https?:\/\/|www\.)youtube\.)/) to T_AJB_UTUBE_BADLINK [20121115.025046] [1366] Nov 15 02:50:46.552 [1367] dbg: config: uri_detail adding (type =~ /(?-xism:^a$)/) to T_AJB_UTUBE_BADLINK [20121115.025046] [1366] Nov 15 02:50:46.552 [1367] dbg: config: uri_detail added T_AJB_UTUBE_BADLINK [20121115.025046] [1366] Nov 15 02:50:46.630 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_H4 [20121115.025046] [1366] Nov 15 02:50:46.630 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_WL [20121115.025046] [1366] Nov 15 02:50:46.630 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_L5 [20121115.025046] [1366] Nov 15 02:50:46.630 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_H3 [20121115.025046] [1366] Nov 15 02:50:46.630 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_H2 [20121115.025046] [1366] Nov 15 02:50:46.630 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_ZBI [20121115.025046] [1366] Nov 15 02:50:46.631 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_L2 [20121115.025046] [1366] Nov 15 02:50:46.631 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_BL [20121115.025046] [1366] Nov 15 02:50:46.631 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_L4 [20121115.025046] [1366] Nov 15 02:50:46.631 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_H5 [20121115.025046] [1366] Nov 15 02:50:46.631 [1367] dbg: config: warning: score set for non-existent rule URIBL_SBL_A [20121115.025046] [1366] Nov 15 02:50:46.631 [1367] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_L3 [20121115.025047] [1366] Nov 15 02:50:47.050 [1367] dbg: rules: __DOS_HAS_MAILING_LIST merged duplicates: __ML4 [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: __HAS_LIST_ID merged duplicates: __ML2 [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: __JM_REACTOR_DATE merged duplicates: __RATWARE_0_TZ_DATE [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: __DOS_HAS_ANY_URI merged duplicates: __HAS_ANY_URI __HAS_URI [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: __FH_HAS_XMSMAIL merged duplicates: __HAS_MSMAIL_PRI [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: __HAS_REPLY_TO merged duplicates: __REPLYTO_EXISTS [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: __FH_HAS_XPRIORITY merged duplicates: __XPRIO [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: CTYPE_001C_A merged duplicates: DATE_IN_FUTURE_96_XX [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: __HAS_XMAIL merged duplicates: __HAS_X_MAILER [20121115.025047] [1366] Nov 15 02:50:47.051 [1367] dbg: rules: FH_MSGID_01C67 merged duplicates: __MSGID_VGA [20121115.025047] [1366] Nov 15 02:50:47.052 [1367] dbg: config: finish parsing [20121115.025047] [1366] Nov 15 02:50:47.068 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0xaafbf18) implements 'finish_parsing_end', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.068 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Rule2XSBody=HASH(0xaafc278) implements 'finish_parsing_end', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.068 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0xaafc50c) implements 'finish_parsing_end', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.068 [1367] dbg: replacetags: replacing tags [20121115.025047] [1366] Nov 15 02:50:47.086 [1367] dbg: replacetags: done replacing tags [20121115.025047] [1366] Nov 15 02:50:47.087 [1367] dbg: zoom: loading compiled ruleset from /var/lib/spamassassin/compiled/5.008/3.003001 [20121115.025047] [1366] Nov 15 02:50:47.090 [1367] dbg: zoom: using compiled ruleset in /var/lib/spamassassin/compiled/5.008/3.003001/Mail/SpamAssassin/CompiledRege xps/body_0.pm for Mail::SpamAssassin::CompiledRegexps::body_0 [20121115.025047] [1366] Nov 15 02:50:47.091 [1367] dbg: zoom: skipping rule __DESTROY_ME, code differs in compiled ruleset [20121115.025047] [1366] Nov 15 02:50:47.092 [1367] dbg: zoom: skipping rule __IS_LEGAL, code differs in compiled ruleset [20121115.025047] [1366] Nov 15 02:50:47.092 [1367] dbg: zoom: skipping rule __SURVIVORS, code differs in compiled ruleset [20121115.025047] [1366] Nov 15 02:50:47.092 [1367] dbg: zoom: skipping rule LOTTO_AGENT, code differs in compiled ruleset [20121115.025047] [1366] Nov 15 02:50:47.093 [1367] dbg: zoom: skipping rule __DEAL, code differs in compiled ruleset [20121115.025047] [1366] Nov 15 02:50:47.093 [1367] dbg: zoom: skipping rule __EX_CUSTOMER, code differs in compiled ruleset [20121115.025047] [1366] Nov 15 02:50:47.093 [1367] dbg: zoom: skipping rule __FRAUD_XWW, code differs in compiled ruleset [20121115.025047] [1366] Nov 15 02:50:47.094 [1367] dbg: zoom: skipping rule __HUSH_HUSH, code differs in compiled ruleset [20121115.025047] [1366] Nov 15 02:50:47.094 [1367] dbg: zoom: able to use 394/840 'body_0' compiled rules (46.904%) [20121115.025047] [1366] Nov 15 02:50:47.094 [1367] dbg: FreeMail: loaded freemail_domains entries: 2112 normal, 26 wildcard [20121115.025047] [1366] Nov 15 02:50:47.095 [1367] dbg: config: score set 0 chosen. [20121115.025047] [1366] Nov 15 02:50:47.096 [1367] dbg: message: main message type: text/plain [20121115.025047] [1366] Nov 15 02:50:47.096 [1367] dbg: message: ---- MIME PARSER START ---- [20121115.025047] [1366] Nov 15 02:50:47.096 [1367] dbg: message: parsing normal part [20121115.025047] [1366] Nov 15 02:50:47.096 [1367] dbg: message: ---- MIME PARSER END ---- [20121115.025047] [1366] Nov 15 02:50:47.097 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0xa904d70) implements 'check_start', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.097 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Rule2XSBody=HASH(0xaafc278) implements 'check_start', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.098 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0xaafc17c) implements 'check_main', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.098 [1367] dbg: config: trusted_networks are not configured; it is recommended that you configure trusted_networks manually [20121115.025047] [1366] Nov 15 02:50:47.098 [1367] dbg: metadata: X-Spam-Relays-Trusted: [20121115.025047] [1366] Nov 15 02:50:47.098 [1367] dbg: metadata: X-Spam-Relays-Untrusted: [20121115.025047] [1366] Nov 15 02:50:47.098 [1367] dbg: metadata: X-Spam-Relays-Internal: [20121115.025047] [1366] Nov 15 02:50:47.098 [1367] dbg: metadata: X-Spam-Relays-External: [20121115.025047] [1366] Nov 15 02:50:47.099 [1367] dbg: message: no encoding detected [20121115.025047] [1366] Nov 15 02:50:47.099 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xaafba98) implements 'parsed_metadata', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.099 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Shortcircuit=HASH(0xaafc1dc) implements 'parsed_metadata', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.099 [1367] dbg: rules: local tests only, ignoring RBL eval [20121115.025047] [1366] Nov 15 02:50:47.100 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Shortcircuit=HASH(0xaafc1dc) implements 'have_shortcircuited', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.100 [1367] dbg: check: running tests for priority: -1000 [20121115.025047] [1366] Nov 15 02:50:47.100 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Rule2XSBody=HASH(0xaafc278) implements 'check_rules_at_priority', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.100 [1367] dbg: rules: running one_line_body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.101 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 502 chars of Mail::SpamAssassin::Plugin::Check::_one_line_body_tests_neg1000_1 [20121115.025047] [1366] Nov 15 02:50:47.101 [1367] dbg: rules: run_generic_tests - compiling eval code: one_line_body, priority -1000 [20121115.025047] [1366] Nov 15 02:50:47.101 [1367] dbg: rules: compiled one_line_body tests [20121115.025047] [1366] Nov 15 02:50:47.101 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Rule2XSBody=HASH(0xaafc278) implements 'run_body_fast_scan', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.101 [1367] dbg: rules: running head tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.102 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 282 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg1000_1 [20121115.025047] [1366] Nov 15 02:50:47.102 [1367] dbg: rules: run_generic_tests - compiling eval code: head, priority -1000 [20121115.025047] [1366] Nov 15 02:50:47.102 [1367] dbg: rules: compiled head tests [20121115.025047] [1366] Nov 15 02:50:47.102 [1367] dbg: rules: running head_eval tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.102 [1367] dbg: rules: run_eval_tests - compiling eval code: 9, priority -1000 [20121115.025047] [1366] Nov 15 02:50:47.103 [1367] dbg: eval: all '*From' addrs: ignore@compiling.spamassassin.taint.org [20121115.025047] [1366] Nov 15 02:50:47.103 [1367] dbg: eval: all '*To' addrs: [20121115.025047] [1366] Nov 15 02:50:47.104 [1367] dbg: rules: running body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.104 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg1000_1 [20121115.025047] [1366] Nov 15 02:50:47.104 [1367] dbg: rules: run_generic_tests - compiling eval code: body, priority -1000 [20121115.025047] [1366] Nov 15 02:50:47.104 [1367] dbg: rules: compiled body tests [20121115.025047] [1366] Nov 15 02:50:47.104 [1367] dbg: rules: running uri tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.104 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg1000_1 [20121115.025047] [1366] Nov 15 02:50:47.104 [1367] dbg: rules: run_generic_tests - compiling eval code: uri, priority -1000 [20121115.025047] [1366] Nov 15 02:50:47.105 [1367] dbg: rules: compiled uri tests [20121115.025047] [1366] Nov 15 02:50:47.105 [1367] dbg: rules: running rawbody tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.105 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 231 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg1000_1 [20121115.025047] [1366] Nov 15 02:50:47.105 [1367] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -1000 [20121115.025047] [1366] Nov 15 02:50:47.105 [1367] dbg: rules: compiled rawbody tests [20121115.025047] [1366] Nov 15 02:50:47.105 [1367] dbg: rules: running full tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.105 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 260 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg1000_1 [20121115.025047] [1366] Nov 15 02:50:47.105 [1367] dbg: rules: run_generic_tests - compiling eval code: full, priority -1000 [20121115.025047] [1366] Nov 15 02:50:47.106 [1367] dbg: rules: compiled full tests [20121115.025047] [1366] Nov 15 02:50:47.106 [1367] dbg: rules: running meta tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.106 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 285 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg1000_1 [20121115.025047] [1366] Nov 15 02:50:47.106 [1367] dbg: rules: run_generic_tests - compiling eval code: meta, priority -1000 [20121115.025047] [1366] Nov 15 02:50:47.106 [1367] dbg: rules: compiled meta tests [20121115.025047] [1366] Nov 15 02:50:47.106 [1367] dbg: check: running tests for priority: -950 [20121115.025047] [1366] Nov 15 02:50:47.106 [1367] dbg: rules: running one_line_body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.107 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 499 chars of Mail::SpamAssassin::Plugin::Check::_one_line_body_tests_neg950_1 [20121115.025047] [1366] Nov 15 02:50:47.107 [1367] dbg: rules: run_generic_tests - compiling eval code: one_line_body, priority -950 [20121115.025047] [1366] Nov 15 02:50:47.107 [1367] dbg: rules: compiled one_line_body tests [20121115.025047] [1366] Nov 15 02:50:47.107 [1367] dbg: rules: running head tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.107 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg950_1 [20121115.025047] [1366] Nov 15 02:50:47.107 [1367] dbg: rules: run_generic_tests - compiling eval code: head, priority -950 [20121115.025047] [1366] Nov 15 02:50:47.107 [1367] dbg: rules: compiled head tests [20121115.025047] [1366] Nov 15 02:50:47.107 [1367] dbg: rules: running head_eval tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.108 [1367] dbg: rules: run_eval_tests - compiling eval code: 9, priority -950 [20121115.025047] [1366] Nov 15 02:50:47.108 [1367] dbg: rules: running body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.108 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg950_1 [20121115.025047] [1366] Nov 15 02:50:47.108 [1367] dbg: rules: run_generic_tests - compiling eval code: body, priority -950 [20121115.025047] [1366] Nov 15 02:50:47.108 [1367] dbg: rules: compiled body tests [20121115.025047] [1366] Nov 15 02:50:47.108 [1367] dbg: rules: running uri tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.109 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg950_1 [20121115.025047] [1366] Nov 15 02:50:47.109 [1367] dbg: rules: run_generic_tests - compiling eval code: uri, priority -950 [20121115.025047] [1366] Nov 15 02:50:47.109 [1367] dbg: rules: compiled uri tests [20121115.025047] [1366] Nov 15 02:50:47.109 [1367] dbg: rules: running rawbody tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.109 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg950_1 [20121115.025047] [1366] Nov 15 02:50:47.109 [1367] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -950 [20121115.025047] [1366] Nov 15 02:50:47.109 [1367] dbg: rules: compiled rawbody tests [20121115.025047] [1366] Nov 15 02:50:47.109 [1367] dbg: rules: running full tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.110 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg950_1 [20121115.025047] [1366] Nov 15 02:50:47.110 [1367] dbg: rules: run_generic_tests - compiling eval code: full, priority -950 [20121115.025047] [1366] Nov 15 02:50:47.110 [1367] dbg: rules: compiled full tests [20121115.025047] [1366] Nov 15 02:50:47.110 [1367] dbg: rules: running meta tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.110 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg950_1 [20121115.025047] [1366] Nov 15 02:50:47.110 [1367] dbg: rules: run_generic_tests - compiling eval code: meta, priority -950 [20121115.025047] [1366] Nov 15 02:50:47.110 [1367] dbg: rules: compiled meta tests [20121115.025047] [1366] Nov 15 02:50:47.111 [1367] dbg: check: running tests for priority: -900 [20121115.025047] [1366] Nov 15 02:50:47.111 [1367] dbg: rules: running one_line_body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.111 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 499 chars of Mail::SpamAssassin::Plugin::Check::_one_line_body_tests_neg900_1 [20121115.025047] [1366] Nov 15 02:50:47.111 [1367] dbg: rules: run_generic_tests - compiling eval code: one_line_body, priority -900 [20121115.025047] [1366] Nov 15 02:50:47.111 [1367] dbg: rules: compiled one_line_body tests [20121115.025047] [1366] Nov 15 02:50:47.111 [1367] dbg: rules: running head tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.111 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg900_1 [20121115.025047] [1366] Nov 15 02:50:47.111 [1367] dbg: rules: run_generic_tests - compiling eval code: head, priority -900 [20121115.025047] [1366] Nov 15 02:50:47.112 [1367] dbg: rules: compiled head tests [20121115.025047] [1366] Nov 15 02:50:47.112 [1367] dbg: rules: running head_eval tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.112 [1367] dbg: rules: run_eval_tests - compiling eval code: 9, priority -900 [20121115.025047] [1366] Nov 15 02:50:47.112 [1367] dbg: rules: running body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.113 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg900_1 [20121115.025047] [1366] Nov 15 02:50:47.113 [1367] dbg: rules: run_generic_tests - compiling eval code: body, priority -900 [20121115.025047] [1366] Nov 15 02:50:47.113 [1367] dbg: rules: compiled body tests [20121115.025047] [1366] Nov 15 02:50:47.113 [1367] dbg: rules: running uri tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.113 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg900_1 [20121115.025047] [1366] Nov 15 02:50:47.113 [1367] dbg: rules: run_generic_tests - compiling eval code: uri, priority -900 [20121115.025047] [1366] Nov 15 02:50:47.113 [1367] dbg: rules: compiled uri tests [20121115.025047] [1366] Nov 15 02:50:47.114 [1367] dbg: rules: running rawbody tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.114 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg900_1 [20121115.025047] [1366] Nov 15 02:50:47.114 [1367] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -900 [20121115.025047] [1366] Nov 15 02:50:47.114 [1367] dbg: rules: compiled rawbody tests [20121115.025047] [1366] Nov 15 02:50:47.114 [1367] dbg: rules: running full tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.114 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg900_1 [20121115.025047] [1366] Nov 15 02:50:47.114 [1367] dbg: rules: run_generic_tests - compiling eval code: full, priority -900 [20121115.025047] [1366] Nov 15 02:50:47.114 [1367] dbg: rules: compiled full tests [20121115.025047] [1366] Nov 15 02:50:47.115 [1367] dbg: rules: running meta tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.115 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg900_1 [20121115.025047] [1366] Nov 15 02:50:47.115 [1367] dbg: rules: run_generic_tests - compiling eval code: meta, priority -900 [20121115.025047] [1366] Nov 15 02:50:47.115 [1367] dbg: rules: compiled meta tests [20121115.025047] [1366] Nov 15 02:50:47.115 [1367] dbg: check: running tests for priority: -400 [20121115.025047] [1366] Nov 15 02:50:47.115 [1367] dbg: rules: running one_line_body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.115 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 499 chars of Mail::SpamAssassin::Plugin::Check::_one_line_body_tests_neg400_1 [20121115.025047] [1366] Nov 15 02:50:47.116 [1367] dbg: rules: run_generic_tests - compiling eval code: one_line_body, priority -400 [20121115.025047] [1366] Nov 15 02:50:47.116 [1367] dbg: rules: compiled one_line_body tests [20121115.025047] [1366] Nov 15 02:50:47.116 [1367] dbg: rules: running head tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.116 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg400_1 [20121115.025047] [1366] Nov 15 02:50:47.116 [1367] dbg: rules: run_generic_tests - compiling eval code: head, priority -400 [20121115.025047] [1366] Nov 15 02:50:47.116 [1367] dbg: rules: compiled head tests [20121115.025047] [1366] Nov 15 02:50:47.116 [1367] dbg: rules: running body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.116 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg400_1 [20121115.025047] [1366] Nov 15 02:50:47.117 [1367] dbg: rules: run_generic_tests - compiling eval code: body, priority -400 [20121115.025047] [1366] Nov 15 02:50:47.117 [1367] dbg: rules: compiled body tests [20121115.025047] [1366] Nov 15 02:50:47.117 [1367] dbg: rules: running uri tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.117 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg400_1 [20121115.025047] [1366] Nov 15 02:50:47.117 [1367] dbg: rules: run_generic_tests - compiling eval code: uri, priority -400 [20121115.025047] [1366] Nov 15 02:50:47.117 [1367] dbg: rules: compiled uri tests [20121115.025047] [1366] Nov 15 02:50:47.117 [1367] dbg: rules: running body_eval tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.117 [1367] dbg: rules: run_eval_tests - compiling eval code: 11, priority -400 [20121115.025047] [1366] Nov 15 02:50:47.118 [1367] dbg: rules: running rawbody tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.118 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg400_1 [20121115.025047] [1366] Nov 15 02:50:47.118 [1367] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -400 [20121115.025047] [1366] Nov 15 02:50:47.118 [1367] dbg: rules: compiled rawbody tests [20121115.025047] [1366] Nov 15 02:50:47.118 [1367] dbg: rules: running full tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.118 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg400_1 [20121115.025047] [1366] Nov 15 02:50:47.118 [1367] dbg: rules: run_generic_tests - compiling eval code: full, priority -400 [20121115.025047] [1366] Nov 15 02:50:47.118 [1367] dbg: rules: compiled full tests [20121115.025047] [1366] Nov 15 02:50:47.119 [1367] dbg: rules: running meta tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.119 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg400_1 [20121115.025047] [1366] Nov 15 02:50:47.119 [1367] dbg: rules: run_generic_tests - compiling eval code: meta, priority -400 [20121115.025047] [1366] Nov 15 02:50:47.119 [1367] dbg: rules: compiled meta tests [20121115.025047] [1366] Nov 15 02:50:47.119 [1367] dbg: check: running tests for priority: 0 [20121115.025047] [1366] Nov 15 02:50:47.119 [1367] dbg: rules: running one_line_body tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.130 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 488 chars of Mail::SpamAssassin::Plugin::Check::_one_line_body_tests_0_1 [20121115.025047] [1366] Nov 15 02:50:47.131 [1367] dbg: rules: run_generic_tests - compiling eval code: one_line_body, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.152 [1367] dbg: rules: compiled one_line_body tests [20121115.025047] [1366] Nov 15 02:50:47.152 [1367] dbg: zoom: run_body_fast_scan for body_0 start [20121115.025047] [1366] Nov 15 02:50:47.153 [1367] dbg: zoom: run_body_fast_scan for body_0 done [20121115.025047] [1366] Nov 15 02:50:47.153 [1367] dbg: rules: running head tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.170 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 60280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_1 [20121115.025047] [1366] Nov 15 02:50:47.180 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 60446 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_2 [20121115.025047] [1366] Nov 15 02:50:47.189 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 60524 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_3 [20121115.025047] [1366] Nov 15 02:50:47.197 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 60069 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_4 [20121115.025047] [1366] Nov 15 02:50:47.205 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 60214 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_5 [20121115.025047] [1366] Nov 15 02:50:47.210 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 4061 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_6 [20121115.025047] [1366] Nov 15 02:50:47.211 [1367] dbg: rules: run_generic_tests - compiling eval code: head, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.211 [1367] dbg: rules: compiled head tests [20121115.025047] [1366] Nov 15 02:50:47.212 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Shortcircuit=HASH(0xaafc1dc) implements 'hit_rule', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.212 [1367] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET" [20121115.025047] [1366] Nov 15 02:50:47.212 [1367] dbg: rules: ran header rule __MISSING_REPLY ======> got hit: "UNSET" [20121115.025047] [1366] Nov 15 02:50:47.212 [1367] dbg: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@lint_rules>" [20121115.025047] [1366] Nov 15 02:50:47.213 [1367] dbg: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "1352969446" [20121115.025047] [1366] Nov 15 02:50:47.213 [1367] dbg: rules: ran header rule __HAS_MESSAGE_ID ======> got hit: "" [20121115.025047] [1366] Nov 15 02:50:47.213 [1367] dbg: rules: ran header rule __HAS_SUBJECT ======> got hit: "" [20121115.025047] [1366] Nov 15 02:50:47.213 [1367] dbg: rules: ran header rule __MSOE_MID_WRONG_CASE ======> got hit: " [20121115.025047] [1366] Nov 15 02:50:47.214 [1367] dbg: rules: [...] Message-Id: " [20121115.025047] [1366] Nov 15 02:50:47.214 [1367] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<" [20121115.025047] [1366] Nov 15 02:50:47.214 [1367] dbg: rules: ran header rule __SANE_MSGID ======> got hit: "<1352969446@lint_rules> [20121115.025047] [1366] Nov 15 02:50:47.214 [1367] dbg: rules: [...] " [20121115.025047] [1366] Nov 15 02:50:47.214 [1367] dbg: rules: ran header rule __TO_NO_ARROWS_R ======> got hit: "3.3" [20121115.025047] [1366] Nov 15 02:50:47.214 [1367] dbg: rules: ran header rule __HAS_FROM ======> got hit: "" [20121115.025047] [1366] Nov 15 02:50:47.215 [1367] dbg: rules: running head_eval tests; score so far=0 [20121115.025047] [1366] Nov 15 02:50:47.216 [1367] dbg: rules: run_eval_tests - compiling eval code: 9, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.219 [1367] dbg: FreeMail: RULE (FREEMAIL_FROM) check_freemail_from [20121115.025047] [1366] Nov 15 02:50:47.219 [1367] dbg: FreeMail: all from-addresses: ignore@compiling.spamassassin.taint.org [20121115.025047] [1366] Nov 15 02:50:47.220 [1367] dbg: FreeMail: RULE (__freemail_reply) check_freemail_replyto [20121115.025047] [1366] Nov 15 02:50:47.220 [1367] dbg: FreeMail: From address: ignore@compiling.spamassassin.taint.org [20121115.025047] [1366] Nov 15 02:50:47.220 [1367] dbg: FreeMail: No Reply-To and From is not freemail, skipping check [20121115.025047] [1366] Nov 15 02:50:47.221 [1367] dbg: rules: ran eval rule NO_RELAYS ======> got hit (1) [20121115.025047] [1366] Nov 15 02:50:47.221 [1367] dbg: rules: ran eval rule __GATED_THROUGH_RCVD_REMOVER ======> got hit (1) [20121115.025047] [1366] Nov 15 02:50:47.222 [1367] dbg: FreeMail: RULE (FREEMAIL_REPLYTO_END_DIGIT) check_freemail_header regex:\d@ [20121115.025047] [1366] Nov 15 02:50:47.222 [1367] dbg: FreeMail: header Reply-To not found from mail [20121115.025047] [1366] Nov 15 02:50:47.222 [1367] dbg: FreeMail: RULE (__freemail_replyto) check_freemail_replyto [20121115.025047] [1366] Nov 15 02:50:47.222 [1367] dbg: FreeMail: From address: ignore@compiling.spamassassin.taint.org [20121115.025047] [1366] Nov 15 02:50:47.222 [1367] dbg: FreeMail: Reply-To is not freemail, skipping check [20121115.025047] [1366] Nov 15 02:50:47.222 [1367] dbg: FreeMail: RULE (__freemail_hdr_replyto) check_freemail_header [20121115.025047] [1366] Nov 15 02:50:47.222 [1367] dbg: FreeMail: header Reply-To not found from mail [20121115.025047] [1366] Nov 15 02:50:47.223 [1367] dbg: spf: cannot get Envelope-From, cannot use SPF [20121115.025047] [1366] Nov 15 02:50:47.223 [1367] dbg: spf: def_spf_whitelist_from: could not find useable envelope sender [20121115.025047] [1366] Nov 15 02:50:47.223 [1367] dbg: FreeMail: RULE (FREEMAIL_ENVFROM_END_DIGIT) check_freemail_header regex:\d@ [20121115.025047] [1366] Nov 15 02:50:47.223 [1367] dbg: FreeMail: header EnvelopeFrom not found from mail [20121115.025047] [1366] Nov 15 02:50:47.223 [1367] dbg: rules: ran eval rule MISSING_HEADERS ======> got hit (1) [20121115.025047] [1366] Nov 15 02:50:47.224 [1367] dbg: spf: spf_whitelist_from: could not find useable envelope sender [20121115.025047] [1366] Nov 15 02:50:47.224 [1367] dbg: rules: running body tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.226 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 60365 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_1 [20121115.025047] [1366] Nov 15 02:50:47.236 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 60457 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_2 [20121115.025047] [1366] Nov 15 02:50:47.244 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 34834 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_3 [20121115.025047] [1366] Nov 15 02:50:47.249 [1367] dbg: rules: run_generic_tests - compiling eval code: body, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.249 [1367] dbg: rules: compiled body tests [20121115.025047] [1366] Nov 15 02:50:47.257 [1367] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "I" [20121115.025047] [1366] Nov 15 02:50:47.279 [1367] dbg: rules: running uri tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.280 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 21827 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_0_1 [20121115.025047] [1366] Nov 15 02:50:47.282 [1367] dbg: rules: run_generic_tests - compiling eval code: uri, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.282 [1367] dbg: rules: compiled uri tests [20121115.025047] [1366] Nov 15 02:50:47.282 [1367] dbg: rules: running body_eval tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.284 [1367] dbg: rules: run_eval_tests - compiling eval code: 11, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.293 [1367] dbg: https_http_mismatch: anchors 0 [20121115.025047] [1366] Nov 15 02:50:47.299 [1367] dbg: rules: running rawbody tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.300 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 18569 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_1 [20121115.025047] [1366] Nov 15 02:50:47.303 [1367] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.303 [1367] dbg: rules: compiled rawbody tests [20121115.025047] [1366] Nov 15 02:50:47.303 [1367] dbg: rules: running rawbody_eval tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.303 [1367] dbg: rules: run_eval_tests - compiling eval code: 15, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.304 [1367] dbg: rules: running full tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.304 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 1119 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_0_1 [20121115.025047] [1366] Nov 15 02:50:47.304 [1367] dbg: rules: run_generic_tests - compiling eval code: full, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.304 [1367] dbg: rules: compiled full tests [20121115.025047] [1366] Nov 15 02:50:47.305 [1367] dbg: rules: running full_eval tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.305 [1367] dbg: rules: run_eval_tests - compiling eval code: 13, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.305 [1367] dbg: rules: running meta tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.305 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 275 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_0_1 [20121115.025047] [1366] Nov 15 02:50:47.305 [1367] dbg: rules: run_generic_tests - compiling eval code: meta, priority 0 [20121115.025047] [1366] Nov 15 02:50:47.305 [1367] dbg: rules: compiled meta tests [20121115.025047] [1366] Nov 15 02:50:47.306 [1367] dbg: check: running tests for priority: 500 [20121115.025047] [1366] Nov 15 02:50:47.306 [1367] dbg: dns: harvest_dnsbl_queries [20121115.025047] [1366] Nov 15 02:50:47.306 [1367] dbg: rules: running one_line_body tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.306 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 494 chars of Mail::SpamAssassin::Plugin::Check::_one_line_body_tests_500_1 [20121115.025047] [1366] Nov 15 02:50:47.306 [1367] dbg: rules: run_generic_tests - compiling eval code: one_line_body, priority 500 [20121115.025047] [1366] Nov 15 02:50:47.306 [1367] dbg: rules: compiled one_line_body tests [20121115.025047] [1366] Nov 15 02:50:47.307 [1367] dbg: rules: running head tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.307 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_500_1 [20121115.025047] [1366] Nov 15 02:50:47.307 [1367] dbg: rules: run_generic_tests - compiling eval code: head, priority 500 [20121115.025047] [1366] Nov 15 02:50:47.307 [1367] dbg: rules: compiled head tests [20121115.025047] [1366] Nov 15 02:50:47.307 [1367] dbg: rules: running body tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.307 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_500_1 [20121115.025047] [1366] Nov 15 02:50:47.307 [1367] dbg: rules: run_generic_tests - compiling eval code: body, priority 500 [20121115.025047] [1366] Nov 15 02:50:47.307 [1367] dbg: rules: compiled body tests [20121115.025047] [1366] Nov 15 02:50:47.308 [1367] dbg: rules: running uri tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.308 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_500_1 [20121115.025047] [1366] Nov 15 02:50:47.308 [1367] dbg: rules: run_generic_tests - compiling eval code: uri, priority 500 [20121115.025047] [1366] Nov 15 02:50:47.308 [1367] dbg: rules: compiled uri tests [20121115.025047] [1366] Nov 15 02:50:47.308 [1367] dbg: rules: running rawbody tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.308 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_500_1 [20121115.025047] [1366] Nov 15 02:50:47.308 [1367] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 500 [20121115.025047] [1366] Nov 15 02:50:47.309 [1367] dbg: rules: compiled rawbody tests [20121115.025047] [1366] Nov 15 02:50:47.309 [1367] dbg: rules: running full tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.309 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_500_1 [20121115.025047] [1366] Nov 15 02:50:47.309 [1367] dbg: rules: run_generic_tests - compiling eval code: full, priority 500 [20121115.025047] [1366] Nov 15 02:50:47.309 [1367] dbg: rules: compiled full tests [20121115.025047] [1366] Nov 15 02:50:47.309 [1367] dbg: rules: running meta tests; score so far=0.914 [20121115.025047] [1366] Nov 15 02:50:47.316 [1367] dbg: rules: meta test __FROM_MISSP_DKIM has undefined dependency '__DKIM_DEPENDABLE' [20121115.025047] [1366] Nov 15 02:50:47.331 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 60108 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_1 [20121115.025047] [1366] Nov 15 02:50:47.343 [1367] dbg: rules: flush_evalstr (add_evalstr) compiling 61874 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_2 [20121115.025047] [1366] Nov 15 02:50:47.350 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 1935 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_3 [20121115.025047] [1366] Nov 15 02:50:47.350 [1367] dbg: rules: run_generic_tests - compiling eval code: meta, priority 500 [20121115.025047] [1366] Nov 15 02:50:47.350 [1367] dbg: rules: compiled meta tests [20121115.025047] [1366] Nov 15 02:50:47.352 [1367] dbg: check: running tests for priority: 1000 [20121115.025047] [1366] Nov 15 02:50:47.352 [1367] dbg: rules: running one_line_body tests; score so far=3.652 [20121115.025047] [1366] Nov 15 02:50:47.352 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 497 chars of Mail::SpamAssassin::Plugin::Check::_one_line_body_tests_1000_1 [20121115.025047] [1366] Nov 15 02:50:47.352 [1367] dbg: rules: run_generic_tests - compiling eval code: one_line_body, priority 1000 [20121115.025047] [1366] Nov 15 02:50:47.352 [1367] dbg: rules: compiled one_line_body tests [20121115.025047] [1366] Nov 15 02:50:47.352 [1367] dbg: rules: running head tests; score so far=3.652 [20121115.025047] [1366] Nov 15 02:50:47.353 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 278 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_1000_1 [20121115.025047] [1366] Nov 15 02:50:47.353 [1367] dbg: rules: run_generic_tests - compiling eval code: head, priority 1000 [20121115.025047] [1366] Nov 15 02:50:47.353 [1367] dbg: rules: compiled head tests [20121115.025047] [1366] Nov 15 02:50:47.353 [1367] dbg: rules: running head_eval tests; score so far=3.652 [20121115.025047] [1366] Nov 15 02:50:47.353 [1367] dbg: rules: run_eval_tests - compiling eval code: 9, priority 1000 [20121115.025047] [1366] Nov 15 02:50:47.353 [1367] dbg: rules: running body tests; score so far=3.652 [20121115.025047] [1366] Nov 15 02:50:47.354 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_1000_1 [20121115.025047] [1366] Nov 15 02:50:47.354 [1367] dbg: rules: run_generic_tests - compiling eval code: body, priority 1000 [20121115.025047] [1366] Nov 15 02:50:47.354 [1367] dbg: rules: compiled body tests [20121115.025047] [1366] Nov 15 02:50:47.354 [1367] dbg: rules: running uri tests; score so far=3.652 [20121115.025047] [1366] Nov 15 02:50:47.354 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_1000_1 [20121115.025047] [1366] Nov 15 02:50:47.354 [1367] dbg: rules: run_generic_tests - compiling eval code: uri, priority 1000 [20121115.025047] [1366] Nov 15 02:50:47.354 [1367] dbg: rules: compiled uri tests [20121115.025047] [1366] Nov 15 02:50:47.355 [1367] dbg: rules: running rawbody tests; score so far=3.652 [20121115.025047] [1366] Nov 15 02:50:47.355 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 227 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_1000_1 [20121115.025047] [1366] Nov 15 02:50:47.355 [1367] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 1000 [20121115.025047] [1366] Nov 15 02:50:47.355 [1367] dbg: rules: compiled rawbody tests [20121115.025047] [1366] Nov 15 02:50:47.355 [1367] dbg: rules: running full tests; score so far=3.652 [20121115.025047] [1366] Nov 15 02:50:47.355 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 256 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_1000_1 [20121115.025047] [1366] Nov 15 02:50:47.355 [1367] dbg: rules: run_generic_tests - compiling eval code: full, priority 1000 [20121115.025047] [1366] Nov 15 02:50:47.355 [1367] dbg: rules: compiled full tests [20121115.025047] [1366] Nov 15 02:50:47.356 [1367] dbg: rules: running meta tests; score so far=3.652 [20121115.025047] [1366] Nov 15 02:50:47.356 [1367] dbg: rules: flush_evalstr (run_generic_tests) compiling 281 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_1000_1 [20121115.025047] [1366] Nov 15 02:50:47.356 [1367] dbg: rules: run_generic_tests - compiling eval code: meta, priority 1000 [20121115.025047] [1366] Nov 15 02:50:47.356 [1367] dbg: rules: compiled meta tests [20121115.025047] [1366] Nov 15 02:50:47.356 [1367] dbg: check: is spam? score=3.652 required=5 [20121115.025047] [1366] Nov 15 02:50:47.356 [1367] dbg: check: tests=MISSING_DATE,MISSING_HEADERS,NO_RECEIVED,NO_RELAYS [20121115.025047] [1366] Nov 15 02:50:47.356 [1367] dbg: check: subtests=__GATED_THROUGH_RCVD_REMOVER,__HAS_FROM,__HAS_MESSAGE_ID,__HAS_MSGI D,__HAS_SUBJECT,__MISSING_REF,__MISSING_REPLY,__MSGID_OK_DIGITS,__MSGID_OK_H OST,__MSOE_MID_WRONG_CASE,__NONEMPTY_BODY,__NOT_SPOOFED,__SANE_MSGID,__TO_NO _ARROWS_R,__UNUSABLE_MSGID [20121115.025047] [1366] Nov 15 02:50:47.357 [1367] dbg: timing: total 1085 ms - init: 824 (76.0%), parse: 0.89 (0.1%), extract_message_metadata: 0.95 (0.1%), get_uri_detail_list: 0.71 (0.1%), tests_pri_-1000: 6 (0.6%), compile_gen: 89 (8.2%), compile_eval: 12 (1.1%), tests_pri_-950: 4 (0.4%), tests_pri_-900: 5 (0.4%), tests_pri_-400: 4 (0.4%), tests_pri_0: 186 (17.2%), tests_pri_500: 46 (4.2%), tests_pri_1000: 4 (0.4%) [20121115.025047] [1366] Nov 15 02:50:47.357 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0xaafbe7c) implements 'finish_tests', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.357 [1367] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0xaafc17c) implements 'finish_tests', priority 0 [20121115.025047] [1366] Nov 15 02:50:47.371 [1367] dbg: channel: lint check succeeded, extracting archive to /var/lib/spamassassin/3.003001/updates_spamassassin_org... [20121115.025047] [1366] Nov 15 02:50:47.371 [1367] dbg: channel: point of no return for existing /var/lib/spamassassin/3.003001/updates_spamassassin_org [20121115.025047] [1366] Nov 15 02:50:47.374 [1367] dbg: generic: unlinking 20_vbounce.cf [20121115.025047] [1366] Nov 15 02:50:47.374 [1367] dbg: generic: unlinking 20_html_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.374 [1367] dbg: generic: unlinking 25_spf.cf [20121115.025047] [1366] Nov 15 02:50:47.374 [1367] dbg: generic: unlinking 20_advance_fee.cf [20121115.025047] [1366] Nov 15 02:50:47.374 [1367] dbg: generic: unlinking 25_replace.cf [20121115.025047] [1366] Nov 15 02:50:47.374 [1367] dbg: generic: unlinking 20_freemail_domains.cf [20121115.025047] [1366] Nov 15 02:50:47.374 [1367] dbg: generic: unlinking 25_asn.cf [20121115.025047] [1366] Nov 15 02:50:47.374 [1367] dbg: generic: unlinking 20_porn.cf [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking 60_whitelist.cf [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking 72_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking 25_razor2.cf [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking 60_shortcircuit.cf [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking user_prefs.template [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking 50_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking 30_text_fr.cf [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking 30_text_pt_br.cf [20121115.025047] [1366] Nov 15 02:50:47.375 [1367] dbg: generic: unlinking 20_compensate.cf [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking 23_bayes.cf [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking 20_dynrdns.cf [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking 25_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking MIRRORED.BY [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking sa-update-pubkey.txt [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking 10_hasbase.cf [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking 20_net_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking 10_default_prefs.cf [20121115.025047] [1366] Nov 15 02:50:47.376 [1367] dbg: generic: unlinking 30_text_de.cf [20121115.025047] [1366] Nov 15 02:50:47.377 [1367] dbg: generic: unlinking 30_text_it.cf [20121115.025047] [1366] Nov 15 02:50:47.377 [1367] dbg: generic: unlinking 20_freemail.cf [20121115.025047] [1366] Nov 15 02:50:47.377 [1367] dbg: generic: unlinking 20_uri_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.377 [1367] dbg: generic: unlinking local.cf [20121115.025047] [1366] Nov 15 02:50:47.377 [1367] dbg: generic: unlinking 25_antivirus.cf [20121115.025047] [1366] Nov 15 02:50:47.377 [1367] dbg: generic: unlinking languages [20121115.025047] [1366] Nov 15 02:50:47.383 [1367] dbg: generic: unlinking 20_imageinfo.cf [20121115.025047] [1366] Nov 15 02:50:47.383 [1367] dbg: generic: unlinking 73_sandbox_manual_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.383 [1367] dbg: generic: unlinking 25_dcc.cf [20121115.025047] [1366] Nov 15 02:50:47.383 [1367] dbg: generic: unlinking 25_pyzor.cf [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking STATISTICS-set3-72_scores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking 25_uribl.cf [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking STATISTICS-set0-72_scores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking 20_mailspike.cf [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking 20_drugs.cf [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking 20_phrases.cf [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking 60_adsp_override_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking STATISTICS-set2-72_scores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.384 [1367] dbg: generic: unlinking 20_body_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.385 [1367] dbg: generic: unlinking 30_text_pl.cf [20121115.025047] [1366] Nov 15 02:50:47.385 [1367] dbg: generic: unlinking STATISTICS-set1-72_scores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.385 [1367] dbg: generic: unlinking 60_whitelist_spf.cf [20121115.025047] [1366] Nov 15 02:50:47.385 [1367] dbg: generic: unlinking 60_whitelist_subject.cf [20121115.025047] [1366] Nov 15 02:50:47.385 [1367] dbg: generic: unlinking 72_active.cf [20121115.025047] [1366] Nov 15 02:50:47.385 [1367] dbg: generic: unlinking 20_aux_tlds.cf [20121115.025047] [1366] Nov 15 02:50:47.385 [1367] dbg: generic: unlinking 30_text_nl.cf [20121115.025047] [1366] Nov 15 02:50:47.385 [1367] dbg: generic: unlinking 60_awl.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 20_ratware.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 20_head_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 25_textcat.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 25_accessdb.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 20_fake_helo_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking regression_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 25_hashcash.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 20_meta_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 60_whitelist_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.386 [1367] dbg: generic: unlinking 20_dnsbl_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.439 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf [20121115.025047] [1366] Nov 15 02:50:47.439 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf [20121115.025047] [1366] Nov 15 02:50:47.439 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf [20121115.025047] [1366] Nov 15 02:50:47.439 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf [20121115.025047] [1366] Nov 15 02:50:47.439 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.440 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf [20121115.025047] [1366] Nov 15 02:50:47.440 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.440 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf [20121115.025047] [1366] Nov 15 02:50:47.441 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf [20121115.025047] [1366] Nov 15 02:50:47.441 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.c f [20121115.025047] [1366] Nov 15 02:50:47.441 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf [20121115.025047] [1366] Nov 15 02:50:47.441 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains. cf [20121115.025047] [1366] Nov 15 02:50:47.442 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.442 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.442 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf [20121115.025047] [1366] Nov 15 02:50:47.443 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf [20121115.025047] [1366] Nov 15 02:50:47.443 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.443 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.443 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf [20121115.025047] [1366] Nov 15 02:50:47.444 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf [20121115.025047] [1366] Nov 15 02:50:47.444 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf [20121115.025047] [1366] Nov 15 02:50:47.444 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.445 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf [20121115.025047] [1366] Nov 15 02:50:47.445 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf [20121115.025047] [1366] Nov 15 02:50:47.445 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf [20121115.025047] [1366] Nov 15 02:50:47.446 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf [20121115.025047] [1366] Nov 15 02:50:47.446 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf [20121115.025047] [1366] Nov 15 02:50:47.446 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf [20121115.025047] [1366] Nov 15 02:50:47.446 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.447 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf [20121115.025047] [1366] Nov 15 02:50:47.447 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf [20121115.025047] [1366] Nov 15 02:50:47.447 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf [20121115.025047] [1366] Nov 15 02:50:47.448 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf [20121115.025047] [1366] Nov 15 02:50:47.448 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf [20121115.025047] [1366] Nov 15 02:50:47.448 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf [20121115.025047] [1366] Nov 15 02:50:47.449 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf [20121115.025047] [1366] Nov 15 02:50:47.449 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf [20121115.025047] [1366] Nov 15 02:50:47.450 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf [20121115.025047] [1366] Nov 15 02:50:47.450 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf [20121115.025047] [1366] Nov 15 02:50:47.450 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf [20121115.025047] [1366] Nov 15 02:50:47.451 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf [20121115.025047] [1366] Nov 15 02:50:47.451 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf [20121115.025047] [1366] Nov 15 02:50:47.452 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.453 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dki m.cf [20121115.025047] [1366] Nov 15 02:50:47.453 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf [20121115.025047] [1366] Nov 15 02:50:47.453 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf [20121115.025047] [1366] Nov 15 02:50:47.454 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf [20121115.025047] [1366] Nov 15 02:50:47.454 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.455 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf [20121115.025047] [1366] Nov 15 02:50:47.455 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject .cf [20121115.025047] [1366] Nov 15 02:50:47.455 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf [20121115.025047] [1366] Nov 15 02:50:47.458 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.458 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_sc ores.cf [20121115.025047] [1366] Nov 15 02:50:47.459 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf [20121115.025047] [1366] Nov 15 02:50:47.459 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.460 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/STATISTICS-set0-72_s cores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.460 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/STATISTICS-set1-72_s cores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.460 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/STATISTICS-set2-72_s cores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.461 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/STATISTICS-set3-72_s cores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.461 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/sa-update-pubkey.txt [20121115.025047] [1366] Nov 15 02:50:47.462 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/languages [20121115.025047] [1366] Nov 15 02:50:47.462 [1367] dbg: extracting: /var/lib/spamassassin/3.003001/updates_spamassassin_org/user_prefs.template [20121115.025047] [1366] Nov 15 02:50:47.463 [1367] dbg: channel: creating MIRRORED.BY file [20121115.025047] [1366] Nov 15 02:50:47.463 [1367] dbg: channel: creating update cf/pre files [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 10_default_prefs.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 10_hasbase.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_advance_fee.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_aux_tlds.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_body_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_compensate.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_dnsbl_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_drugs.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_dynrdns.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_fake_helo_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_freemail.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_freemail_domains.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_head_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_html_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_imageinfo.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_mailspike.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_meta_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_net_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_phrases.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_porn.cf [20121115.025047] [1366] Nov 15 02:50:47.465 [1367] dbg: channel: adding 20_ratware.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 20_uri_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 20_vbounce.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 23_bayes.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_accessdb.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_antivirus.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_asn.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_dcc.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_hashcash.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_pyzor.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_razor2.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_replace.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_spf.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_textcat.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 25_uribl.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 30_text_de.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 30_text_fr.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 30_text_it.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 30_text_nl.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 30_text_pl.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 30_text_pt_br.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 50_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 60_adsp_override_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 60_awl.cf [20121115.025047] [1366] Nov 15 02:50:47.466 [1367] dbg: channel: adding 60_shortcircuit.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding 60_whitelist.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding 60_whitelist_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding 60_whitelist_spf.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding 60_whitelist_subject.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding 72_active.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding 72_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding 73_sandbox_manual_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding local.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: adding regression_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: channel: update complete [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: generic: cleaning up temporary directory/files [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: generic: unlinking 20_vbounce.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: generic: unlinking 20_html_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.467 [1367] dbg: generic: unlinking 25_spf.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 20_advance_fee.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 25_replace.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 20_freemail_domains.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 25_asn.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 20_porn.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 60_whitelist.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 72_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 25_razor2.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking 60_shortcircuit.cf [20121115.025047] [1366] Nov 15 02:50:47.468 [1367] dbg: generic: unlinking user_prefs.template [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 50_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 30_text_fr.cf [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 30_text_pt_br.cf [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 20_compensate.cf [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 23_bayes.cf [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 20_dynrdns.cf [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 25_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking sa-update-pubkey.txt [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 10_hasbase.cf [20121115.025047] [1366] Nov 15 02:50:47.469 [1367] dbg: generic: unlinking 20_net_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking 10_default_prefs.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking 30_text_de.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking 30_text_it.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking 20_freemail.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking 20_uri_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking local.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking 25_antivirus.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking languages [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking 20_imageinfo.cf [20121115.025047] [1366] Nov 15 02:50:47.470 [1367] dbg: generic: unlinking 73_sandbox_manual_scores.cf [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking 25_dcc.cf [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking 25_pyzor.cf [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking STATISTICS-set3-72_scores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking 25_uribl.cf [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking STATISTICS-set0-72_scores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking 20_mailspike.cf [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking 20_drugs.cf [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking 20_phrases.cf [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking 60_adsp_override_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking STATISTICS-set2-72_scores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.471 [1367] dbg: generic: unlinking 20_body_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking 30_text_pl.cf [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking STATISTICS-set1-72_scores.cf.txt [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking 60_whitelist_spf.cf [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking 60_whitelist_subject.cf [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking 72_active.cf [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking 20_aux_tlds.cf [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking 30_text_nl.cf [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking 60_awl.cf [20121115.025047] [1366] Nov 15 02:50:47.472 [1367] dbg: generic: unlinking 20_ratware.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking 20_head_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking 25_textcat.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking 25_accessdb.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking 20_fake_helo_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking regression_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking 25_hashcash.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking 20_meta_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking 60_whitelist_dkim.cf [20121115.025047] [1366] Nov 15 02:50:47.473 [1367] dbg: generic: unlinking 20_dnsbl_tests.cf [20121115.025047] [1366] Nov 15 02:50:47.474 [1367] dbg: diag: updates complete, exiting with code 0 [20121115.025047] [1366] Done [20121115.025050] [1366] Checking update....update ok! [20121115.025050] Processing: Restoring compiler permissions [20121115.025050] - Processing command `/usr/local/cpanel/scripts/compilers restore` [20121115.025050] [1399] Compilers are disabled for unprivileged users. [20121115.025050] Processing: Repairing MySQL [20121115.025050] - Processing command `/usr/local/cpanel/scripts/fixmysql` [20121115.025050] Processing: Updating MySQL Server [20121115.025050] - Processing command `/usr/local/cpanel/scripts/mysqlup` [20121115.025050] [1403] MySQL-server-5.0.96-0.cp1130.glibc23 is already installed. Use --force to reinstall. [20121115.025050] Processing: Updating SMTP (EXIM) Server [20121115.025050] - Processing command `/usr/local/cpanel/scripts/eximup` [20121115.025050] [1405] exim-4.80-3 is already installed. Use --force to reinstall. [20121115.025050] Processing: Cleaning SpamAssassin DBM files [20121115.025050] - Processing command `/usr/local/cpanel/scripts/spamassassin_dbm_cleaner` [20121115.025050] [1412] /usr/local/cpanel/scripts/spamassassin_dbm_cleaner: version 1.0 [20121115.025050] [1412] Checking SpamAssassin dbm databases....Done [20121115.025050] [1412] Checked 1 files for 1 user(s), 1 ok, 0 broken [20121115.025050] Processing: Configuring Frontpage [20121115.025050] - Processing command `/usr/local/cpanel/scripts/initfpsuexec` [20121115.025050] [1414] initfpsuexec: using apache 1.x support [20121115.025050] Cleaning Squirrelmail attachment directories [20121115.025050] Processing: Running former postinstall scripts [20121115.025050] - Processing command `/usr/local/cpanel/bin/dcpumon --killproc` [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/BitchX.sym ..Done [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/bnc.sym ..Done [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/eggdrop.sym ..Done [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/generic-sniffers.sym ..Done [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/guardservices.sym ..Done [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/ircd.sym ..Done [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/psyBNC.sym ..Done [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/ptlink.sym ..Done [20121115.025050] [1427] Loading Symbol Table... /usr/local/cpanel/etc/sym/services.sym ..Done [20121115.025050] [1427] Kill Proc Enabled [20121115.025050] [1427] Added Contact beth@1xmarketinggroup.com [20121115.025050] - Processing command `/usr/local/cpanel/bin/scanfornewmail` [20121115.025051] [1430] Scanning for new mail senders.....Done [20121115.025051] - Processing command `/usr/local/cpanel/scripts/fixsuexeccgiscripts` [20121115.025051] [1431] Scanning suexec_log...Done [20121115.025051] - Processing command `/usr/local/cpanel/bin/setupdbmap` [20121115.025051] - Processing command `/usr/local/cpanel/bin/fix_userdata_perms` [20121115.025051] - Processing command `/usr/local/cpanel/scripts/detect_env_capabilities` [20121115.025051] [1435] ioprio_set: Operation not permitted [20121115.025051] [20121115.025051] Maintenance complete. => Log closed Thu Nov 15 02:50:51 2012 ---------------------------------------------------------------------------- ------------------------ => Log opened from cPanel Update (upcp) - Slave at Thu Nov 15 02:50:51 2012 [20121115.025051] Maintenance completed successfully [20121115.025052] Running Standardized hooks [20121115.025052] [20121115.025052] cPanel update completed [20121115.025052] A log of this update is available at /var/cpanel/updatelogs/update.1352969403.log [20121115.025052] Removing upcp pidfile [20121115.025052] [20121115.025052] Completed all updates => Log closed Thu Nov 15 02:50:52 2012