Daily Usenet report

Apr 30 15:00:02 -- May 1 15:00:01

Unknown entries from news log file:

First 50 / 722 lines (6.9%)

Apr 30 15:00:55 colo-sc-1 innd: message repeated 8 times: [ 199.102.165.10 rejected 502]
Apr 30 15:01:08 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Apr 30 15:02:55 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:04:55 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:07:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:08:55 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:10:55 colo-sc-1 innd: message repeated 8 times: [ 199.102.165.10 rejected 502]
Apr 30 15:13:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:15:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:17:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:19:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:21:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:23:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:25:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:27:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:29:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:31:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:33:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:35:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:37:05 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:39:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:41:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:43:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:45:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:47:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:49:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:51:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:53:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:55:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:57:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 15:59:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:01:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:03:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:05:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:07:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:09:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:11:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:13:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:15:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:17:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:19:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:21:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:23:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:25:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:27:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:29:05 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:31:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:33:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:35:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]
Apr 30 16:37:00 colo-sc-1 innd: message repeated 9 times: [ 199.102.165.10 rejected 502]

Log entries by program:

Program nameLines%LinesSize%Size
inn 12517 62.6%1.7 MB 64.9%
innd 4680 23.4%543.6 KB 20.6%
innfeed 1585 7.9%197.8 KB 7.5%
cnfsstat 1152 5.8%176.0 KB 6.7%
controlchan 33 0.2%7.8 KB 0.3%
nnrpd 25 0.1%2.5 KB 0.1%
overchan 2 0.0%0.1 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 8 19995 100.0%2.6 MB100.0%

History cache:

