You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@spamassassin.apache.org by "George R. Kasica" <ge...@netwrx1.com> on 2006/01/10 01:36:05 UTC

SA 3.10 skipping some emails or errors in log??

First thanks for helping get the logging corrected and the daeomizing
set with -d.

Working on getting SA 3.1 working effectively here and I think I may
have some issues with it at this time in terms of it scanning all
incoming mail. 

I'm running exim 3.36 here and have 4.60 ready to go if I can get a
good SA 3.1 and Sophos ACL for it. My old SA 3.0.4 didn't seem to miss
anything or generate this type of error logging at least.

I'm watching my logs and it seems that spamd is having issues with
errors or possibly skipping messages.

Command line is:

/usr/local/bin/spamd -d -u spamd --m 10 --max-conn-per-child=50 

Log snippets etc. in attachment as the spam filter killed my first
attempt...and now my second as an attachment so I'll just include the
log here as the headers seenm to get clobberred.

If anyone wants to see them I can email them directly.

Jan  9 15:29:07 eagle spamd[8481]: spamd: processing message
<E1...@eagle.netwrx1.com> for mail:561 
Jan  9 15:29:07 eagle spamd[8311]: prefork: child states: BBB 
Jan  9 15:29:07 eagle spamd[8311]: spamd: server successfully spawned
child process, pid 8482 
Jan  9 15:29:07 eagle spamd[8311]: prefork: child states: BBBI 
Jan  9 15:29:07 eagle spamd[8420]: spamd: connection from localhost
[127.0.0.1] at port 37261 
Jan  9 15:29:08 eagle spamd[8420]: spamd: processing message
<43...@umassmed.edu> for mail:561 
Jan  9 15:29:10 eagle spamd[8311]: prefork: child states: IBBI 
Jan  9 15:29:14 eagle spamd[8419]: spamd: connection from localhost
[127.0.0.1] at port 37271 
Jan  9 15:29:15 eagle spamd[8419]: spamd: processing message
<00...@localhost> for mail:561 
Jan  9 15:30:06 eagle spamd[8481]: spamd: identified spam (30.6/5.0)
for mail:561 in 58.8 seconds, 805 bytes. 
Jan  9 15:30:06 eagle spamd[8481]: spamd: result: Y 30 -
FORGED_HOTMAIL_RCVD2,FUZZY_PRICES,HTML_MESSAGE,HTML_MIME_NO_HTML_TAG,MIME_HEADER_CTYPE_ONLY,MIME_HTML_ONLY,MSGID_FROM_MTA_ID,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E8_51_100,RAZOR2_CHECK,SARE_OBFU_ONLINE,SARE_OBFU_PART_ICE,SARE_OBFU_PART_ING,SARE_OBFU_PRICE2,URIBL_JP_SURBL,URIBL_OB_SURBL,URIBL_SC_SURBL,URIBL_WS_SURBL,WLS_URI_OPT_632
scantime=58.8,size=805,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=37266,mid=<E1...@eagle.netwrx1.com>,autolearn=disabled
Jan  9 15:30:42 eagle spamd[8420]: spamd: clean message (1.2/5.0) for
mail:561 in 87.4 seconds, 3750 bytes. 
Jan  9 15:30:42 eagle spamd[8420]: spamd: result: .  1 -
BLANK_LINES_70_80
scantime=87.4,size=3750,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=37261,mid=<43...@umassmed.edu>,autolearn=disabled
Jan  9 15:30:42 eagle spamd[8419]: spamd: identified spam (33.7/5.0)
for mail:561 in 86.8 seconds, 14732 bytes. 
Jan  9 15:30:43 eagle spamd[8419]: spamd: result: Y 33 -
DATE_IN_PAST_96_XX,DCC_CHECK,HTML_MESSAGE,HTML_TAG_EXIST_TBODY,RM_bpoem_InstantDL,SARE_OBFU_PART_ICE,SARE_OBFU_PRICE2,SARE_OEM_AND_OTHER,SARE_OEM_A_1,SARE_OEM_NEW_TITLES,SARE_OEM_PRODS_1,SARE_OEM_PRODS_FEW,SARE_OEM_S_PRICE,SARE_OEM_UPPER_EYE,SARE_PRODS_LOTS,SARE_PRODUCTS_02,SARE_PRODUCTS_03,SARE_PRODUCTS_04,URIBL_JP_SURBL,URIBL_SC_SURBL,URIBL_WS_SURBL,WLS_URI_OPT_2170
scantime=86.8,size=14732,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=37271,mid=<00...@localhost>,autolearn=disabled
Jan  9 15:30:43 eagle spamd[8311]: prefork: child states: BIBI 
Jan  9 15:30:44 eagle spamd[8311]: prefork: child states: IIBI 
Jan  9 15:30:44 eagle spamd[8311]: spamd: handled cleanup of child pid
8482 due to SIGCHLD 
Jan  9 15:30:44 eagle spamd[8311]: prefork: child states: IIB 
Jan  9 15:30:57 eagle spamd[8481]: __alarm__ 
Jan  9 15:30:57 eagle spamd[8481]: __alarm__ 
Jan  9 15:30:58 eagle spamd[8311]: prefork: child states: III 
Jan  9 15:30:59 eagle spamd[8311]: spamd: handled cleanup of child pid
8481 due to SIGCHLD 
Jan  9 15:30:59 eagle spamd[8311]: prefork: child states: IIK 
Jan  9 15:31:06 eagle spamd[8419]: spamd: connection from localhost
[127.0.0.1] at port 54409 
Jan  9 15:31:06 eagle spamd[8419]: spamd: processing message
<02...@sn024137420234> for mail:561 
Jan  9 15:31:07 eagle spamd[8420]: spamd: connection from localhost
[127.0.0.1] at port 54410 
Jan  9 15:31:07 eagle spamd[8420]: spamd: processing message
<00...@binc.net> for mail:561 
Jan  9 15:34:55 eagle spamd[8715]: __alarm__ 
Jan  9 15:35:01 eagle spamd[8715]: __alarm__ 
Jan  9 15:35:01 eagle spamd[8311]: prefork: child states: BBBIB 
Jan  9 15:35:02 eagle spamd[8719]: spamd: processing message
<20...@ms-smtp-03-eri0.texas.rr.com> for
mail:561 
Jan  9 15:35:12 eagle spamd[8311]: tcp timeout at
/usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
line 195. 
Jan  9 15:35:12 eagle spamd[8311]: tcp timeout at
/usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
line 195. 
Jan  9 15:35:12 eagle spamd[8311]: prefork: select returned undef!
recovering 
Jan  9 15:35:48 eagle spamd[8712]: spamd: clean message (0.0/5.0) for
mail:561 in 186.2 seconds, 14503 bytes. 
Jan  9 15:35:48 eagle spamd[8712]: spamd: result: .  0 - HTML_MESSAGE
scantime=186.2,size=14503,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=54421,mid=<HE...@animas.net>,autolearn=disabled


