Daily Usenet report

Oct 31 15:00:01 -- Nov 1 15:00:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Oct 31 15:01:12 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Nov 1 00:04:04 colo-sc-1 pgpverify[1517]: pgpverify: verification failed
Nov 1 03:17:11 colo-sc-1 innd: message repeated 2 times: [ 185.204.1.208 rejected 502]
Nov 1 03:17:12 colo-sc-1 innd: message repeated 3 times: [ 185.204.1.208 rejected 502]
Nov 1 06:45:03 colo-sc-1 pgpverify[21692]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 14339 76.6%1.7 MB 74.0%
innd 2533 13.5%353.2 KB 14.8%
innfeed 1201 6.4%167.0 KB 7.0%
cnfsstat 576 3.1%90.5 KB 3.8%
nnrpd 45 0.2%4.5 KB 0.2%
controlchan 33 0.2%7.7 KB 0.3%
pgpverify 2 0.0%0.2 KB 0.0%
overchan 1 0.0%0.1 KB 0.0%
TOTAL: 8 18730 100.0%2.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 40683 44.9%
Positive hits 35995 39.7%
Do not exist 13917 15.4%
Cache misses 32 0.0%
TOTAL: 4 90627 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.754 0.0%72 0.000 24.361 286.000
article cleanup00:00:00.088 0.0%14031 0.000 0.006 0.037
article logging00:00:00.393 0.0%14278 0.000 0.028 0.128
article parse00:00:00.255 0.0%24062 0.000 0.011 0.040
article write00:00:01.149 0.0%12932 0.000 0.089 2.661
artlog/artcncl00:00:00.000 0.0%72 0.000 0.000 0.000
data move00:00:00.045 0.0%98590 0.000 0.000 0.003
hisgrep/artcncl00:00:00.003 0.0%70 0.000 0.043 0.500
hishave/artcncl00:00:00.266 0.0%72 0.000 3.694 26.714
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:04:27.728 0.3%91311 0.002 2.932 69.525
history sync00:00:00.370 0.0%356 0.000 1.039 120.333
history write00:00:04.471 0.0%14030 0.000 0.319 16.209
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:55:25.353 99.6%97244 346.889 885.662 2969.267
nntp read00:00:00.757 0.0%97898 0.000 0.008 0.019
overview write00:00:00.044 0.0%12932 0.000 0.003 0.033
perl filter00:00:19.516 0.0%14030 0.800 1.391 23.613
python filter00:00:00.366 0.0%14030 0.000 0.026 0.128
site send00:00:02.363 0.0%38798 0.030 0.061 0.109
TOTAL: 24:00:48.58324:00:24.921100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%12903 0.000 0.002 0.030
article prepare00:00:00.001 0.0%677 0.000 0.001 0.111
article read00:00:00.020 0.0%502 0.000 0.040 1.000
backlog stats00:00:00.036 0.0%70079 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.335 0.0%43254 0.000 0.008 0.021
data write00:00:00.817 0.0%30597 0.000 0.027 0.077
idle23:58:16.139100.0%70079 576.938 1231.412 3469.913
status file00:00:00.491 0.0%234 0.000 2.098 73.400
TOTAL: 23:58:20.48123:58:17.867100.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.001 0.1%9 0.000 0.111 1.000
TOTAL: 00:00:01.66500:00:00.001 0.1%----

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 8 25192 9747 15131 314 38%50:36:39
2news-out.aioe.org 3 13256 2882 10374 0 21%25:53:54
3news.nntp4.net 1 13706 501 12464 741 3%24:07:04
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 13 52154 13130 37969 1055 25%100:37:37
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net33.4 MB0.0 KB653.5 KB34.0 MB 98%3.5 KB
2news-out.aioe.org7.4 MB0.0 KB0.0 KB7.4 MB100%2.6 KB
3news.nntp4.net1.3 MB58.5 KB2.1 MB3.4 MB 38%2.8 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 442.1 MB58.5 KB2.7 MB44.8 MB 93%3.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 31 15:00:01 - 15:59:59 786 6.1% 0.222.2 MB 5.2% 0.62
Oct 31 16:00:00 - 16:59:59 781 6.0% 0.222.2 MB 5.2% 0.61
Oct 31 17:00:00 - 17:59:59 753 5.8% 0.212.0 MB 4.9% 0.57
Oct 31 18:00:00 - 18:59:59 732 5.7% 0.202.1 MB 5.0% 0.59
Oct 31 19:00:00 - 19:59:59 691 5.3% 0.193.3 MB 8.0% 0.95
Oct 31 20:00:00 - 20:59:59 703 5.4% 0.202.0 MB 4.7% 0.56
Oct 31 21:00:00 - 21:59:59 676 5.2% 0.192.4 MB 5.7% 0.67
Oct 31 22:00:00 - 22:59:59 496 3.8% 0.141.7 MB 4.0% 0.48
Oct 31 23:00:00 - 23:59:59 396 3.1% 0.111.8 MB 4.4% 0.52
Nov 1 00:00:00 - 00:59:59 356 2.8% 0.101.1 MB 2.6% 0.31
Nov 1 01:00:00 - 01:59:59 314 2.4% 0.09964.5 KB 2.3% 0.27
Nov 1 02:00:00 - 02:59:59 237 1.8% 0.071.2 MB 3.0% 0.35
Nov 1 03:00:00 - 03:59:59 264 2.0% 0.071.5 MB 3.5% 0.42
Nov 1 04:00:00 - 04:59:59 286 2.2% 0.08844.0 KB 2.0% 0.23
Nov 1 05:00:00 - 05:59:59 340 2.6% 0.091.3 MB 3.1% 0.36
Nov 1 06:00:00 - 06:59:59 359 2.8% 0.101.2 MB 2.9% 0.34
Nov 1 07:00:00 - 07:59:59 414 3.2% 0.121.2 MB 3.0% 0.35
Nov 1 08:00:00 - 08:59:59 520 4.0% 0.141.6 MB 3.8% 0.45
Nov 1 09:00:00 - 09:59:59 596 4.6% 0.171.7 MB 4.2% 0.49
Nov 1 10:00:00 - 10:59:59 639 4.9% 0.181.7 MB 4.1% 0.49
Nov 1 11:00:00 - 11:59:59 549 4.2% 0.151.6 MB 3.8% 0.44
Nov 1 12:00:00 - 12:59:59 631 4.9% 0.181.6 MB 4.0% 0.47
Nov 1 13:00:00 - 13:59:59 704 5.4% 0.202.4 MB 5.8% 0.68
Nov 1 14:00:00 - 14:59:59 695 5.4% 0.192.0 MB 4.9% 0.57
Nov 1 15:00:00 - 15:00:02 1 0.0% 0.505.3 KB 0.0% 2.67
TOTAL: 24:00:01 12919 100.0% 0.1541.5 MB 100.0% 0.49
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
FOURDEFAULT1024.0 MB778.8 MB 76.1% 3 0.20 59.64
ONEDEFAULT1024.0 MB940.3 MB 91.8% 5 0.20 60.35
THREEDEFAULT1024.0 MB781.9 MB 76.4% 3 0.20 60.53
TWODEFAULT1024.0 MB774.3 MB 75.6% 3 0.20 60.24
TOTAL: 4-4.0 GB3.2 GB 80.0% 14 0.20-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 793 13 0 1 1 0 0 0 778
2hasname-out.news.weretis.net 533 8 0 0 0 0 0 0 525
3news-out.aioe.org 22 0 0 0 0 0 0 0 22
TOTAL: 3 1348 21 0 1 1 0 0 0 1325

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.alt.alt 11
wolfsburg.test 4
comp.infosystems.gemini 2
alt.bestjobsusa.philly.jobs 1
alt.bestjobsusa.jobs.offered 1
mailing.freebsd.cvs 1
biz.marketplace 1
TOTAL: 7 21

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) 470
EMP (md5) 268
EMP (phn nph) 175
EMP (phl) 106
User-issued spam cancel 34
Too many newsgroups 15
HTML Multipart 8
HTML post 1
TOTAL: 8 1077

