Daily Usenet report

Aug 1 15:00:23 -- Aug 2 15:00:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 1 15:03:09 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Aug 2 06:13:35 colo-sc-1 pgpverify[23659]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 15640 77.9%1.9 MB 75.2%
innd 2603 13.0%363.9 KB 14.2%
innfeed 1242 6.2%187.6 KB 7.3%
cnfsstat 576 2.9%80.9 KB 3.2%
controlchan 12 0.1%2.8 KB 0.1%
nnrpd 4 0.0%0.4 KB 0.0%
overchan 2 0.0%0.2 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 8 20080 100.0%2.5 MB100.0%

History cache:

ReasonCount%Count
Negative hits 47019 46.6%
Positive hits 38598 38.2%
Do not exist 14835 14.7%
Cache misses 460 0.5%
TOTAL: 4 100912 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:07.748 0.0%742 0.000 10.442 70.697
article cleanup00:00:00.111 0.0%14661 0.000 0.008 0.043
article logging00:00:00.458 0.0%14885 0.000 0.031 0.111
article parse00:00:00.216 0.0%27238 0.000 0.008 0.039
article write00:00:18.028 0.0%13396 0.000 1.346 33.014
artlog/artcncl00:00:00.005 0.0%739 0.000 0.007 0.008
data move00:00:00.065 0.0%109423 0.000 0.001 0.003
hisgrep/artcncl00:00:00.003 0.0%466 0.000 0.006 0.010
hishave/artcncl00:00:02.326 0.0%742 0.000 3.135 5.616
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:00.819 0.1%100867 0.000 0.603 15.884
history sync00:00:00.255 0.0%334 0.000 0.763 121.500
history write00:00:17.267 0.0%14661 0.000 1.178 26.273
hiswrite/artcncl00:00:00.005 0.0%276 0.000 0.018 0.020
idle23:57:55.522 99.8%108676 316.431 793.878 2067.645
nntp read00:00:00.723 0.0%108978 0.000 0.007 0.016
overview write00:00:00.036 0.0%13396 0.000 0.003 0.026
perl filter00:00:18.910 0.0%14661 0.530 1.290 8.387
python filter00:00:00.410 0.0%14661 0.000 0.028 0.095
site send00:00:02.369 0.0%40188 0.030 0.059 0.114
TOTAL: 24:00:41.25324:00:05.276100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.022 0.0%13464 0.000 0.002 0.036
article prepare00:00:00.000 0.0%548 0.000 0.000 0.000
article read00:00:00.274 0.0%396 0.000 0.692 27.000
backlog stats00:00:00.044 0.0%61257 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.294 0.0%41125 0.000 0.007 0.025
data write00:00:00.748 0.0%28211 0.000 0.027 0.075
idle23:57:22.494100.0%61257 371.488 1407.880 3055.345
status file00:00:18.008 0.0%256 0.000 70.344 776.467
TOTAL: 23:57:43.54123:57:41.884100.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%1 0.000 0.000 0.000
TOTAL: 00:00:00.50700: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.org001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.news.weretis.net 4 24726 8290 16061 375 33%46:43:42
2news-out.aioe.org 2 13553 4582 8950 21 33%24:44:08
3news.nntp4.net 2 14571 520 13165 886 3%24:14:41
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 9 52850 13392 38176 1282 25%95:42:31
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net26.2 MB0.0 KB639.4 KB26.9 MB 97%3.2 KB
2news-out.aioe.org12.9 MB0.0 KB27.1 KB12.9 MB 99%2.9 KB
3news.nntp4.net2.1 MB0.0 KB2.6 MB4.8 MB 44%3.5 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 441.3 MB0.0 KB3.3 MB44.5 MB 92%3.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 1 15:00:23 - 15:59:59 721 5.4% 0.202.0 MB 4.8% 0.57
Aug 1 16:00:00 - 16:59:59 728 5.4% 0.202.2 MB 5.3% 0.63
Aug 1 17:00:00 - 17:59:59 777 5.8% 0.222.1 MB 4.9% 0.58
Aug 1 18:00:00 - 18:59:59 792 5.9% 0.222.4 MB 5.8% 0.69
Aug 1 19:00:00 - 19:59:59 647 4.8% 0.181.7 MB 4.1% 0.48
Aug 1 20:00:00 - 20:59:59 570 4.2% 0.161.7 MB 4.1% 0.48
Aug 1 21:00:00 - 21:59:59 609 4.5% 0.172.0 MB 4.8% 0.56
Aug 1 22:00:00 - 22:59:59 401 3.0% 0.111.4 MB 3.4% 0.40
Aug 1 23:00:00 - 23:59:59 328 2.4% 0.091.2 MB 2.9% 0.34
Aug 2 00:00:00 - 00:59:59 300 2.2% 0.081.1 MB 2.7% 0.32
Aug 2 01:00:00 - 01:59:59 276 2.1% 0.081.2 MB 2.8% 0.34
Aug 2 02:00:00 - 02:59:59 248 1.8% 0.07876.4 KB 2.1% 0.24
Aug 2 03:00:00 - 03:59:59 286 2.1% 0.08975.2 KB 2.3% 0.27
Aug 2 04:00:00 - 04:59:59 636 4.7% 0.181.5 MB 3.6% 0.43
Aug 2 05:00:00 - 05:59:59 599 4.5% 0.172.7 MB 6.5% 0.76
Aug 2 06:00:00 - 06:59:59 419 3.1% 0.121.3 MB 3.2% 0.38
Aug 2 07:00:00 - 07:59:59 463 3.5% 0.131.7 MB 4.2% 0.50
Aug 2 08:00:00 - 08:59:59 653 4.9% 0.181.6 MB 3.8% 0.45
Aug 2 09:00:00 - 09:59:59 657 4.9% 0.181.8 MB 4.4% 0.52
Aug 2 10:00:00 - 10:59:59 612 4.6% 0.171.9 MB 4.6% 0.55
Aug 2 11:00:00 - 11:59:59 591 4.4% 0.161.7 MB 4.0% 0.48
Aug 2 12:00:00 - 12:59:59 609 4.5% 0.171.8 MB 4.3% 0.50
Aug 2 13:00:00 - 13:59:59 716 5.3% 0.202.0 MB 4.9% 0.58
Aug 2 14:00:00 - 14:59:59 773 5.8% 0.212.7 MB 6.5% 0.76
Aug 2 15:00:00 - 15:00:02 1 0.0% 0.502.7 KB 0.0% 1.33
TOTAL: 23:59:39 13412 100.0% 0.1641.6 MB 100.0% 0.49
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
FOURDEFAULT1024.0 MB240.1 MB 23.4% 2 0.20 59.29
ONEDEFAULT1024.0 MB402.3 MB 39.3% 4 0.20 59.99
THREEDEFAULT1024.0 MB244.7 MB 23.9% 2 0.20 60.82
TWODEFAULT1024.0 MB242.0 MB 23.6% 2 0.21 58.71
TOTAL: 4-4.0 GB1.1 GB 27.6% 10 0.20-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 862 11 0 0 1 0 0 0 850
2hasname-out.news.weretis.net 541 1 0 0 0 0 0 0 540
3news-out.aioe.org 82 1 0 0 0 0 0 0 81
TOTAL: 3 1485 13 0 0 1 0 0 0 1471

