Message marked with SpamAssassin timeout when it should't

[Mariano Absatz]

El 12 Apr 2004 a las 11:15, Ugo Bellavance escribió:

> >4) the test message arrives, but it has a 'SpamAssassin (timed 
> >out)' in the X-
> >MailScanner-SpamAssassin: header...
> 

> Maybe spamassassin got time to process text-based tests but timed out on
> RBL checks and that SA has the default behavior of setting a message wher
> SA timed out as non-spam? 

Yes, but that was not the case, as the following line (logged by MailScanner 
AFTER getting SpamAssassin results) implies, it DID get the SpamAssassin info 
(no RBL test yelded positive, since I sent this legitimately from mail.com).

> >Apr 12 11:26:51 dedos MailScanner[2695]: Message 141779 from 205.158.62.67 (el_baby at mail.com) to baby.com.ar is spam, Alerce-SpamAnalista-2.63 (puntaje=10.004, requerido 5, DEJAR_DE_FUMAR_ES 1.00, EXCLAMACION_ES 1.00, EXCUSE_ES_02 1.00, EXCUSE_ES_06 1.00, MANY_EXCLAMATIONS 0.39, NO_REAL_NAME 0.28, OFERTA_ES 1.00, PERT_OFERTA_ES 2.00, PLING_PLING 1.32, REMOVE_ES_06 1.00) 

And the following lines also refer to this same message saying that the 
actions ARE 'attachment,deliver', however, that (attachment) did not happen.

> >Apr 12 11:28:53 dedos MailScanner[2695]: Spam Checks: Found 1 spam messages
> >Apr 12 11:28:53 dedos MailScanner[2695]: Spam Actions: message 141779 actions are attachment,deliver


> 
> >
> >Here's the log corresponding to that batch:
> >
> >Apr 12 11:26:00 dedos MailScanner[2695]: New Batch: Found 17 messages waiting
> >Apr 12 11:26:00 dedos MailScanner[2695]: New Batch: Forwarding 1 unscanned messages, 8711 bytes
> >Apr 12 11:26:00 dedos MailScanner[2695]: New Batch: Scanning 3 messages, 5699 bytes
> >Apr 12 11:26:00 dedos MailScanner[2695]: MCP Checks completed at 14410 bytes per second
> >Apr 12 11:26:00 dedos MailScanner[2695]: Spam Checks: Starting
> >Apr 12 11:26:51 dedos MailScanner[2695]: Message 141779 from 205.158.62.67 (el_baby at mail.com) to baby.com.ar is spam, Alerce-SpamAnalista-2.63 (puntaje=10.004, requerido 5, DEJAR_DE_FUMAR_ES 1.00, EXCLAMACION_ES 1.00, EXCUSE_ES_02 1.00, EXCUSE_ES_06 1.00, MANY_EXCLAMATIONS 0.39, NO_REAL_NAME 0.28, OFERTA_ES 1.00, PERT_OFERTA_ES 2.00, PLING_PLING 1.32, REMOVE_ES_06 1.00)
> >Apr 12 11:27:51 dedos MailScanner[2695]: SpamAssassin timed out and was killed, consecutive failure 1 of 20
> >Apr 12 11:28:52 dedos MailScanner[2695]: SpamAssassin timed out and was killed, consecutive failure 2 of 20
> >Apr 12 11:28:53 dedos MailScanner[2695]: Spam Checks: Found 1 spam messages Apr 12 11:28:53 dedos MailScanner[2695]: Spam Actions: message 141779 actions are attachment,deliver
> >Apr 12 11:28:54 dedos MailScanner[2695]: Spam Checks completed at 83 bytes per second
> >Apr 12 11:28:54 dedos MailScanner[2695]: ZM: message 141783 renamed into 215984
> >Apr 12 11:28:54 dedos MailScanner[2695]: Unscanned: Delivered 1 messages
> >Apr 12 11:28:58 dedos MailScanner[2695]: Virus and Content Scanning: Starting 
> >Apr 12 11:30:32 dedos MailScanner[2695]: Filename Checks: Allowing 141779 msg-2695-2.txt
> >Apr 12 11:30:32 dedos MailScanner[2695]: Filename Checks: Allowing 141732 msg-2695-3.txt
> >Apr 12 11:30:33 dedos MailScanner[2695]: Filename Checks: Allowing 141782 msg-2695-4.txt
> >Apr 12 11:30:33 dedos MailScanner[2695]: Virus Scanning completed at 145 bytes per second
> >Apr 12 11:30:35 dedos MailScanner[2695]: ZM: message 141779 renamed into 215984
> >Apr 12 11:30:35 dedos MailScanner[2695]: ZM: message 141732 renamed into 216319
> >Apr 12 11:30:35 dedos MailScanner[2695]: ZM: message 141782 renamed into 216324
> >Apr 12 11:30:35 dedos MailScanner[2695]: Uninfected: Delivered 3 messages
> >Apr 12 11:30:35 dedos MailScanner[2695]: Virus Processing completed at 7205 bytes per second
> >Apr 12 11:30:35 dedos MailScanner[2695]: Disinfection completed at 14410 bytes per second
> >Apr 12 11:30:35 dedos MailScanner[2695]: Batch completed at 52 bytes per second (14410 / 275)
> >Apr 12 11:30:40 dedos MailScanner[2695]: New Batch: Found 29 messages waiting
> >Apr 12 11:30:40 dedos MailScanner[2695]: New Batch: Forwarding 26 unscanned messages, 499377 bytes
> >Apr 12 11:30:40 dedos MailScanner[2695]: New Batch: Scanning 2 messages, 38091 bytes
> >Apr 12 11:30:40 dedos MailScanner[2695]: MCP Checks completed at 537468 bytes per second
> >Apr 12 11:30:40 dedos MailScanner[2695]: Spam Checks: Starting
> >
> >
> >
> >The headers of the identified spam message follow:
> >
> >X-Alerce-Informacion: Contactarse con su proveedor para mas informacion
> >X-Alerce: Se encontro limpio
> >X-Alerce-SpamAnalisis: no es spam, Alerce-SpamAnalista-2.63 (timed out)
> >X-Alerce-From: el_baby at mail.com
> >Return-Path: <el_baby at mail.com>
> >
> >
> >I _KNOW_ this is the message (el_baby at mail.com is me) so 
> >there's no mistake
> >in identified the message in question...
> >
> >What could have happened?
> >
> >TIA.

--
Mariano Absatz
El Baby
----------------------------------------------------------
Who is General Failure and why is he reading my disk?