INND no permission servers:

SystemConn
185.204.1.208 2
197.248.146.186 1
TOTAL: 2 3

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 226
news-out.aioe.org 22
TOTAL: 2 248
TOTAL: 1 248

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 443 443 0 0 0 0100%19:50:59
2aioe 11806 38 9954 0 0 6 0%23:50:00
3nntp4 14388 3 12336 0 0 0 0%23:51:58
4weretis 3246 0 3137 17 0 0 0%23:57:19
TOTAL: 4 29883 484 25427 17 0 6 1%91:30:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom2.0 MB0.0 KB2.0 MB0.0 KB/s4.7 KB19:50:59
2aioe149.8 KB0.0 KB149.8 KB0.0 KB/s3.9 KB23:50:00
3nntp45.8 KB0.0 KB5.8 KB0.0 KB/s1.9 KB23:51:58
4weretis0.0 KB121.7 KB121.7 KB0.0 KB/s7.2 KB23:57:19
TOTAL: 42.2 MB121.7 KB2.3 MB0.0 KB/s4.7 KB91:30:16
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
185.204.1.208 0.002 0.017 0.00000:00:01
165.232.146.58 0.002 0.002 0.00000:00:00
23.129.64.188 0.001 0.001 0.00000:00:00
TOTAL: 3 0.005 0.020 0.00000:00:01

NNRP no permission clients:

SystemConn
185.204.1.208 7
23.129.64.188 1
165.232.146.58 1
TOTAL: 3 9