Mail headers of what I would think would be spam but doesn't appear to
get tested or flagged:




===[George R. Kasica]===        +1 262 677 0766
President                       +1 206 374 6482 FAX 
Netwrx Consulting Inc.          Jackson, WI USA 
http://www.netwrx1.com
georgek@netwrx1.com
ICQ #12862186

Re: SA 3.10 skipping some emails or errors in log??

Posted by "George R. Kasica" <ge...@netwrx1.com>.
>Please see, and comment on, bug 4696:
>
>http://issues.apache.org/SpamAssassin/show_bug.cgi?id=4696
>

Not sure if my comments made it to that so here they are:

Similar problem here with it dying at random during the day. No
recycling of logs or anything here. Linux kernel 2.6.12.2 with SA 3.1
and Exim 3.36

See below log piece I captured from a start at 18:25 until it died on
its own at 1853

Jan  9 18:22:45 eagle spamd[18307]: logger: removing stderr method  
Jan  9 18:25:09 eagle dccifd[18383]: write(MTA socket,89): Broken pipe
Jan  9 18:25:24 eagle spamd[18313]: spamd: server started on port
783/tcp (runni
ng version 3.1.0)   
Jan  9 18:25:24 eagle spamd[18313]: spamd: server pid: 18313   
Jan  9 18:25:24 eagle spamd[18313]: spamd: server successfully spawned
child pro
cess, pid 18414  
Jan  9 18:25:24 eagle spamd[18313]: spamd: server successfully spawned
child pro
cess, pid 18415  
Jan  9 18:25:24 eagle spamd[18414]: spamd: connection from localhost
[127.0.0.1]
 at port 59378   
Jan  9 18:25:24 eagle spamd[18414]: spamd: processing message
<43c2fe5f@happypat
tern.com> for mail:561  
Jan  9 18:25:24 eagle spamd[18313]: prefork: child states: BI
Jan  9 18:25:24 eagle spamd[18415]: spamd: connection from localhost
[127.0.0.1]
 at port 59381
Jan  9 18:25:24 eagle spamd[18415]: spamd: processing message
<o4vD3rkVl.v3YxK2d
9Af.nqkiRY1@spray.se> for mail:561
Jan  9 18:25:39 eagle spamd[18414]: spamd: identified spam (122.9/5.0)
for mail:
561 in 15.3 seconds, 5772 bytes.
Jan  9 18:25:40 eagle spamd[18414]: spamd: result: Y 122 -
DCC_CHECK,DIGEST_MULT
IPLE,FRONTPAGE,HTML_MESSAGE,HTML_TAG_BALANCE_BODY,RAZOR2_CF_RANGE_51_100,RAZOR2_
CF_RANGE_E4_51_100,RAZOR2_CF_RANGE_E8_51_100,RAZOR2_CHECK,SARE_EN_A_9XX_1,SARE_H
TML_HTML_BEFORE,SARE_SXLIFE,URIBL_JP_SURBL,URIBL_OB_SURBL,URIBL_WS_SURBL,USER_IN
_BLACKLIST,WLS_URI_OPT_1395
scantime=15.3,size=5772,user=mail,uid=561,required_s
core=5.0,rhost=localhost,raddr=127.0.0.1,rport=59378,mid=<43c2fe5f@happypattern.
com>,autolearn=disabled
Jan  9 18:25:50 eagle spamd[18414]: __alarm__
Jan  9 18:25:50 eagle spamd[18414]: __alarm__
Jan  9 18:25:50 eagle spamd[18313]: prefork: child states: BB
Jan  9 18:25:50 eagle spamd[18313]: spamd: server successfully spawned
child pro
cess, pid 18433
Jan  9 18:25:50 eagle spamd[18313]: prefork: child states: BBB
Jan  9 18:25:50 eagle spamd[18313]: spamd: server successfully spawned
child pro
cess, pid 18434
Jan  9 18:25:50 eagle spamd[18313]: prefork: child states: BBBI
Jan  9 18:25:50 eagle spamd[18433]: spamd: connection from localhost
[127.0.0.1]
 at port 59385
Jan  9 18:25:50 eagle spamd[18433]: spamd: processing message
<Xzugd31hP.N12uh2t
D.iK9niF@postmaster.co.uk> for mail:561
Jan  9 18:25:50 eagle spamd[18414]: spamd: connection from localhost
[127.0.0.1]
 at port 59384
Jan  9 18:25:50 eagle spamd[18414]: spamd: processing message
<JH4gKuqgma.dPnxot
NlAS.SVXzzQw@mweb.com.cn> for mail:561
Jan  9 18:26:13 eagle spamd[18434]: spamd: connection from localhost
[127.0.0.1]
 at port 46548
Jan  9 18:26:13 eagle spamd[18434]: spamd: processing message
<000001c615a6$a397
6400$0100007f@localhost> for mail:561
Jan  9 18:26:32 eagle spamd[18415]: spamd: identified spam (25.1/5.0)
for mail:5
61 in 67.9 seconds, 772 bytes.
Jan  9 18:26:32 eagle spamd[18415]: spamd: result: Y 25 -
DCC_CHECK,DIGEST_MULTI
PLE,FROM_LOCAL_NOVOWEL,INFO_TLD,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E8_51_100
,RAZOR2_CHECK,SARE_URI_RAW_ONLY,URIBL_JP_SURBL,URIBL_SC_SURBL,URIBL_WS_SURBL,URI
_NO_WWW_INFO_CGI,WLS_URI_OPT_1475
scantime=67.9,size=772,user=mail,uid=561,requi
red_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=59381,mid=<o4vD3rkVl.v3YxK2d
9Af.nqkiRY1@spray.se>,autolearn=disabled
Jan  9 18:26:50 eagle spamd[18415]: __alarm__
Jan  9 18:26:50 eagle spamd[18415]: __alarm__
Jan  9 18:26:54 eagle spamd[18313]: prefork: child states: BBBB
Jan  9 18:26:54 eagle spamd[18313]: spamd: server successfully spawned
child pro
cess, pid 18464
Jan  9 18:26:54 eagle spamd[18313]: prefork: child states: BBBBI
Jan  9 18:26:54 eagle spamd[18415]: spamd: connection from localhost
[127.0.0.1]
 at port 46551