Unwanted newsgroups [Top 20]:

NewsgroupCount
nanaimo.general 4
alt.health.policy.drug-approval 2
alt.math 1
alt.religion.jewish 1
alt.exploding.barney 1
soc.culture.germany 1
alt.french 1
jaring.general 1
alt.soc.germans 1
TOTAL: 9 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) 633
EMP (md5) 355
EMP (phn nph) 217
User-issued spam cancel 20
HTML Multipart 12
EMP (phl) 4
Binary: misplaced binary 3
Scoring filter 1
HTML post 1
Too many newsgroups (meow) 1
TOTAL: 10 1247

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 163
news-out.aioe.org 61
TOTAL: 2 224
TOTAL: 1 224

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 349 349 0 0 0 0100%18:30:36
2aioe 9093 14 8747 2 0 12 0%23:59:29
3nntp4 13248 1 12894 0 0 74 0%23:59:39
4weretis 5185 0 4912 33 0 0 0%23:59:40
TOTAL: 4 27875 364 26553 35 0 86 1%90:29:24
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom1.5 MB0.0 KB1.5 MB0.0 KB/s4.4 KB18:30:36
2aioe42.3 KB4.9 KB47.2 KB0.0 KB/s2.9 KB23:59:29
3nntp43.5 KB0.0 KB3.5 KB0.0 KB/s3.5 KB23:59:39
4weretis0.0 KB143.2 KB143.2 KB0.0 KB/s4.3 KB23:59:40
TOTAL: 41.5 MB148.1 KB1.7 MB0.0 KB/s4.3 KB90:29:24
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
news.nntp4.net 0.000 0.003 0.00000:00:00
TOTAL: 1 0.000 0.003 0.00000:00:00

NNRP no permission clients:

SystemConn
news.nntp4.net 1
TOTAL: 1 1