Long delay and no "Spam Checks completed in xxx seconds"

[Ugo Bellavance]

Hi,

	Symptom: Batch took 612s to process (30 messages).  This system usually 
processes that in around 200s.  This batch should have been processed in 
less than 20s because all of the messages hit the SA cache.  Virus 
scanning took about 7 secs.

	Observation: There is a 10-minute gap between the log of the last log 
entry of spam checks and the start of virus checks.  Also, there is no 
log entry saying "Spam Checks completed in xxx seconds".

Other relevant log entries:

Oct 22 17:23:37 server MailScanner[9558]: Virus and Content Scanning: 
Starting
Oct 22 17:23:45 server MailScanner[9558]: Virus Scanning completed at 
2945 bytes per second
Oct 22 17:23:46 server MailScanner[9558]: Uninfected: Delivered 30 messages
Oct 22 17:23:46 server MailScanner[9558]: Batch completed at 2943 bytes 
per second (1803120 / 612)
Oct 22 17:23:46 server MailScanner[9558]: Batch (30 messages) processed 
in 612.64 seconds

Have a look at the speed of virus scanning.  It took 8 seconds to be 
done, but if you compute 2945 1803120 / 2945, you also get 612s.

This is CentOS release 4.7 (Final)
This is Perl version 5.008005 (5.8.5)

This is MailScanner version 4.71.10
Module versions are:
1.00    AnyDBM_File
1.26    Archive::Zip
0.21    bignum
1.03    Carp
2.008   Compress::Zlib
1.119   Convert::BinHex
0.17    Convert::TNEF
2.121   Data::Dumper
2.27    Date::Parse
1.00    DirHandle
1.05    Fcntl
2.73    File::Basename
2.08    File::Copy
2.01    FileHandle
1.06    File::Path
0.20    File::Temp
0.92    Filesys::Df
1.35    HTML::Entities
3.56    HTML::Parser
2.37    HTML::TokeParser
1.23    IO
1.14    IO::File
1.13    IO::Pipe
2.02    Mail::Header
1.86    Math::BigInt
0.19    Math::BigRat
3.05    MIME::Base64
5.425   MIME::Decoder
5.425   MIME::Decoder::UU
5.425   MIME::Head
5.425   MIME::Parser
3.03    MIME::QuotedPrint
5.425   MIME::Tools
0.11    Net::CIDR
1.25    Net::IP
0.16    OLE::Storage_Lite
1.04    Pod::Escapes
3.05    Pod::Simple
1.08    POSIX
1.14    Scalar::Util
1.77    Socket
2.13    Storable
1.4     Sys::Hostname::Long
0.18    Sys::Syslog
missing Test::Pod
0.7     Test::Simple
1.9711  Time::HiRes
1.02    Time::localtime

Optional module versions are:
1.36    Archive::Tar
0.21    bignum
missing Business::ISBN
missing Business::ISBN::Data
missing Data::Dump
1.809   DB_File
1.14    DBD::SQLite
1.601   DBI
1.08    Digest
1.01    Digest::HMAC
2.33    Digest::MD5
2.11    Digest::SHA1
1.01    Encode::Detect
0.17015 Error
0.24    ExtUtils::CBuilder
2.19    ExtUtils::ParseXS
2.36    Getopt::Long
0.44    Inline
1.08    IO::String
1.09    IO::Zlib
2.25    IP::Country
missing Mail::ClamAV
3.002005        Mail::SpamAssassin
v2.006  Mail::SPF
1.999001        Mail::SPF::Query
0.2808  Module::Build
0.20    Net::CIDR::Lite
0.63    Net::DNS
missing Net::DNS::Resolver::Programmable
0.34    Net::LDAP
  4.007  NetAddr::IP
1.94    Parse::RecDescent
missing SAVI
2.42    Test::Harness
1.22    Test::Manifest
1.95    Text::Balanced
1.30    URI
0.74    version
missing YAML

Thanks,

Ugo