Jan  9 18:26:55 eagle spamd[18415]: spamd: processing message
<001501c6157c$2a66
6740$8200a8c0@David> for mail:561
Jan  9 18:27:12 eagle spamd[18414]: spamd: identified spam (32.5/5.0)
for mail:5
61 in 82.2 seconds, 750 bytes.
Jan  9 18:27:12 eagle spamd[18414]: spamd: result: Y 32 -
INFO_TLD,RAZOR2_CF_RAN
GE_51_100,RAZOR2_CF_RANGE_E8_51_100,RAZOR2_CHECK,SARE_URI_RAW_ONLY,URIBL_AB_SURB
L,URIBL_JP_SURBL,URIBL_OB_SURBL,URIBL_SBL,URIBL_SC_SURBL,URIBL_WS_SURBL,URI_NO_W
WW_INFO_CGI,WLS_URI_OPT_3272
scantime=82.2,size=750,user=mail,uid=561,required_s
core=5.0,rhost=localhost,raddr=127.0.0.1,rport=59384,mid=<JH4gKuqgma.dPnxotNlAS.
SVXzzQw@mweb.com.cn>,autolearn=disabled
Jan  9 18:27:23 eagle spamd[18414]: __alarm__
Jan  9 18:27:23 eagle spamd[18414]: __alarm__
Jan  9 18:27:29 eagle spamd[18313]: tcp timeout at
/usr/local/lib/perl5/site_per
l/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm line 195.
Jan  9 18:27:29 eagle spamd[18313]: tcp timeout at
/usr/local/lib/perl5/site_per
l/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm line 195.
Jan  9 18:27:29 eagle spamd[18313]: prefork: select returned undef!
recovering
Jan  9 18:27:41 eagle spamd[18433]: spamd: identified spam (26.4/5.0)
for mail:5
61 in 110.7 seconds, 805 bytes.
Jan  9 18:27:41 eagle spamd[18433]: spamd: result: Y 26 -
DCC_CHECK,FROM_LOCAL_N
OVOWEL,INFO_TLD,SARE_URI_RAW_ONLY,URIBL_JP_SURBL,URIBL_SBL,URIBL_SC_SURBL,URIBL_
WS_SURBL,URI_NO_WWW_INFO_CGI,WLS_URI_OPT_1475
scantime=110.7,size=805,user=mail,
uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=59385,mid=<Xzug
d31hP.N12uh2tD.iK9niF@postmaster.co.uk>,autolearn=disabled
Jan  9 18:27:47 eagle spamd[18313]: tcp timeout at
/usr/local/lib/perl5/site_per
l/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm line 195.
Jan  9 18:27:47 eagle spamd[18313]: tcp timeout at
/usr/local/lib/perl5/site_per
l/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm line 195.
Jan  9 18:27:47 eagle spamd[18313]: prefork: select returned undef!
recovering
Jan  9 18:27:48 eagle spamd[18313]: prefork: child states: IBBBI
Jan  9 18:27:51 eagle spamd[18414]: spamd: connection from localhost
[127.0.0.1]
 at port 46555
Jan  9 18:27:53 eagle spamd[18414]: spamd: processing message
<191690-2200612100
271598@PSU.EDU> for mail:561
Jan  9 18:27:57 eagle spamd[18433]: __alarm__
Jan  9 18:27:57 eagle spamd[18433]: __alarm__
Jan  9 18:27:57 eagle spamd[18313]: prefork: child states: BBIBI
Jan  9 18:28:25 eagle spamd[18433]: spamd: connection from localhost
[127.0.0.1]
 at port 46556
Jan  9 18:28:26 eagle spamd[18433]: spamd: processing message
<001101c60f1c$66c0
f360$0200a8c0@pc> for mail:561
Jan  9 18:28:42 eagle spamd[18434]: spamd: identified spam (38.4/5.0)
for mail:5
61 in 149.0 seconds, 14763 bytes.
Jan  9 18:28:43 eagle spamd[18434]: spamd: result: Y 38 -
DCC_CHECK,DIGEST_MULTI
PLE,HTML_MESSAGE,HTML_TAG_EXIST_TBODY,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E4_
51_100,RAZOR2_CF_RANGE_E8_51_100,RAZOR2_CHECK,RM_bpoem_InstantDL,SARE_OEM_AND_OT
HER,SARE_OEM_A_1,SARE_OEM_NEW_TITLES,SARE_OEM_PRODS_1,SARE_OEM_PRODS_FEW,SARE_OE
M_S_PRICE,SARE_OEM_UPPER_EYE,SARE_PRODS_LOTS,SARE_PRODUCTS_02,SARE_PRODUCTS_03,S
ARE_PRODUCTS_04,SARE_SUB_CHEAP_SW,URIBL_JP_SURBL,URIBL_SBL,URIBL_SC_SURBL,URIBL_
WS_SURBL,WLS_URI_OPT_2170
scantime=149.0,size=14763,user=mail,uid=561,required_s
core=5.0,rhost=localhost,raddr=127.0.0.1,rport=46548,mid=<000001c615a6$a3976400$
0100007f@localhost>,autolearn=disabled
Jan  9 18:28:59 eagle spamd[18464]: spamd: connection from localhost
[127.0.0.1]
 at port 46559
