Fw: MailScanner 4.67.6 - Double processing of message and then Unlink error

Steve' Mailing List list at torpey.org
Sat Mar 8 00:34:44 GMT 2008


I have been having double processing of a message and then an unlinking
error from the second child.  Since my server has very very low volume I
 just set the children to 1.

 Basically the problem, as I see it, is that while Child 1 is processing the
message, Child 2 starts processing.  After Child 1 has decided what to do
with the message and removes the message from mqueue.in, then Child 2 has
finished deciding what to do and then can not find the message to take
action.  I think this is close the "duplicate message" problems, but since
my volume is minimal, Child 1 can remove the message before Child 2 takes
action.

 I upgraded to 4.67.6 and set children to 5 and have the same problem.

 Log output, mailscanner starting:
 ---------------------------------------
 Feb 16 07:06:54 firewall MailScanner[29990]: MailScanner E-Mail Virus
Scanner version 4.67.4 starting...
 Feb 16 07:06:54 firewall MailScanner[29990]: Read 817 hostnames from the
phishing whitelist
 Feb 16 07:06:54 firewall MailScanner[29990]: Read 5198 hostnames from the
phishing blacklist
 Feb 16 07:06:54 firewall MailScanner[29990]: Config: calling custom init
function MailWatchLogging
 Feb 16 07:06:54 firewall MailScanner[29990]: Started SQL Logging child
 Feb 16 07:06:54 firewall MailScanner[29990]: SpamAssassin temporary working
directory is /var/spool/MailScanner/incoming/SpamAssassin-Temp
 Feb 16 07:06:54 firewall MailScanner[29990]: Using SpamAssassin results
cache
 Feb 16 07:06:54 firewall MailScanner[29990]: Connected to SpamAssassin
cache database
 Feb 16 07:06:55 firewall MailScanner[29990]: Expired 4 records from the
SpamAssassin cache
 Feb 16 07:06:55 firewall MailScanner[29990]: Enabling SpamAssassin
auto-whitelist functionality...
 Feb 16 07:07:01 firewall MailScanner[29990]: Using locktype = posix
 Feb 16 07:07:01 firewall MailScanner[29990]: Creating hardcoded
struct_flock subroutine for linux (Linux-type)
 --------------------------------------

 Maillog from Child 1 [21738] and Child 2 [21734] processing the same
message:

 -------------------------------------
 Feb 15 21:41:09 firewall sendmail[23711]: m1G3f90I023711:
from=<TimeforaRaise at pomegranatethistle.com>, size=10010, class=0, nrcpts=1,
msgid=<47b62323ka5bbd-qwki6wp5l at pomegranatethistle.com>, proto=SMTP,
daemon=MTA, relay=pomegranatethistle.com [209.58.38.171]
 Feb 15 21:41:09 firewall sendmail[23711]: m1G3f90I023711:
to=<xxxxx at torpey.org>, delay=00:00:00, mailer=esmtp, pri=40010, stat=queued
Feb 15 21:41:09 firewall MailScanner[21738]: New Batch: Scanning 1 messages,
10516 bytes
 Feb 15 21:41:09 firewall MailScanner[21738]: Spam Checks: Starting
 Feb 15 21:41:09 firewall MailScanner[21738]: Expired 1 records from the
SpamAssassin cache
 Feb 15 21:41:11 firewall sendmail[23705]: m1G3e9Bi023705:
from=<1-6096106-?jan at mx1.twotipperty.com>, size=0, class=0, nrcpts=0,
proto=SMTP, daemon=MTA, relay=mx1.twotipperty.com [66.160.171.202]
 Feb 15 21:41:29 firewall MailScanner[21738]: Message m1G3f90I023711 from
209.58.38.171 (timeforaraise at pomegranatethistle.com) to torpey.org is spam,
SpamAssassin (not cached, score=21.098, required 4.9, autolearn=spam,
BAYES_50 0.00, FUZZY_MILLION 2.53, HTML_IMAGE_RATIO_04 0.17, HTML_MESSAGE
0.00, MIME_QP_LONG_LINE 1.40, RAZOR2_CF_RANGE_51_100 0.50,
RAZOR2_CF_RANGE_E4_51_100 1.50, RAZOR2_CF_RANGE_E8_51_100 1.50, AZOR2_CHECK
0.50, RCVD_IN_BLCSMA_spamikaze 3.00, RCVD_IN_SBLCSMA_spamikaze 2.00,
SPF_HELO_PASS -0.00, SPF_PASS -0.00, URIBL_BLACK 3.00, URIBL_JP_SURBL 1.50,
URIBL_OB_SURBL 1.50, to_subscriber 2.00)
 Feb 15 21:41:29 firewall MailScanner[21738]: Spam Checks: Found 1 spam
