Daily Usenet report

May 8 15:00:01 -- May 9 15:00:02

Unknown entries from news log file:

First 50 / 735 lines (6.8%)

May 8 15:00:46 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
May 8 15:02:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:04:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:06:02 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:08:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:10:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:12:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:14:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:16:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:18:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:20:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:22:05 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:24:01 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:26:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:28:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:30:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:32:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:34:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:36:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:38:05 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:40:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:42:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:44:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:46:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:48:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:50:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:52:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:54:01 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:56:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 15:58:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:00:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:02:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:04:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:06:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:08:00 colo-sc-1 innd: message repeated 8 times: [ 199.102.165.10 rejected 502]
May 8 16:10:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:12:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:14:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:16:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:18:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:20:05 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:22:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:24:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:26:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:28:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:30:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:32:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:34:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:36:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
May 8 16:38:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]

Log entries by program:

Program nameLines%LinesSize%Size
inn 12877 62.9%1.7 MB 65.3%
innd 4922 24.0%585.5 KB 21.5%
innfeed 1463 7.1%178.4 KB 6.5%
cnfsstat 1152 5.6%172.3 KB 6.3%
nnrpd 54 0.3%5.5 KB 0.2%
controlchan 13 0.1%2.9 KB 0.1%
pgpverify 2 0.0%0.2 KB 0.0%
TOTAL: 7 20483 100.0%2.7 MB100.0%

History cache:

ReasonCount%Count
Negative hits 28083 45.6%
Positive hits 20994 34.1%
Do not exist 12486 20.3%
Cache misses 7 0.0%
TOTAL: 4 61570 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.002 0.0%38 0.000 0.053 1.000
article cleanup00:00:00.086 0.0%12489 0.000 0.007 0.064
article logging00:00:00.293 0.0%12691 0.000 0.023 0.071
article parse00:00:00.198 0.0%22428 0.000 0.009 0.033
article write00:00:00.524 0.0%10541 0.000 0.050 0.143
artlog/artcncl00:00:00.000 0.0%37 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.048 0.0%71604 0.000 0.001 0.010
hisgrep/artcncl00:00:00.000 0.0%25 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%38 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.806 0.0%61618 0.000 0.013 1.058
history sync00:00:00.014 0.0%396 0.000 0.035 0.500
history write00:00:00.716 0.0%12489 0.000 0.057 3.208
hiswrite/artcncl00:00:00.000 0.0%13 0.000 0.000 0.000
idle23:50:30.886100.0%83856 593.329 1023.551 3177.392
nntp read00:00:00.463 0.0%70772 0.000 0.007 0.021
overview write00:00:00.040 0.0%10541 0.000 0.004 0.030
perl filter00:00:13.022 0.0%12489 0.792 1.043 1.473
python filter00:00:00.332 0.0%12489 0.000 0.027 0.101
site send00:00:01.669 0.0%31625 0.000 0.053 0.111
TOTAL: 23:50:56.27123:50:49.099100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%10820 0.000 0.003 0.029
article prepare00:00:00.000 0.0%448 0.000 0.000 0.000
article read00:00:00.016 0.0%423 0.000 0.038 1.000
backlog stats00:00:00.040 0.0%59477 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.220 0.0%35228 0.000 0.006 0.022
data write00:00:00.693 0.0%24665 0.000 0.028 0.101
idle23:58:09.266100.0%59477 873.365 1450.801 3774.075
status file00:00:00.076 0.0%287 0.000 0.265 1.000
TOTAL: 23:58:16.63123:58:10.339100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:00.000 0.0%0 0.000 0.000 0.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%11 0.000 0.000 0.000
TOTAL: 00:00:00.66700:00:00.000 0.0%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reserve 2
TOTAL: 10 167

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
control@usenet-fr.news.eu.org001201
TOTAL001201

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.news.weretis.net 8 20561 9377 10274 910 45%46:48:33
2news.nntp4.net 5 13544 1276 11187 1081 9%26:29:51
3localhost 1 0 0 0 0 0%00:00:00
TOTAL: 3 14 34105 10653 21461 1991 31%73:18:24
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net32.1 MB0.0 KB4.0 MB36.1 MB 88%3.6 KB
2news.nntp4.net2.9 MB0.0 KB2.9 MB5.7 MB 50%2.5 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 334.9 MB0.0 KB6.9 MB41.8 MB 83%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 8 15:00:01 - 15:59:59 700 6.6% 0.191.9 MB 5.4% 0.54
May 8 16:00:00 - 16:59:59 667 6.3% 0.191.9 MB 5.5% 0.54
May 8 17:00:00 - 17:59:59 602 5.7% 0.171.7 MB 4.9% 0.49
May 8 18:00:00 - 18:59:59 600 5.6% 0.172.1 MB 5.9% 0.59
May 8 19:00:00 - 19:59:59 609 5.7% 0.172.0 MB 5.7% 0.57
May 8 20:00:00 - 20:59:59 479 4.5% 0.131.9 MB 5.4% 0.54
May 8 21:00:00 - 21:59:59 425 4.0% 0.121.7 MB 4.7% 0.47
May 8 22:00:00 - 22:59:59 354 3.3% 0.101.4 MB 4.0% 0.40
May 8 23:00:00 - 23:59:59 310 2.9% 0.091.1 MB 3.2% 0.31
May 9 00:00:00 - 00:59:59 296 2.8% 0.081.2 MB 3.4% 0.34
May 9 01:00:00 - 01:59:59 206 1.9% 0.06801.4 KB 2.2% 0.22
May 9 02:00:00 - 02:59:59 152 1.4% 0.04527.6 KB 1.5% 0.15
May 9 03:00:00 - 03:59:59 232 2.2% 0.06889.2 KB 2.5% 0.25
May 9 04:00:00 - 04:59:59 250 2.3% 0.071.2 MB 3.3% 0.33
May 9 05:00:00 - 05:59:59 254 2.4% 0.071.0 MB 3.0% 0.30
May 9 06:00:00 - 06:59:59 374 3.5% 0.101.3 MB 3.7% 0.37
May 9 07:00:00 - 07:59:59 446 4.2% 0.121.2 MB 3.3% 0.33
May 9 08:00:00 - 08:59:59 536 5.0% 0.151.6 MB 4.5% 0.45
May 9 09:00:00 - 09:59:59 500 4.7% 0.141.5 MB 4.4% 0.43
May 9 10:00:00 - 10:59:59 468 4.4% 0.131.4 MB 4.1% 0.41
May 9 11:00:00 - 11:59:59 492 4.6% 0.141.6 MB 4.5% 0.44
May 9 12:00:00 - 12:59:59 491 4.6% 0.141.7 MB 5.0% 0.50
May 9 13:00:00 - 13:59:59 592 5.6% 0.161.7 MB 4.7% 0.47
May 9 14:00:00 - 14:59:59 605 5.7% 0.171.8 MB 5.2% 0.51
May 9 15:00:00 - 15:00:02 3 0.0% 1.509.0 KB 0.0% 4.50
TOTAL: 24:00:01 10643 100.0% 0.1235.0 MB 100.0% 0.41
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
EIGHTBIG4.0 GB2.9 GB 72.6% 1 0.00 0.00
FIVEBIG4.0 GB2.9 GB 72.6% 1 0.00 0.00
FOURDEFAULT1024.0 MB240.4 MB 23.5% 21 0.17 73.05
ONEDEFAULT1024.0 MB402.8 MB 39.3% 23 0.16 75.38
SEVENBIG4.0 GB2.9 GB 72.6% 1 0.00 0.00
SIXBIG4.0 GB2.9 GB 72.8% 1 0.00 0.00
THREEDEFAULT1024.0 MB247.9 MB 24.2% 21 0.17 72.69
TWODEFAULT1024.0 MB234.6 MB 22.9% 21 0.17 72.69
TOTAL: 8-20.0 GB12.7 GB 63.6% 90 0.17-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1hasname-out.news.weretis.net 1117 8 0 0 0 0 0 0 1109
2news.nntp4.net 1080 5 0 0 1 0 0 0 1074
TOTAL: 2 2197 13 0 0 1 0 0 0 2183