Jan  9 18:29:24 eagle spamd[18434]: __alarm__
Jan  9 18:29:28 eagle spamd[18434]: __alarm__
Jan  9 18:29:28 eagle spamd[18313]: prefork: child states: BBBIB
Jan  9 18:29:53 eagle spamd[18464]: spamd: processing message
<dKqBnX.squirrel@2
18.208.51.3> for mail:561
Jan  9 18:30:35 eagle spamd[18433]: spamd: identified spam (47.5/5.0)
for mail:5
61 in 130.4 seconds, 3461 bytes.
Jan  9 18:30:37 eagle spamd[18433]: spamd: result: Y 47 -
DATE_IN_PAST_06_12,DCC
_CHECK,DIGEST_MULTIPLE,DRUGS_ERECTILE,DRUG_DOSAGE,DRUG_ED_CAPS,FORGED_YAHOO_RCVD
,HTML_FONT_BIG,HTML_FONT_SIZE_LARGE,HTML_MESSAGE,HTML_TAG_EXIST_TBODY,INFO_TLD,M
SGID_OUTLOOK_INVALID,NO_REAL_NAME,ONLINE_PHARMACY,RAZOR2_CF_RANGE_51_100,RAZOR2_
CF_RANGE_E4_51_100,RAZOR2_CF_RANGE_E8_51_100,RAZOR2_CHECK,SARE_SUB_AS_LOW_AS,SAR
E_SUPERVIAGRA,URIBL_AB_SURBL,URIBL_JP_SURBL,URIBL_OB_SURBL,URIBL_SBL,URIBL_SC_SU
RBL,URIBL_WS_SURBL,URI_NO_WWW_INFO_CGI,WLS_URI_OPT_1744
scantime=130.4,size=3461
,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=4655
6,mid=<00...@pc>,autolearn=disabled
Jan  9 18:30:40 eagle spamd[18434]: spamd: connection from localhost
[127.0.0.1]
 at port 46566
Jan  9 18:30:47 eagle spamd[18313]: prefork: child states: BBIBB
Jan  9 18:30:54 eagle spamd[18434]: spamd: processing message
<j$39-27i184-$1g@f
nsdvt.mqon> for mail:561
Jan  9 18:31:07 eagle spamd[18433]: spamd: connection from localhost
[127.0.0.1]
 at port 34888
Jan  9 18:31:09 eagle spamd[18433]: spamd: processing message
<7-10168083-19L6LK
JNFKCLbi4Zru@em.prewards.com> for mail:561
Jan  9 18:31:27 eagle spamd[18313]: tcp timeout at
/usr/local/lib/perl5/site_per
l/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm line 195.
Jan  9 18:31:28 eagle spamd[18313]: tcp timeout at
/usr/local/lib/perl5/site_per
l/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm line 195.
Jan  9 18:31:28 eagle spamd[18313]: prefork: select returned undef!
recovering
Jan  9 18:31:51 eagle spamd[18414]: spamd: clean message (1.3/5.0) for
mail:561
in 234.4 seconds, 3145 bytes.
Jan  9 18:32:05 eagle spamd[18414]: spamd: result: .  1 -
HTML_IMAGE_ONLY_16,HTM
L_MESSAGE,MIME_BOUND_NEXTPART,MISSING_HEADERS,SARE_HTML_BODY_2SP,TO_CC_NONE
scan
time=234.4,size=3145,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=
127.0.0.1,rport=46555,mid=<19...@PSU.EDU>,autolearn=disabled
Jan  9 18:32:07 eagle spamd[18414]: __alarm__
Jan  9 18:32:08 eagle spamd[18414]: __alarm__
Jan  9 18:32:08 eagle spamd[18313]: prefork: child states: IBBBB
Jan  9 18:32:08 eagle spamd[18415]: spamd: clean message (0.1/5.0) for
mail:561
in 308.9 seconds, 9149 bytes.
Jan  9 18:32:09 eagle spamd[18415]: spamd: result: .  0 -
HTML_MESSAGE,TW_TB sca
ntime=308.9,size=9149,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr
=127.0.0.1,rport=46551,mid=<00...@David>,autolearn=disa
bled
Jan  9 18:32:09 eagle spamd[18313]: prefork: child states: IIBBB
Jan  9 18:32:27 eagle spamd[18414]: spamd: connection from localhost
[127.0.0.1]
 at port 34889
Jan  9 18:32:32 eagle spamd[18414]: spamd: processing message
<158c195h.6664687@
yahoo.com> for mail:561
Jan  9 18:32:45 eagle spamd[18433]: spamd: identified spam (23.8/5.0)
for mail:5
61 in 97.9 seconds, 4857 bytes.
Jan  9 18:32:46 eagle spamd[18433]: spamd: result: Y 23 -
BAD_CREDIT,DCC_CHECK,H
TML_IMAGE_RATIO_02,HTML_MESSAGE,MIME_HTML_ONLY,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_
RANGE_E4_51_100,SARE_MSGID_DDDASH,SARE_SUB_POOR_CREDIT,URIBL_JP_SURBL,URIBL_OB_S
URBL,URIBL_SBL,URIBL_WS_SURBL,WLS_URI_OPT_14
scantime=97.9,size=4857,user=mail,u
id=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=34888,mid=<7-101
68083-19L6LKJNFKCLbi4Zru@em.prewards.com>,autolearn=disabled
Jan  9 18:32:48 eagle spamd[18415]: spamd: connection from localhost
[127.0.0.1]
 at port 34893
Jan  9 18:32:51 eagle spamd[18415]: spamd: processing message
<200601100031.k0A0
VLnG016456@smtp30.hccnet.nl> for mail:561
Jan  9 18:33:36 eagle spamd[18434]: spamd: identified spam (25.3/5.0)
for mail:5
61 in 174.2 seconds, 10711 bytes.
Jan  9 18:34:01 eagle spamd[18434]: spamd: result: Y 25 -
DCC_CHECK,FORGED_MUA_E
UDORA,FROM_HAS_MIXED_NUMS,FROM_ILLEGAL_CHARS,HEAD_ILLEGAL_CHARS,HTML_IMAGE_RATIO
_02,HTML_MESSAGE,HTTP_EXCESSIVE_ESCAPES,MIME_HTML_ONLY,MIME_HTML_ONLY_MULTI,MPAR
T_ALT_DIFF,RCVD_DOUBLE_IP_LOOSE,RCVD_HELO_IP_MISMATCH,RCVD_NUMERIC_HELO,REPTO_QU
OTE_QUALCOMM,SARE_HTML_TITLE_1WD,SUBJ_ALL_CAPS,SUBJ_ILLEGAL_CHARS,URIBL_SBL
scan
time=174.2,size=10711,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr
=127.0.0.1,rport=46566,mid=<j$...@fnsdvt.mqon>,autolearn=disabled
Jan  9 18:34:05 eagle spamd[18433]: __alarm__
Jan  9 18:34:07 eagle spamd[18433]: __alarm__
Jan  9 18:34:07 eagle spamd[18313]: prefork: child states: BBIBB
Jan  9 18:34:08 eagle spamd[18414]: spamd: identified spam (11.2/5.0)
for mail:5
61 in 71.1 seconds, 12360 bytes.
Jan  9 18:34:08 eagle spamd[18414]: spamd: result: Y 11 -
DATE_IN_PAST_96_XX,FOR
GED_MSGID_YAHOO,HELO_DYNAMIC_COMCAST,HTML_IMAGE_ONLY_08,HTML_MESSAGE,HTML_SHORT_
LINK_IMG_1,MIME_HTML_ONLY,SARE_UNF
scantime=71.1,size=12360,user=mail,uid=561,re
quired_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=34889,mid=<158c195h.66646
87@yahoo.com>,autolearn=disabled
Jan  9 18:34:08 eagle spamd[18313]: prefork: child states: BBIIB
Jan  9 18:34:08 eagle spamd[18414]: __alarm__
Jan  9 18:34:09 eagle spamd[18414]: __alarm__
Jan  9 18:34:09 eagle spamd[18313]: prefork: child states: IBIIB
Jan  9 18:34:12 eagle spamd[18313]: spamd: handled cleanup of child
pid 18434 du
e to SIGCHLD
Jan  9 18:34:13 eagle spamd[18313]: prefork: child states: IBIB
Jan  9 18:34:26 eagle spamd[18464]: child processing timeout at
/usr/local/bin/s
pamd line 1085, <GEN20> line 36.
Jan  9 18:34:28 eagle spamd[18464]: child processing timeout at
/usr/local/bin/s
pamd line 1085, <GEN20> line 36.
Jan  9 18:34:35 eagle spamd[18414]: spamd: connection from localhost
[127.0.0.1]
 at port 34897
