Daily Usenet report

Aug 31 15:00:01 -- Sep 1 15:00:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Aug 31 15:01:13 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Sep 1 00:04:02 colo-sc-1 pgpverify[31993]: pgpverify: verification failed
Sep 1 08:45:03 colo-sc-1 pgpverify[9803]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 16443 77.1%2.0 MB 74.4%
innd 3128 14.7%438.3 KB 16.3%
innfeed 1148 5.4%164.7 KB 6.1%
cnfsstat 576 2.7%77.0 KB 2.9%
controlchan 33 0.2%7.8 KB 0.3%
nnrpd 9 0.0%0.9 KB 0.0%
pgpverify 2 0.0%0.3 KB 0.0%
TOTAL: 7 21339 100.0%2.6 MB100.0%

History cache:

ReasonCount%Count
Negative hits 44542 43.6%
Positive hits 41608 40.7%
Do not exist 16059 15.7%
Cache misses 33 0.0%
TOTAL: 4 102242 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.379 0.0%74 0.000 32.149 446.000
article cleanup00:00:00.094 0.0%16163 0.000 0.006 0.034
article logging00:00:00.568 0.0%16378 0.000 0.035 0.128
article parse00:00:00.253 0.0%29037 0.000 0.009 0.037
article write00:00:06.315 0.0%14440 0.000 0.437 39.727
artlog/artcncl00:00:00.002 0.0%73 0.000 0.027 0.167
data move00:00:00.050 0.0%112537 0.000 0.000 0.004
hisgrep/artcncl00:00:00.001 0.0%71 0.000 0.014 0.143
hishave/artcncl00:00:01.101 0.0%74 0.000 14.878 95.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:06:37.355 0.5%102852 0.003 3.863 74.760
history sync00:00:00.017 0.0%350 0.000 0.049 1.000
history write00:00:06.877 0.0%16156 0.000 0.426 23.650
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle23:53:04.642 99.5%111156 318.885 773.549 2840.678
nntp read00:00:00.853 0.0%112313 0.000 0.008 0.019
overview write00:00:00.055 0.0%14440 0.000 0.004 0.024
perl filter00:00:23.827 0.0%16156 0.752 1.475 14.163
python filter00:00:00.473 0.0%16156 0.000 0.029 0.109
site send00:00:03.014 0.0%43323 0.018 0.070 0.113
TOTAL: 24:00:52.88724:00:27.876100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.041 0.0%14409 0.000 0.003 0.022
article prepare00:00:00.000 0.0%1276 0.000 0.000 0.000
article read00:00:00.189 0.0%776 0.000 0.244 32.000
backlog stats00:00:00.075 0.0%81989 0.000 0.001 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.427 0.0%50023 0.000 0.009 0.025
data write00:00:01.075 0.0%35781 0.000 0.030 0.088
idle23:57:58.589100.0%81989 442.127 1052.319 3186.402
status file00:00:00.061 0.0%207 0.000 0.295 1.000
TOTAL: 23:58:03.65423:58:00.457100.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%2 0.000 0.000 0.000
TOTAL: 00:00:00.60300: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
moderator@dana.de001101
news@sfere.anjou.terraraq.org.uk001001
us-control@lists.killfile.org000100
TOTAL002202

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.news.weretis.net 4 28042 8909 18638 495 31%48:35:44
2news-out.aioe.org 3 14375 5126 9248 1 35%24:21:42
3news.nntp4.net 1 16474 525 14700 1249 3%24:45:37
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 9 58891 14560 42586 1745 24%97:43:03
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net29.2 MB19.9 KB1.3 MB30.5 MB 95%3.3 KB
2news-out.aioe.org13.5 MB0.0 KB1.1 KB13.5 MB 99%2.7 KB
3news.nntp4.net1.7 MB3.0 KB3.5 MB5.2 MB 32%3.0 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 444.4 MB22.9 KB4.8 MB49.2 MB 90%3.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 31 15:00:01 - 15:59:59 831 5.8% 0.232.3 MB 5.3% 0.66
Aug 31 16:00:00 - 16:59:59 811 5.6% 0.232.3 MB 5.3% 0.66
Aug 31 17:00:00 - 17:59:59 855 5.9% 0.242.6 MB 5.9% 0.74
Aug 31 18:00:00 - 18:59:59 645 4.5% 0.181.9 MB 4.4% 0.55
Aug 31 19:00:00 - 19:59:59 754 5.2% 0.212.3 MB 5.2% 0.66
Aug 31 20:00:00 - 20:59:59 834 5.8% 0.232.4 MB 5.4% 0.68
Aug 31 21:00:00 - 21:59:59 619 4.3% 0.171.8 MB 4.1% 0.52
Aug 31 22:00:00 - 22:59:59 558 3.9% 0.152.1 MB 4.7% 0.59
Aug 31 23:00:00 - 23:59:59 452 3.1% 0.131.5 MB 3.4% 0.43
Sep 1 00:00:00 - 00:59:59 384 2.7% 0.111.4 MB 3.2% 0.40
Sep 1 01:00:00 - 01:59:59 277 1.9% 0.08928.3 KB 2.1% 0.26
Sep 1 02:00:00 - 02:59:59 282 2.0% 0.081.1 MB 2.6% 0.32
Sep 1 03:00:00 - 03:59:59 237 1.6% 0.07858.3 KB 1.9% 0.24
Sep 1 04:00:00 - 04:59:59 320 2.2% 0.091.0 MB 2.4% 0.30
Sep 1 05:00:00 - 05:59:59 314 2.2% 0.091.2 MB 2.7% 0.34
Sep 1 06:00:00 - 06:59:59 450 3.1% 0.121.5 MB 3.3% 0.42
Sep 1 07:00:00 - 07:59:59 555 3.8% 0.151.7 MB 3.9% 0.49
Sep 1 08:00:00 - 08:59:59 744 5.2% 0.212.0 MB 4.5% 0.57
Sep 1 09:00:00 - 09:59:59 726 5.0% 0.202.1 MB 4.8% 0.60
Sep 1 10:00:00 - 10:59:59 715 5.0% 0.202.1 MB 4.8% 0.60
Sep 1 11:00:00 - 11:59:59 700 4.9% 0.192.0 MB 4.6% 0.58
Sep 1 12:00:00 - 12:59:59 646 4.5% 0.181.8 MB 4.2% 0.52
Sep 1 13:00:00 - 13:59:59 847 5.9% 0.242.4 MB 5.4% 0.68
Sep 1 14:00:00 - 14:59:59 869 6.0% 0.242.7 MB 6.0% 0.75
Sep 1 15:00:00 - 15:00:02 5 0.0% 2.5013.8 KB 0.0% 6.89
TOTAL: 24:00:01 14430 100.0% 0.1744.1 MB 100.0% 0.52
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
FOURDEFAULT1024.0 MB755.9 MB 73.8% 2 0.22 55.39
ONEDEFAULT1024.0 MB917.3 MB 89.6% 4 0.22 55.12
THREEDEFAULT1024.0 MB759.9 MB 74.2% 2 0.22 56.40
TWODEFAULT1024.0 MB754.9 MB 73.7% 2 0.22 55.90
TOTAL: 4-4.0 GB3.1 GB 77.8% 10 0.22-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 1230 3 0 1 1 0 0 0 1225
2hasname-out.news.weretis.net 628 6 0 6 0 0 0 0 616
3news-out.aioe.org 82 0 0 0 0 0 0 0 82
TOTAL: 3 1940 9 0 7 1 0 0 0 1923

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.math 2
oecher.wohnungsmarkt 1
alt.business.career-opportunities.executives 1
list.postfix.users 1
alt.business.import-export.biz.marketplace.international 1
alt.biz.misc 1
alt.bestjobsusa.florida.jobs 1
panix.chat.politics 1
TOTAL: 8 9

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) 869
EMP (md5) 469
EMP (phn nph) 241
EMP (phl) 55
Too many newsgroups 46
User-issued spam cancel 22
Scoring filter 5
HTML Multipart 2
HTML post 1
TOTAL: 9 1710

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 130
news-out.aioe.org 81
news.nntp4.net 2
TOTAL: 3 213
TOTAL: 1 213

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 615 615 0 0 0 0100%21:38:19
2aioe 11973 51 9201 0 0 0 0%23:59:57
3nntp4 16549 2 13781 0 0 3 0%23:50:02
4weretis 5714 0 5402 114 0 0 0%23:50:19
TOTAL: 4 34851 668 28384 114 0 3 1%93:18:37
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom2.2 MB0.0 KB2.2 MB0.0 KB/s3.7 KB21:38:19
2aioe221.9 KB0.0 KB221.9 KB0.0 KB/s4.4 KB23:59:57
3nntp46.0 KB0.0 KB6.0 KB0.0 KB/s3.0 KB23:50:02
4weretis0.0 KB453.7 KB453.7 KB0.0 KB/s4.0 KB23:50:19
TOTAL: 42.4 MB453.7 KB2.9 MB0.0 KB/s3.8 KB93:18:37
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
news.nntp4.net 0.001 0.002 0.00000:00:00
185.220.101.227 0.000 0.003 0.00000:00:00
TOTAL: 2 0.001 0.005 0.00000:00:00

NNRP no permission clients:

SystemConn
news.nntp4.net 1
185.220.101.227 1
TOTAL: 2 2