ReasonCount%Count
Negative hits 26816 45.0%
Positive hits 20620 34.6%
Do not exist 12143 20.4%
Cache misses 30 0.1%
TOTAL: 4 59609 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.192 0.0%127 0.000 1.512 7.800
article cleanup00:00:00.075 0.0%12201 0.000 0.006 0.057
article logging00:00:00.287 0.0%12424 0.000 0.023 0.065
article parse00:00:00.187 0.0%22921 0.000 0.008 0.036
article write00:00:00.623 0.0%10497 0.000 0.059 2.016
artlog/artcncl00:00:00.001 0.0%127 0.000 0.008 0.091
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.045 0.0%70670 0.000 0.001 0.007
hisgrep/artcncl00:00:00.000 0.0%72 0.000 0.000 0.000
hishave/artcncl00:00:00.001 0.0%127 0.000 0.008 0.091
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:11.247 0.0%60058 0.000 0.187 1.566
history sync00:00:00.013 0.0%415 0.000 0.031 0.333
history write00:00:01.489 0.0%12201 0.000 0.122 7.683
hiswrite/artcncl00:00:00.004 0.0%55 0.000 0.073 1.000
idle24:00:26.336100.0%83470 543.216 1035.418 2766.442
nntp read00:00:00.468 0.0%70003 0.000 0.007 0.024
overview write00:00:00.034 0.0%10497 0.000 0.003 0.048
perl filter00:00:13.171 0.0%12201 0.747 1.080 1.736
python filter00:00:00.318 0.0%12201 0.000 0.026 0.100
site send00:00:01.496 0.0%31494 0.020 0.048 0.118
TOTAL: 24:01:02.50724:00:55.987100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.015 0.0%10464 0.000 0.001 0.027
article prepare00:00:00.000 0.0%270 0.000 0.000 0.000
article read00:00:00.013 0.0%235 0.000 0.055 1.000
backlog stats00:00:00.034 0.0%57551 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.212 0.0%33567 0.000 0.006 0.027
data write00:00:00.629 0.0%23179 0.000 0.027 0.088
idle23:57:09.526100.0%57551 818.495 1498.315 4697.109
status file00:00:00.568 0.0%364 0.000 1.560 217.500
TOTAL: 23:57:12.54223:57:10.997100.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%5 0.000 0.000 0.000
TOTAL: 00:00:00.19200:00:00.000 0.0%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
moderator@dana.de001101
us-control@lists.killfile.org000100
TOTAL002202

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.news.weretis.net 10 20391 9228 10394 769 45%47:34:01
2news.nntp4.net 2 11819 1037 9862 920 8%23:57:02
3localhost 1 0 0 0 0 0%00:00:00
TOTAL: 3 13 32210 10265 20256 1689 31%71:31:03
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net33.0 MB0.0 KB3.2 MB36.2 MB 91%3.7 KB
2news.nntp4.net2.7 MB0.0 KB3.5 MB6.2 MB 43%3.3 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 335.8 MB0.0 KB6.7 MB42.4 MB 84%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 30 15:00:02 - 15:59:59 595 5.7% 0.172.2 MB 6.2% 0.63
Apr 30 16:00:00 - 16:59:59 471 4.5% 0.131.4 MB 3.9% 0.40
Apr 30 17:00:00 - 17:59:59 435 4.2% 0.121.3 MB 3.5% 0.37
Apr 30 18:00:00 - 18:59:59 526 5.0% 0.151.6 MB 4.3% 0.44
Apr 30 19:00:00 - 19:59:59 486 4.6% 0.141.5 MB 4.2% 0.44
Apr 30 20:00:00 - 20:59:59 492 4.7% 0.141.6 MB 4.4% 0.46
Apr 30 21:00:00 - 21:59:59 408 3.9% 0.111.3 MB 3.6% 0.37
Apr 30 22:00:00 - 22:59:59 353 3.4% 0.101.3 MB 3.6% 0.37
Apr 30 23:00:00 - 23:59:59 344 3.3% 0.101.3 MB 3.7% 0.38
May 1 00:00:00 - 00:59:59 350 3.3% 0.101.3 MB 3.7% 0.38
May 1 01:00:00 - 01:59:59 247 2.4% 0.071.1 MB 3.0% 0.31
May 1 02:00:00 - 02:59:59 228 2.2% 0.061.2 MB 3.3% 0.34
May 1 03:00:00 - 03:59:59 203 1.9% 0.06755.4 KB 2.0% 0.21
May 1 04:00:00 - 04:59:59 230 2.2% 0.061.0 MB 2.9% 0.30
May 1 05:00:00 - 05:59:59 218 2.1% 0.06707.1 KB 1.9% 0.20
May 1 06:00:00 - 06:59:59 393 3.8% 0.111.7 MB 4.8% 0.49
May 1 07:00:00 - 07:59:59 426 4.1% 0.121.4 MB 3.9% 0.40
May 1 08:00:00 - 08:59:59 570 5.4% 0.161.7 MB 4.6% 0.47
May 1 09:00:00 - 09:59:59 530 5.1% 0.151.7 MB 4.7% 0.48
May 1 10:00:00 - 10:59:59 565 5.4% 0.162.0 MB 5.6% 0.58
May 1 11:00:00 - 11:59:59 610 5.8% 0.172.0 MB 5.4% 0.56
May 1 12:00:00 - 12:59:59 501 4.8% 0.142.2 MB 6.0% 0.61
May 1 13:00:00 - 13:59:59 654 6.2% 0.182.1 MB 5.8% 0.60
May 1 14:00:00 - 14:59:59 629 6.0% 0.171.7 MB 4.7% 0.49
May 1 15:00:00 - 15:00:01 1 0.0% 1.001.3 KB 0.0% 1.27
TOTAL: 23:59:59 10465 100.0% 0.1236.2 MB 100.0% 0.43
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 MB127.4 MB 12.4% 21 0.17 72.64
ONEDEFAULT1024.0 MB290.0 MB 28.3% 23 0.17 71.01
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 MB134.8 MB 13.2% 21 0.17 72.79
TWODEFAULT1024.0 MB121.4 MB 11.9% 21 0.17 70.52
TOTAL: 8-20.0 GB12.3 GB 61.4% 90 0.17-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1hasname-out.news.weretis.net 993 5 0 0 0 0 0 0 988
2news.nntp4.net 932 0 0 0 1 0 0 0 931
TOTAL: 2 1925 5 0 0 1 0 0 0 1919

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.creative-cooking 3
de.comm.geraete.mobil 2
TOTAL: 2 5

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

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 708
EMP (phn path) 548
EMP (phl) 249
EMP (phn nph) 155
Binary: misplaced binary 15
HTML Multipart 8
Scoring filter 6
Too many newsgroups (meow) 4
Unapproved newgroup message 1
hierarchy violation - crosspost outside sdnet 1
Malformed article 1
TOTAL: 11 1696

INND no permission servers:

SystemConn
199.102.165.10 749
TOTAL: 1 749

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 217
news.nntp4.net 6
TOTAL: 2 223
TOTAL: 1 223

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 226 226 0 0 0 0100%15:50:30
2blueworldhosting 10435 2 8400 0 0 22 0%23:59:55
3nntp4 11125 0 9434 0 0 0 0%23:59:50
4weretis 1014 0 990 9 0 0 0%22:30:51
5aioe 0 0 0 0 0 10399 0%23:59:55
TOTAL: 5 22800 228 18824 9 0 10421 1%110:21:01
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom1.4 MB0.0 KB1.4 MB0.0 KB/s6.6 KB15:50:30
2blueworldhosting48.0 KB0.0 KB48.0 KB0.0 KB/s24.0 KB23:59:55
3nntp40.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
4weretis0.0 KB35.5 KB35.5 KB0.0 KB/s3.9 KB22:30:51
5aioe0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
TOTAL: 51.5 MB35.5 KB1.5 MB0.0 KB/s6.6 KB110:21:01
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
107.150.102.211 0.002 0.002 0.00000:00:00
198.199.118.88 0.001 0.002 0.00000:00:00
104.152.52.199 0.000 0.002 0.00000:00:00
104.152.52.93 0.000 0.002 0.00000:00:00
TOTAL: 4 0.003 0.008 0.00000:00:00

NNRP no permission clients:

SystemConn
107.150.102.211 2
104.152.52.199 1
198.199.118.88 1
104.152.52.93 1
TOTAL: 4 5
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>