Jan  9 18:34:37 eagle spamd[18414]: spamd: processing message
<E1Ew7Rr-0004si-00
@eagle.netwrx1.com> for mail:561
Jan  9 18:34:37 eagle spamd[18433]: spamd: connection from localhost
[127.0.0.1]
 at port 34898
Jan  9 18:34:37 eagle spamd[18433]: spamd: processing message
<dpuvat+gdo9@eGrou
ps.com> for mail:561
Jan  9 18:35:02 eagle spamd[18464]: spamd: identified spam (25.9/5.0)
for mail:5
61 in 364.8 seconds, 1867 bytes.
Jan  9 18:35:02 eagle spamd[18464]: spamd: result: Y 25 -
HTML_FONT_FACE_BAD,HTM
L_MESSAGE,MIME_HTML_ONLY,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E8_51_100,RAZOR2
_CHECK,SARE_UNG,SARE_UNQ,TW_OQ,TW_QH,URIBL_AB_SURBL,URIBL_JP_SURBL,URIBL_OB_SURB
L,URIBL_SC_SURBL,URIBL_WS_SURBL,URI_NOVOWEL,WLS_URI_OPT_3415
scantime=364.8,size
=1867,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport
=46559,mid=<dK...@218.208.51.3>,autolearn=disabled
Jan  9 18:35:10 eagle spamd[18415]: spamd: clean message (0.0/5.0) for
mail:561
in 143.6 seconds, 3130 bytes.
Jan  9 18:35:15 eagle spamd[18415]: spamd: result: .  0 -
UNPARSEABLE_RELAY scan
time=143.6,size=3130,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=
127.0.0.1,rport=34893,mid=<20...@smtp30.hccnet.nl>,autolea
rn=disabled
Jan  9 18:35:34 eagle spamd[18415]: __alarm__
Jan  9 18:35:34 eagle spamd[18415]: __alarm__
Jan  9 18:35:35 eagle spamd[18415]: spamd: connection from localhost
[127.0.0.1]
 at port 34904
Jan  9 18:35:37 eagle spamd[18313]: prefork: child states: BBBB
Jan  9 18:35:42 eagle spamd[18415]: spamd: processing message
<001101c60f1c$66c0
f360$0200a8c0@pc> for mail:561
Jan  9 18:35:57 eagle spamd[18313]: spamd: server successfully spawned
child pro
cess, pid 18903
Jan  9 18:36:03 eagle spamd[18464]: __alarm__
Jan  9 18:36:04 eagle spamd[18464]: __alarm__
Jan  9 18:36:05 eagle spamd[18313]: prefork: child states: BBBBB
Jan  9 18:36:06 eagle spamd[18313]: spamd: server successfully spawned
child pro
cess, pid 18921
Jan  9 18:36:09 eagle spamd[18903]: spamd: connection from localhost
[127.0.0.1]
 at port 34905
Jan  9 18:36:18 eagle spamd[18464]: spamd: connection from localhost
[127.0.0.1]
 at port 34906
