Daily Usenet report

Nov 25 15:00:02 -- Nov 26 15:00:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Nov 25 15:01:13 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Nov 25 21:00:36 colo-sc-1 pgpverify[16443]: pgpverify: verification failed
Nov 25 21:00:36 colo-sc-1 controlchan[4321]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20211125210001$d99d@isc.org>
Nov 25 21:00:36 colo-sc-1 pgpverify[16488]: pgpverify: verification failed
Nov 25 21:00:36 colo-sc-1 controlchan[4321]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20211125210002$88e3@isc.org>

Log entries by program:

Program nameLines%LinesSize%Size
inn 15829 72.5%1.9 MB 70.2%
innd 4159 19.0%553.0 KB 20.2%
innfeed 1258 5.8%172.7 KB 6.3%
cnfsstat 576 2.6%85.9 KB 3.1%
nnrpd 13 0.1%1.3 KB 0.0%
controlchan 3 0.0%0.2 KB 0.0%
pgpverify 2 0.0%0.3 KB 0.0%
overchan 2 0.0%0.3 KB 0.0%
TOTAL: 8 21842 100.0%2.7 MB100.0%

History cache:

ReasonCount%Count
Negative hits 42463 44.1%
Positive hits 38321 39.8%
Do not exist 15424 16.0%
Cache misses 39 0.0%
TOTAL: 4 96247 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:14.023 0.0%85 0.000 164.976 4572.000
article cleanup00:00:00.099 0.0%15527 0.000 0.006 0.033
article logging00:00:00.438 0.0%15757 0.000 0.028 0.091
article parse00:00:00.283 0.0%29753 0.000 0.010 0.046
article write00:00:01.920 0.0%12895 0.000 0.149 2.815
artlog/artcncl00:00:00.002 0.0%85 0.000 0.024 0.071
data move00:00:00.068 0.0%107959 0.000 0.001 0.004
hisgrep/artcncl00:00:00.031 0.0%84 0.000 0.369 1.273
hishave/artcncl00:00:08.093 0.0%85 0.000 95.212 2351.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:05:13.326 0.4%96807 0.003 3.237 61.993
history sync00:00:00.016 0.0%387 0.000 0.041 1.000
history write00:00:03.664 0.0%15525 0.000 0.236 6.457
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:54:51.776 99.6%106866 431.038 805.605 2598.065
nntp read00:00:00.937 0.0%107874 0.002 0.009 0.018
overview write00:00:00.057 0.0%12895 0.000 0.004 0.037
perl filter00:00:19.314 0.0%15525 0.778 1.244 7.633
python filter00:00:00.400 0.0%15525 0.000 0.026 0.075
site send00:00:02.401 0.0%38686 0.030 0.062 0.118
TOTAL: 24:01:05.38524:00:56.848100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.034 0.0%12870 0.000 0.003 0.024
article prepare00:00:00.000 0.0%662 0.000 0.000 0.000
article read00:00:00.017 0.0%410 0.000 0.041 1.000
backlog stats00:00:00.046 0.0%72990 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.364 0.0%44085 0.000 0.008 0.031
data write00:00:00.970 0.0%31494 0.000 0.031 0.080
idle23:58:11.737100.0%72990 614.603 1182.241 3550.917
status file00:00:03.934 0.0%253 0.000 15.549 651.400
TOTAL: 23:58:19.18423:58:17.102100.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%3 0.000 0.000 0.000
TOTAL: 00:00:00.06800:00:00.000 0.0%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.news.weretis.net 4 25844 7506 17491 847 29%47:54:54
2news-out.aioe.org 2 12371 4800 7539 32 38%23:25:52
3news.nntp4.net 2 16278 368 14148 1762 2%25:22:27
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 9 54493 12674 39178 2641 23%96:43:13
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net26.7 MB0.0 KB1.5 MB28.2 MB 94%3.5 KB
2news-out.aioe.org13.1 MB0.0 KB46.5 KB13.1 MB 99%2.8 KB
3news.nntp4.net1.3 MB8.2 KB7.5 MB8.8 MB 14%4.2 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 441.1 MB8.2 KB9.0 MB50.1 MB 81%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 25 15:00:02 - 15:59:59 742 5.8% 0.212.1 MB 4.9% 0.59
Nov 25 16:00:00 - 16:59:59 806 6.3% 0.222.6 MB 6.3% 0.75
Nov 25 17:00:00 - 17:59:59 837 6.5% 0.232.7 MB 6.5% 0.77
Nov 25 18:00:00 - 18:59:59 746 5.8% 0.212.4 MB 5.7% 0.67
Nov 25 19:00:00 - 19:59:59 685 5.3% 0.192.2 MB 5.2% 0.61
Nov 25 20:00:00 - 20:59:59 728 5.7% 0.202.3 MB 5.5% 0.65
Nov 25 21:00:00 - 21:59:59 715 5.6% 0.202.5 MB 5.9% 0.70
Nov 25 22:00:00 - 22:59:59 523 4.1% 0.151.7 MB 4.1% 0.49
Nov 25 23:00:00 - 23:59:59 420 3.3% 0.121.6 MB 3.8% 0.45
Nov 26 00:00:00 - 00:59:59 320 2.5% 0.091.3 MB 3.1% 0.36
Nov 26 01:00:00 - 01:59:59 263 2.0% 0.071.4 MB 3.3% 0.40
Nov 26 02:00:00 - 02:59:59 262 2.0% 0.071.3 MB 3.1% 0.37
Nov 26 03:00:00 - 03:59:59 250 1.9% 0.071.3 MB 3.1% 0.36
Nov 26 04:00:00 - 04:59:59 240 1.9% 0.071.3 MB 3.0% 0.36
Nov 26 05:00:00 - 05:59:59 252 2.0% 0.07948.4 KB 2.2% 0.26
Nov 26 06:00:00 - 06:59:59 338 2.6% 0.091009.4 KB 2.4% 0.28
Nov 26 07:00:00 - 07:59:59 424 3.3% 0.121.3 MB 3.0% 0.36
Nov 26 08:00:00 - 08:59:59 621 4.8% 0.171.7 MB 4.1% 0.49
Nov 26 09:00:00 - 09:59:59 646 5.0% 0.181.6 MB 3.8% 0.45
Nov 26 10:00:00 - 10:59:59 621 4.8% 0.171.7 MB 4.0% 0.47
Nov 26 11:00:00 - 11:59:59 641 5.0% 0.181.7 MB 4.1% 0.49
Nov 26 12:00:00 - 12:59:59 545 4.2% 0.151.7 MB 4.2% 0.49
Nov 26 13:00:00 - 13:59:59 630 4.9% 0.171.7 MB 4.1% 0.49
Nov 26 14:00:00 - 14:59:59 620 4.8% 0.171.9 MB 4.5% 0.54
Nov 26 15:00:00 - 15:00:02 3 0.0% 1.5014.2 KB 0.0% 7.10
TOTAL: 24:00:00 12878 100.0% 0.1541.7 MB 100.0% 0.49
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
FOURDEFAULT1024.0 MB174.3 MB 17.0% 4 0.20 60.43
ONEDEFAULT1024.0 MB337.4 MB 32.9% 6 0.20 59.47
THREEDEFAULT1024.0 MB176.5 MB 17.2% 4 0.20 60.79
TWODEFAULT1024.0 MB171.2 MB 16.7% 4 0.20 59.30
TOTAL: 4-4.0 GB859.3 MB 21.0% 18 0.20-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 1756 16 0 2 1 0 0 0 1737
2hasname-out.news.weretis.net 1045 1 0 0 0 0 0 0 1044
3news-out.aioe.org 65 0 0 0 0 0 0 0 65
TOTAL: 3 2866 17 0 2 1 0 0 0 2846