Unwanted newsgroups [Top 20]:

NewsgroupCount
sybase.public.powerdesigner.general 5
panix.chat.politics 2
alt.business.import.export 2
alt.penthouse.sex.femdom 2
mailing.database.sql-general 1
alt.radio.free 1
TOTAL: 6 13

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 1072
EMP (md5) 640
EMP (phl) 138
EMP (phn nph) 115
Too many newsgroups (meow) 5
HTML Multipart 5
Scoring filter 4
TOTAL: 7 1979

INND no permission servers:

SystemConn
199.102.165.10 764
TOTAL: 1 764

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 192
news.nntp4.net 12
TOTAL: 2 204
TOTAL: 1 204

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 416 416 0 0 0 0100%19:49:20
2blueworldhosting 11968 4 9530 0 0 2 0%23:59:53
3weretis 1160 1 1135 3 0 0 0%22:45:55
4nntp4 10750 0 9471 0 0 199 0%23:59:53
5aioe 0 0 0 0 0 10610 0%23:59:53
TOTAL: 5 24294 421 20136 3 0 10811 1%114:34:54
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom1.9 MB0.0 KB1.9 MB0.0 KB/s4.8 KB19:49:20
2blueworldhosting33.9 KB0.0 KB33.9 KB0.0 KB/s8.5 KB23:59:53
3weretis2.0 KB16.3 KB18.3 KB0.0 KB/s4.6 KB22:45:55
4nntp40.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
5aioe0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 52.0 MB16.3 KB2.0 MB0.0 KB/s4.8 KB114:34:54
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
134.209.95.237 0.004 0.006 0.00000:00:00
64.226.86.7 0.002 0.004 0.00000:00:00
147.182.247.10 0.001 0.003 0.00000:00:00
scanner-29.ch1.censys-scanner.com 0.001 0.001 0.00000:00:00
159.65.111.107 0.000 0.002 0.00000:00:00
80.66.77.238 0.001 0.001 0.00000:00:00
TOTAL: 6 0.009 0.017 0.00000:00:00

NNRP no permission clients:

SystemConn
134.209.95.237 4
64.226.86.7 2
147.182.247.10 2
scanner-29.ch1.censys-scanner.com 1
159.65.111.107 1
80.66.77.238 1
TOTAL: 6 11
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>