Jan  9 18:36:21 eagle spamd[18313]: prefork: child states: BBBBBI
Jan  9 18:36:21 eagle spamd[18464]: spamd: processing message
<KVKFJSEOCGRPSDDQU
FZSZP@yahoo.com> for mail:561
Jan  9 18:37:34 eagle spamd[18903]: spamd: processing message
<000001c615a7$d8f0
8180$0100007f@localhost> for mail:561
Jan  9 18:38:04 eagle spamd[18313]: tcp timeout at
/usr/local/lib/perl5/site_per
l/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm line 195.
Jan  9 18:38:11 eagle spamd[18313]: tcp timeout at
/usr/local/lib/perl5/site_per
l/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm line 195.
Jan  9 18:38:11 eagle spamd[18313]: prefork: select returned undef!
recovering
Jan  9 18:38:15 eagle spamd[18433]: spamd: clean message (0.0/5.0) for
mail:561
in 210.0 seconds, 3170 bytes.
Jan  9 18:38:18 eagle spamd[18433]: spamd: result: .  0 -
scantime=210.0,size=3
170,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=3
4898,mid=<dp...@eGroups.com>,autolearn=disabled
Jan  9 18:38:28 eagle spamd[18433]: __alarm__
Jan  9 18:38:32 eagle spamd[18433]: __alarm__
Jan  9 18:38:42 eagle spamd[18414]: spamd: identified spam (10.1/5.0)
for mail:5
61 in 233.4 seconds, 1581 bytes.
Jan  9 18:39:19 eagle spamd[18414]: spamd: result: Y 10 -
DCC_CHECK,MAILTO_TO_SP
AM_ADDR,MIME_BASE64_TEXT,MISSING_MIMEOLE,MSGID_FROM_MTA_ID,RCVD_NUMERIC_HELO,SUB
J_ILLEGAL_CHARS
scantime=233.4,size=1581,user=mail,uid=561,required_score=5.0,rh
ost=localhost,raddr=127.0.0.1,rport=34897,mid=<E1Ew7Rr-0004si-00@eagle.netwrx1.c
om>,autolearn=disabled
Jan  9 18:40:09 eagle spamd[18464]: spamd: identified spam (51.0/5.0)
for mail:5
61 in 143.3 seconds, 2071 bytes.
Jan  9 18:40:44 eagle spamd[18464]: spamd: result: Y 50 -
DATE_IN_FUTURE_96_XX,D
ATE_SPAMWARE_Y2K,FORGED_MUA_OUTLOOK,FORGED_OUTLOOK_HTML,FORGED_YAHOO_RCVD,FROM_I
LLEGAL_CHARS,FROM_LOCAL_NOVOWEL,HEAD_ILLEGAL_CHARS,HTML_IMAGE_ONLY_12,HTML_IMAGE
_RATIO_02,HTML_MESSAGE,HTTP_EXCESSIVE_ESCAPES,MIME_BOUND_DD_DIGITS,MIME_HTML_ONL
Y,MIME_HTML_ONLY_MULTI,MISSING_MIMEOLE,MPART_ALT_DIFF,MSGID_SPAM_CAPS,MSGID_YAHO
O_CAPS,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E4_51_100,RAZOR2_CHECK,RCVD_NUMERI
C_HELO,REPTO_QUOTE_YAHOO,SARE_BOUNDARY_07,SUBJ_ILLEGAL_CHARS,UNPARSEABLE_RELAY,U
RIBL_JP_SURBL,URIBL_WS_SURBL,WLS_URI_OPT_2599,X_PRIORITY_HIGH
scantime=143.3,siz
e=2071,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rpor
t=34906,mid=<KV...@yahoo.com>,autolearn=disabled
Jan  9 18:41:07 eagle spamd[18464]: __alarm__
Jan  9 18:41:08 eagle spamd[18464]: __alarm__
Jan  9 18:41:08 eagle spamd[18414]: __alarm__
Jan  9 18:41:09 eagle spamd[18414]: __alarm__
Jan  9 18:41:09 eagle spamd[18415]: spamd: identified spam (48.5/5.0)
for mail:5
61 in 195.3 seconds, 3443 bytes.
Jan  9 18:41:38 eagle spamd[18415]: spamd: result: Y 48 -
DATE_IN_PAST_06_12,DCC
_CHECK,DIGEST_MULTIPLE,DRUGS_ERECTILE,DRUGS_PAIN,DRUGS_PAIN_EREC,DRUG_DOSAGE,DRU
G_ED_CAPS,FORGED_YAHOO_RCVD,HTML_FONT_BIG,HTML_FONT_SIZE_LARGE,HTML_MESSAGE,HTML
_TAG_EXIST_TBODY,INFO_TLD,MSGID_OUTLOOK_INVALID,NO_REAL_NAME,ONLINE_PHARMACY,RAZ
OR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E4_51_100,RAZOR2_CF_RANGE_E8_51_100,RAZOR2_C
HECK,SARE_SUB_AS_LOW_AS,SARE_SUPERVIAGRA,URIBL_AB_SURBL,URIBL_JP_SURBL,URIBL_OB_
SURBL,URIBL_SBL,URIBL_SC_SURBL,URIBL_WS_SURBL,URI_NO_WWW_INFO_CGI,WLS_URI_OPT_17
44
scantime=195.3,size=3443,user=mail,uid=561,required_score=5.0,rhost=localhost
,raddr=127.0.0.1,rport=34904,mid=<00...@pc>,autolearn=d
isabled
Jan  9 18:41:39 eagle spamd[18903]: child processing timeout at
/usr/local/bin/s
pamd line 1085, <GEN23> line 147.
Jan  9 18:41:39 eagle spamd[18903]: child processing timeout at
/usr/local/bin/s
pamd line 1085, <GEN23> line 147.
Jan  9 18:41:39 eagle spamd[18903]: child processing timeout at
/usr/local/bin/s
pamd line 1085, <GEN23> line 147.
Jan  9 18:42:09 eagle spamd[18903]: spamd: identified spam (35.4/5.0)
for mail:5
61 in 364.8 seconds, 14725 bytes.
Jan  9 18:44:25 eagle spamd[18903]: spamd: result: Y 35 -
HTML_MESSAGE,HTML_TAG_
EXIST_TBODY,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E4_51_100,RAZOR2_CF_RANGE_E8_
51_100,RAZOR2_CHECK,RM_bpoem_InstantDL,SARE_OEM_AND_OTHER,SARE_OEM_A_1,SARE_OEM_
NEW_TITLES,SARE_OEM_PRODS_1,SARE_OEM_PRODS_FEW,SARE_OEM_S_PRICE,SARE_OEM_UPPER_E
YE,SARE_PRODS_LOTS,SARE_PRODUCTS_02,SARE_PRODUCTS_03,SARE_PRODUCTS_04,URIBL_JP_S
URBL,URIBL_SBL,URIBL_SC_SURBL,URIBL_WS_SURBL,WLS_URI_OPT_2170
scantime=364.8,siz
e=14725,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rpo
rt=34905,mid=<00...@localhost>,autolearn=disabled
Jan  9 18:47:04 eagle spamd[18921]: prefork: sysread(12) failed after
300 secs a
t
/usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
lin
e 501.
Jan  9 18:48:21 eagle spamd[18433]: prefork: sysread(9) failed after
300 secs at

/usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
line
 501.
Jan  9 18:48:34 eagle spamd[18903]: __alarm__
Jan  9 18:48:36 eagle spamd[18464]: prefork: sysread(11) failed after
300 secs a
t
/usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
lin
e 501.
Jan  9 18:48:37 eagle spamd[18414]: prefork: sysread(7) failed after
300 secs at

/usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
line
 501.
Jan  9 18:48:38 eagle spamd[18415]: prefork: sysread(8) failed after
300 secs at

/usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
line
 501.