Unwanted newsgroups [Top 20]:

NewsgroupCount
panix.chat.politics 11
alt.support.chronic.pain 2
alt.macintosh 1
list.postfix.users 1
ga.jobs 1
adobe.creative.suite.mac 1
TOTAL: 6 17

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) 1558
EMP (md5) 782
EMP (phn nph) 194
EMP (phl) 38
User-issued spam cancel 25
Binary: misplaced binary 13
Too many newsgroups 4
HTML Multipart 3
Scoring filter 1
TOTAL: 9 2618

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 197
news-out.aioe.org 31
TOTAL: 2 228
TOTAL: 1 228

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 329 329 0 0 0 0100%18:21:49
2aioe 10195 49 7743 4 0 7 0%23:59:57
3nntp4 15077 4 12631 0 0 0 0%24:09:52
4weretis 5619 3 5292 24 0 0 0%24:08:56
TOTAL: 4 31220 385 25666 28 0 7 1%90:40:34
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom1.7 MB0.0 KB1.7 MB0.0 KB/s5.4 KB18:21:49
2aioe226.7 KB12.0 KB238.8 KB0.0 KB/s4.5 KB23:59:57
3weretis13.4 KB88.0 KB101.4 KB0.0 KB/s3.8 KB24:08:56
4nntp410.7 KB0.0 KB10.7 KB0.0 KB/s2.7 KB24:09:52
TOTAL: 42.0 MB100.0 KB2.1 MB0.0 KB/s5.2 KB90:40:34
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
scanner-21.ch1.censys-scanner.com 0.001 0.002 0.00000:00:00
tor-exit-12.zbau.f3netze.de 0.000 0.004 0.00000:00:00
23.129.64.147 0.000 0.003 0.00000:00:00
TOTAL: 3 0.001 0.009 0.00000:00:00

NNRP no permission clients:

SystemConn
scanner-21.ch1.censys-scanner.com 1
tor-exit-12.zbau.f3netze.de 1
23.129.64.147 1
TOTAL: 3 3