messages
 Feb 15 21:41:29 firewall MailScanner[21738]: Spam Actions: message
m1G3f90I023711 actions are store,spamtrap at firewall,forward
 Feb 15 21:41:30 firewall MailScanner[21738]: Virus and Content Scanning:
Starting
 Feb 15 21:41:30 firewall MailScanner[21734]: New Batch: Scanning 1
messages, 10516 bytes
 Feb 15 21:41:30 firewall MailScanner[21734]: Spam Checks: Starting
 Feb 15 21:41:31 firewall MailScanner[21738]: Uninfected: Delivered 1
messages
 Feb 15 21:41:31 firewall MailScanner[21738]: Logging message m1G3f90I023711
to SQL Feb 15 21:41:31 firewall MailScanner[21732]: m1G3f90I023711: Logged
to
 MailWatch SQL Feb 15 21:41:33 firewall sendmail[23723]: m1G3f90I023711:
 to=|/opt/spamikaze/scripts/passivetrap.pl, ctladdr=<spamtrap at firewall>
(8/0), delay=00:00:24, xdelay=00:00:02, mailer=prog, pri=130010, dsn=2.0.0,
stat=Sent
 Feb 15 21:41:41 firewall MailScanner[21734]: SpamAssassin cache hit for
message m1G3f90I023711
 Feb 15 21:41:41 firewall MailScanner[21734]: Message m1G3f90I023711 from
209.58.38.171 (timeforaraise at pomegranatethistle.com) to torpey.org is spam,
SpamAssassin (cached, score=21.098, required 4.9, autolearn=spam, BAYES_50
0.00, FUZZY_MILLION 2.53, HTML_IMAGE_RATIO_04 0.17, HTML_MESSAGE 0.00,
MIME_QP_LONG_LINE 1.40, RAZOR2_CF_RANGE_51_100 0.50,
RAZOR2_CF_RANGE_E4_51_100 1.50, RAZOR2_CF_RANGE_E8_51_100 1.50, AZOR2_CHECK
0.50, RCVD_IN_BLCSMA_spamikaze 3.00, RCVD_IN_SBLCSMA_spamikaze 2.00,
SPF_HELO_PASS -0.00, SPF_PASS -0.00, RIBL_BLACK 3.00, URIBL_JP_SURBL 1.50,
URIBL_OB_SURBL 1.50, to_subscriber 2.00)
 Feb 15 21:41:41 firewall MailScanner[21734]: Spam Checks: Found 1 spam
messages
 Feb 15 21:41:41 firewall MailScanner[21734]: Spam Actions: message
m1G3f90I023711 actions are store,spamtrap at firewall,forward
 Feb 15 21:41:42 firewall MailScanner[21734]: Virus and Content Scanning:
Starting
 Feb 15 21:41:42 firewall MailScanner[21734]: Unlinking
/var/spool/mqueue.in/qfm1G3f90I023711 failed: No such file or directory
 Feb 15 21:41:42 firewall MailScanner[21734]: Unlinking
/var/spool/mqueue.in/dfm1G3f90I023711 failed: No such file or directory
 Feb 15 21:41:42 firewall MailScanner[21734]: Unlinking
/var/spool/mqueue.in/qfm1G3f90I023711 failed: No such file or directory
 Feb 15 21:41:42 firewall MailScanner[21734]: Unlinking
/var/spool/mqueue.in/dfm1G3f90I023711 failed: No such file or directory
 Feb 15 21:41:42 firewall MailScanner[21734]: Uninfected: Delivered 1
messages
 Feb 15 21:41:42 firewall MailScanner[21734]: Logging message m1G3f90I023711
to SQL
 Feb 15 21:41:42 firewall MailScanner[21732]: m1G3f90I023711: Logged to
MailWatch SQL
 Feb 15 21:41:42 firewall sendmail[23733]: m1G3f90I023711: SYSERR(root):
readqf: cannot open ./dfm1G3f90I023711: No such file or directory
 Feb 15 21:41:44 firewall sendmail[23733]: m1G3f90I023711:
to=|/opt/spamikaze/scripts/passivetrap.pl, ctladdr=<spamtrap at firewall>
(8/0), delay=00:00:35, xdelay=00:00:02, mailer=prog, pri=130010, dsn=2.0.0,
stat=Sent
 -----------------------------------------

 Even SpamAssassin realizes it is the same message and indicates "cache hit"



I think the issue is the following phrase when MailScanner is starting:

"Creating hardcoded struct_flock subroutine for linux (Linux-type)"

 Details
 MailScanner 4.67.6
 OS is equivalent to RHEL3 - Whitebox EL3
 Sendmail 8.12.11

 What other information can I provide?
 Any suggestions?

 Thanks,
 Steve




More information about the MailScanner mailing list