Jan  9 18:48:39 eagle spamd[18903]: __alarm__
Jan  9 18:53:03 eagle spamc[19415]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#1 of 3): Connection refused
Jan  9 18:53:04 eagle spamc[19415]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#2 of 3): Connection refused
Jan  9 18:53:04 eagle spamc[19420]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#1 of 3): Connection refused
Jan  9 18:53:05 eagle spamc[19415]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#3 of 3): Connection refused
Jan  9 18:53:05 eagle spamc[19420]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#2 of 3): Connection refused
Jan  9 18:53:06 eagle spamc[19415]: connection attempt to spamd
aborted after 3
retries
Jan  9 18:53:06 eagle spamc[19420]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#3 of 3): Connection refused
Jan  9 18:53:07 eagle spamc[19420]: connection attempt to spamd
aborted after 3
retries
Jan  9 18:53:08 eagle spamc[19429]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#1 of 3): Connection refused
Jan  9 18:53:09 eagle spamc[19429]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#2 of 3): Connection refused
Jan  9 18:53:09 eagle spamc[19437]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#1 of 3): Connection refused
Jan  9 18:53:10 eagle spamc[19429]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#3 of 3): Connection refused
Jan  9 18:53:10 eagle spamc[19437]: connect(AF_INET) to spamd at
127.0.0.1 faile
d, retrying (#2 of 3): Connection refused
Jan  9 18:53:11 eagle spamc[19429]: connection attempt to spamd
aborted after 3
retries
George, MR. Tibbs, Nazarene, Ginger/The Beast Kasica(8/1/88-3/19/01, 1/17/02-)
Jackson, WI USA
georgek@netwrx1.com
http://www.netwrx1.com/georgek
ICQ #12862186

("`-''-/").___..--''"`-._
`6_ 6  )   `-.  (     ).`-.__.`)
(_Y_.)'  ._   )  `._ `. ``-..-'
_..`--'_..-_/  /--'_.' ,'
(il),-''  (li),'  ((!.-'

Re: SA 3.10 skipping some emails or errors in log??

Posted by "George R. Kasica" <ge...@netwrx1.com>.
>On Tue, 10 Jan 2006 21:29:02 -0600, you wrote:

>>On Tue, 10 Jan 2006 20:56:48 -0500, you wrote:
>
>>On 10/01/2006 8:17 PM, George R. Kasica wrote:
>>>>On Tue, 10 Jan 2006 18:58:37 -0500, you wrote:
>>
>>>>If you can get a "strace -ftp PID" of the parent spamd process while 
>>>>this happens (along with a matching debug log) and *attach* it to the 
>>>>bug, I'm sure Justin would take a look at it.
>>>>
>>>>I haven't been able to reproduce it myself, so I haven't looked at it 
>>>>further.
>>> 
>>> Daryl:
>>> 
>>> Not a programmer here, but with a little direction I think I can get
>>> the info.
>>> 
>>> I'm assuming the following here:
>>> 
>>> strace -ftp PID where PID is the PID of the parent spamd process
>>> correct?
>>
>>Yeah PID is the process ID of the parent spamd process.  Also, you can 
>>redirect the output to a file with normal redirection, or just specify 
>>an output file with the -o option, ala:
>>
>>strace -ftp PID -o /path/to/output/file
>>
>>
>>> As to debug log, how would I go about that? Is it the info I provided
>>> earlier just doing it over again to match with strace output?
>>
>>Yeah.  You might want to add "-Dprefork" as one of the options to your 
>>spamd call though.
>
>It's running now. I will hopefully have some items to upload soon.
>

Daryl:

I've uploaded a zip of 4 files, 2 logs, 2 traces to the 4696 bug
report.

===[George R. Kasica]===        +1 262 677 0766
President                       +1 206 374 6482 FAX 
Netwrx Consulting Inc.          Jackson, WI USA 
http://www.netwrx1.com
georgek@netwrx1.com
ICQ #12862186

Re: SA 3.10 skipping some emails or errors in log??

Posted by "George R. Kasica" <ge...@netwrx1.com>.
>On Tue, 10 Jan 2006 20:56:48 -0500, you wrote:

>On 10/01/2006 8:17 PM, George R. Kasica wrote:
>>>On Tue, 10 Jan 2006 18:58:37 -0500, you wrote:
>
>>>If you can get a "strace -ftp PID" of the parent spamd process while 
>>>this happens (along with a matching debug log) and *attach* it to the 
>>>bug, I'm sure Justin would take a look at it.
>>>
>>>I haven't been able to reproduce it myself, so I haven't looked at it 
>>>further.
>> 
>> Daryl:
>> 
>> Not a programmer here, but with a little direction I think I can get
>> the info.
>> 
>> I'm assuming the following here:
>> 
>> strace -ftp PID where PID is the PID of the parent spamd process
>> correct?
>
>Yeah PID is the process ID of the parent spamd process.  Also, you can 
>redirect the output to a file with normal redirection, or just specify 
>an output file with the -o option, ala:
>
>strace -ftp PID -o /path/to/output/file
>
>
>> As to debug log, how would I go about that? Is it the info I provided
>> earlier just doing it over again to match with strace output?
>
>Yeah.  You might want to add "-Dprefork" as one of the options to your 
>spamd call though.

It's running now. I will hopefully have some items to upload soon.

George
George, MR. Tibbs, Nazarene, Ginger/The Beast Kasica(8/1/88-3/19/01, 1/17/02-)
Jackson, WI USA
georgek@netwrx1.com
http://www.netwrx1.com/georgek
ICQ #12862186

("`-''-/").___..--''"`-._
`6_ 6  )   `-.  (     ).`-.__.`)
(_Y_.)'  ._   )  `._ `. ``-..-'
_..`--'_..-_/  /--'_.' ,'
(il),-''  (li),'  ((!.-'

Re: SA 3.10 skipping some emails or errors in log??

Posted by "Daryl C. W. O'Shea" <sp...@dostech.ca>.
On 10/01/2006 8:17 PM, George R. Kasica wrote:
>>On Tue, 10 Jan 2006 18:58:37 -0500, you wrote:

>>If you can get a "strace -ftp PID" of the parent spamd process while 
>>this happens (along with a matching debug log) and *attach* it to the 
>>bug, I'm sure Justin would take a look at it.
>>
>>I haven't been able to reproduce it myself, so I haven't looked at it 
>>further.
> 
> 
> Daryl:
> 
> Not a programmer here, but with a little direction I think I can get
> the info.
> 
> I'm assuming the following here:
> 
> strace -ftp PID where PID is the PID of the parent spamd process
> correct?

Yeah PID is the process ID of the parent spamd process.  Also, you can 
redirect the output to a file with normal redirection, or just specify 
an output file with the -o option, ala:

strace -ftp PID -o /path/to/output/file


> As to debug log, how would I go about that? Is it the info I provided
> earlier just doing it over again to match with strace output?

Yeah.  You might want to add "-Dprefork" as one of the options to your 
spamd call though.


Daryl


Re: SA 3.10 skipping some emails or errors in log??

Posted by "George R. Kasica" <ge...@netwrx1.com>.
>On Tue, 10 Jan 2006 18:58:37 -0500, you wrote:

