Something strange with MS and Exim

[Jan-Peter Koopmann]

Hi,

please have a look at this log:

Mar  6 09:13:13 proxy MailScanner[79130]: Enabling SpamAssassin
auto-whitelist functionality...
Mar  6 09:13:22 proxy MailScanner[79130]: Using locktype = posix
Mar  6 09:13:22 proxy MailScanner[79130]: Creating hardcoded
struct_flock subroutine for freebsd (BSD-type)
Mar  6 09:13:22 proxy MailScanner[79130]: New Batch: Found 11 messages
waiting
Mar  6 09:13:22 proxy MailScanner[79130]: New Batch: Scanning 3
messages, 27539 bytes
Mar  6 09:13:22 proxy MailScanner[79130]: Spam Checks: Starting
Mar  6 09:13:33 proxy MailScanner[79178]: MailScanner E-Mail Virus
Scanner version 4.13-3 starting...
Mar  6 09:13:34 proxy MailScanner[79178]: Enabling SpamAssassin
auto-whitelist functionality...
Mar  6 09:13:39 proxy MailScanner[79178]: Using locktype = posix
Mar  6 09:13:39 proxy MailScanner[79178]: Creating hardcoded
struct_flock subroutine for freebsd (BSD-type)
Mar  6 09:13:49 proxy MailScanner[79178]: New Batch: Found 15 messages
waiting
Mar  6 09:13:49 proxy MailScanner[79178]: New Batch: Scanning 1
messages, 1717 bytes
Mar  6 09:13:49 proxy MailScanner[79178]: Spam Checks: Starting
Mar  6 09:13:49 proxy MailScanner[79178]: Virus and Content Scanning:
Starting
Mar  6 09:13:51 proxy MailScanner[79178]: Uninfected: Delivered 1
messages
Mar  6 09:13:51 proxy MailScanner[79130]: RBL Check spamcop.net timed
out and was killed, consecutive failure 1 of 7
Mar  6 09:14:08 proxy MailScanner[79130]: Virus and Content Scanning:
Starting
Mar  6 09:14:11 proxy MailScanner[79130]: Uninfected: Delivered 3
messages
Mar  6 09:14:11 proxy MailScanner[79130]: New Batch: Scanning 1
messages, 2041 bytes
Mar  6 09:14:11 proxy MailScanner[79130]: Spam Checks: Starting
Mar  6 09:14:11 proxy MailScanner[79266]: MailScanner E-Mail Virus
Scanner version 4.13-3 starting...
Mar  6 09:14:15 proxy MailScanner[79130]: Virus and Content Scanning:
Starting
Mar  6 09:14:15 proxy MailScanner[79266]: Enabling SpamAssassin
auto-whitelist functionality...
Mar  6 09:14:18 proxy MailScanner[79130]: Uninfected: Delivered 1
messages
Mar  6 09:14:20 proxy MailScanner[79266]: Using locktype = posix
Mar  6 09:14:20 proxy MailScanner[79266]: Creating hardcoded
struct_flock subroutine for freebsd (BSD-type)
Mar  6 09:15:30 proxy MailScanner[79266]: New Batch: Scanning 1
messages, 1718 bytes
Mar  6 09:15:30 proxy MailScanner[79266]: Spam Checks: Starting
Mar  6 09:15:30 proxy MailScanner[79266]: Virus and Content Scanning:
Starting
Mar  6 09:15:32 proxy MailScanner[79266]: Uninfected: Delivered 1
messages
Mar  6 09:15:38 proxy MailScanner[79130]: New Batch: Scanning 1
messages, 2042 bytes
Mar  6 09:15:38 proxy MailScanner[79130]: Spam Checks: Starting
Mar  6 09:15:40 proxy MailScanner[79130]: Virus and Content Scanning:
Starting
Mar  6 09:15:41 proxy MailScanner[79130]: Uninfected: Delivered 1
messages
Mar  6 09:16:11 proxy MailScanner[79130]: New Batch: Scanning 7
messages, 42792 bytes
Mar  6 09:16:11 proxy MailScanner[79130]: Spam Checks: Starting
Mar  6 09:16:12 proxy MailScanner[79266]: New Batch: Found 9 messages
waiting
Mar  6 09:16:12 proxy MailScanner[79266]: New Batch: Scanning 2
messages, 10870 bytes
Mar  6 09:16:12 proxy MailScanner[79266]: Spam Checks: Starting
Mar  6 09:16:22 proxy MailScanner[79178]: New Batch: Found 43 messages
waiting
Mar  6 09:16:22 proxy MailScanner[79178]: New Batch: Scanning 10
messages, 52122 bytes
Mar  6 09:16:22 proxy MailScanner[79178]: Spam Checks: Starting
Mar  6 09:16:25 proxy MailScanner[79266]: Virus and Content Scanning:
Starting
Mar  6 09:16:30 proxy MailScanner[79266]: Uninfected: Delivered 2
messages
Mar  6 09:16:30 proxy MailScanner[79266]: New Batch: Found 18 messages
waiting
Mar  6 09:16:30 proxy MailScanner[79266]: New Batch: Scanning 1
messages, 5456 bytes
Mar  6 09:16:30 proxy MailScanner[79266]: Spam Checks: Starting
Mar  6 09:16:36 proxy MailScanner[79266]: Virus and Content Scanning:
Starting
Mar  6 09:16:39 proxy MailScanner[79266]: Uninfected: Delivered 1
messages
Mar  6 09:16:58 proxy MailScanner[79130]: Virus and Content Scanning:
Starting
Mar  6 09:17:02 proxy MailScanner[79130]: Uninfected: Delivered 7
messages


There seems to be a bug in the "New Batch: Found xx messages waiting"
routine. This keeps growing and growing even though there are not so
many messages in the inbound queue. When it says

Mar  6 09:16:30 proxy MailScanner[79266]: New Batch: Found 18 messages
waiting
Mar  6 09:16:30 proxy MailScanner[79266]: New Batch: Scanning 1
messages, 5456 bytes
Mar  6 09:16:30 proxy MailScanner[79266]: Spam Checks: Starting
Mar  6 09:16:36 proxy MailScanner[79266]: Virus and Content Scanning:
Starting
Mar  6 09:16:39 proxy MailScanner[79266]: Uninfected: Delivered 1
messages

There is really only 1 message in the queue at this time. I never
noticed this with sendmail, only with exim. Any explanations? All my
messages seem to come through btw. so this probably is not a
misconfiguration.

Regards,
  JP

PS: This is with 4.13-3, SA 2.50 and exim 4.12-6.