>On 10/01/2006 11:29 AM, George R. Kasica wrote:
>>>On Mon, 09 Jan 2006 21:45:11 -0500, you wrote:
>
>>>Please see, and comment on, bug 4696:
>>>
>>>http://issues.apache.org/SpamAssassin/show_bug.cgi?id=4696
>>>
>
>> Just curious as to the estimate for how long it will be until the
>> problem is corrected? Right now with the way SA 3.1 is operating here
>> it is almost worthless, catching and scanning about 20% of the spam
>> due to the bug causing difficulties I'm assuming?
>
>If you can get a "strace -ftp PID" of the parent spamd process while 
>this happens (along with a matching debug log) and *attach* it to the 
>bug, I'm sure Justin would take a look at it.
>
>I haven't been able to reproduce it myself, so I haven't looked at it 
>further.

Daryl:

Not a programmer here, but with a little direction I think I can get
the info.

I'm assuming the following here:

strace -ftp PID where PID is the PID of the parent spamd process
correct?

As to debug log, how would I go about that? Is it the info I provided
earlier just doing it over again to match with strace output?

George
George, MR. Tibbs, Nazarene, Ginger/The Beast Kasica(8/1/88-3/19/01, 1/17/02-)
Jackson, WI USA
georgek@netwrx1.com
http://www.netwrx1.com/georgek
ICQ #12862186

("`-''-/").___..--''"`-._
`6_ 6  )   `-.  (     ).`-.__.`)
(_Y_.)'  ._   )  `._ `. ``-..-'
_..`--'_..-_/  /--'_.' ,'
(il),-''  (li),'  ((!.-'

Re: SA 3.10 skipping some emails or errors in log??

Posted by "Daryl C. W. O'Shea" <sp...@dostech.ca>.
On 10/01/2006 11:29 AM, George R. Kasica wrote:
>>On Mon, 09 Jan 2006 21:45:11 -0500, you wrote:

>>Please see, and comment on, bug 4696:
>>
>>http://issues.apache.org/SpamAssassin/show_bug.cgi?id=4696
>>

> Just curious as to the estimate for how long it will be until the
> problem is corrected? Right now with the way SA 3.1 is operating here
> it is almost worthless, catching and scanning about 20% of the spam
> due to the bug causing difficulties I'm assuming?

If you can get a "strace -ftp PID" of the parent spamd process while 
this happens (along with a matching debug log) and *attach* it to the 
bug, I'm sure Justin would take a look at it.

I haven't been able to reproduce it myself, so I haven't looked at it 
further.


Daryl


Re: SA 3.10 skipping some emails or errors in log??

Posted by "George R. Kasica" <ge...@netwrx1.com>.
>On Mon, 09 Jan 2006 21:45:11 -0500, you wrote:

>On 09/01/2006 7:36 PM, George R. Kasica wrote:
>
>> Jan  9 15:31:07 eagle spamd[8420]: spamd: processing message
>> <00...@binc.net> for mail:561 
>> Jan  9 15:34:55 eagle spamd[8715]: __alarm__ 
>> Jan  9 15:35:01 eagle spamd[8715]: __alarm__ 
>> Jan  9 15:35:01 eagle spamd[8311]: prefork: child states: BBBIB 
>> Jan  9 15:35:02 eagle spamd[8719]: spamd: processing message
>> <20...@ms-smtp-03-eri0.texas.rr.com> for
>> mail:561 
>> Jan  9 15:35:12 eagle spamd[8311]: tcp timeout at
>> /usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
>> line 195. 
>> Jan  9 15:35:12 eagle spamd[8311]: tcp timeout at
>> /usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
>> line 195. 
>> Jan  9 15:35:12 eagle spamd[8311]: prefork: select returned undef!
>> recovering 
>> Jan  9 15:35:48 eagle spamd[8712]: spamd: clean message (0.0/5.0) for
>> mail:561 in 186.2 seconds, 14503 bytes. 
>> Jan  9 15:35:48 eagle spamd[8712]: spamd: result: .  0 - HTML_MESSAGE
>> scantime=186.2,size=14503,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=54421,mid=<HE...@animas.net>,autolearn=disabled
>
>Please see, and comment on, bug 4696:
>
>http://issues.apache.org/SpamAssassin/show_bug.cgi?id=4696
>
>
>Daryl
>
Daryl:

Just curious as to the estimate for how long it will be until the
problem is corrected? Right now with the way SA 3.1 is operating here
it is almost worthless, catching and scanning about 20% of the spam
due to the bug causing difficulties I'm assuming?

George
===[George R. Kasica]===        +1 262 677 0766
President                       +1 206 374 6482 FAX 
Netwrx Consulting Inc.          Jackson, WI USA 
http://www.netwrx1.com
georgek@netwrx1.com
ICQ #12862186

Re: SA 3.10 skipping some emails or errors in log??

Posted by "Daryl C. W. O'Shea" <sp...@dostech.ca>.
On 09/01/2006 7:36 PM, George R. Kasica wrote:

> Jan  9 15:31:07 eagle spamd[8420]: spamd: processing message
> <00...@binc.net> for mail:561 
> Jan  9 15:34:55 eagle spamd[8715]: __alarm__ 
> Jan  9 15:35:01 eagle spamd[8715]: __alarm__ 
> Jan  9 15:35:01 eagle spamd[8311]: prefork: child states: BBBIB 
> Jan  9 15:35:02 eagle spamd[8719]: spamd: processing message
> <20...@ms-smtp-03-eri0.texas.rr.com> for
> mail:561 
> Jan  9 15:35:12 eagle spamd[8311]: tcp timeout at
> /usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
> line 195. 
> Jan  9 15:35:12 eagle spamd[8311]: tcp timeout at
> /usr/local/lib/perl5/site_perl/5.8.0/Mail/SpamAssassin/SpamdForkScaling.pm
> line 195. 
> Jan  9 15:35:12 eagle spamd[8311]: prefork: select returned undef!
> recovering 
> Jan  9 15:35:48 eagle spamd[8712]: spamd: clean message (0.0/5.0) for
> mail:561 in 186.2 seconds, 14503 bytes. 
> Jan  9 15:35:48 eagle spamd[8712]: spamd: result: .  0 - HTML_MESSAGE
> scantime=186.2,size=14503,user=mail,uid=561,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=54421,mid=<HE...@animas.net>,autolearn=disabled

Please see, and comment on, bug 4696:

http://issues.apache.org/SpamAssassin/show_bug.cgi?id=4696


Daryl