Daily Usenet Report

May 12 00:01:01 -- May 13 00:01:01

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

May 12 00:02:37 www innd: message repeated 2 times: [ ctlinnd command s]
May 12 15:30:03 www pgpverify[986684]: pgpverify: verification failed
May 12 15:30:03 www controlchan[963772]: skipping newgroup y moderator@dana.de (pgpverify failed) in <newgroup-de.comm.geraete.mobil-2022-05-12@dana.de>
May 12 15:45:02 www pgpverify[987129]: pgpverify: verification failed
May 12 15:45:02 www pgpverify[987137]: pgpverify: verification failed
May 12 15:45:03 www pgpverify[987145]: pgpverify: verification failed
May 12 15:45:03 www pgpverify[987153]: pgpverify: verification failed
May 12 15:45:03 www pgpverify[987161]: pgpverify: verification failed
May 12 15:45:04 www pgpverify[987169]: pgpverify: verification failed
May 12 15:45:04 www pgpverify[987177]: pgpverify: verification failed
May 12 15:45:04 www pgpverify[987185]: pgpverify: verification failed
May 12 15:45:04 www pgpverify[987193]: pgpverify: verification failed
May 12 15:45:05 www pgpverify[987202]: pgpverify: verification failed
May 12 15:45:05 www pgpverify[987210]: pgpverify: verification failed
May 12 16:15:02 www pgpverify[987927]: pgpverify: verification failed
May 12 16:15:02 www controlchan[963772]: skipping newgroup y moderator@dana.de (pgpverify failed) in <newgroup-de.comm.geraete.mobil-2022-05-12-1@dana.de>
May 12 16:15:02 www pgpverify[987935]: pgpverify: verification failed
May 12 16:15:02 www controlchan[963772]: skipping newgroup y moderator@dana.de (pgpverify failed) in <newgroup-de.markt-2022-05-12-1@dana.de>
May 12 16:33:11 www nnrpd[988259]: unable to get certificates from '/news/etc/cert.pem'
May 12 16:33:11 www nnrpd[988259]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]

Log entries by program:

Program nameLines%LinesSize%Size
inn 14586 72.0%3.2 MB 79.4%
innd 3657 18.1%532.4 KB 12.7%
innfeed 1858 9.2%317.4 KB 7.6%
nnrpd 116 0.6%9.8 KB 0.2%
controlchan 15 0.1%1.1 KB 0.0%
pgpverify 14 0.1%2.0 KB 0.0%
TOTAL: 6 20246 100.0%4.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 83056 51.8%
Negative hits 63134 39.3%
Do not exist 14258 8.9%
Cache misses 39 0.0%
TOTAL: 4 160487 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.139 0.0%100 0.000 1.390 5.500
article cleanup00:00:00.307 0.0%14336 0.000 0.021 0.082
article logging00:00:00.643 0.0%14509 0.000 0.044 0.200
article parse00:00:00.502 0.0%22011 0.000 0.023 0.076
article write00:00:08.557 0.0%12546 0.290 0.682 4.370
artlog/artcncl00:00:00.003 0.0%93 0.000 0.032 0.500
data move00:00:00.193 0.0%162881 0.000 0.001 0.006
hisgrep/artcncl00:00:00.013 0.0%99 0.000 0.131 2.000
hishave/artcncl00:00:00.016 0.0%100 0.000 0.160 1.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.400 0.0%161400 0.004 0.021 0.071
history sync00:00:00.054 0.0%394 0.000 0.137 0.667
history write00:00:03.824 0.0%14334 0.031 0.267 0.824
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:58:45.116 99.8%160174 292.079 538.946 3078.118
nntp read00:00:05.225 0.0%161156 0.008 0.032 0.059
overview write00:00:08.179 0.0%12546 0.321 0.652 4.278
perl filter00:00:42.552 0.0%14334 2.218 2.969 5.145
python filter00:00:02.556 0.0%14334 0.077 0.178 0.455
site send00:00:04.734 0.0%25106 0.026 0.189 0.657
TOTAL: 24:01:08.10824:00:06.013 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.119 0.0%13059 0.000 0.009 0.151
article prepare00:00:00.001 0.0%3632 0.000 0.000 0.036
article read00:00:00.288 0.0%727 0.000 0.396 16.333
backlog stats00:00:00.301 0.0%202343 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:03.891 0.0%140003 0.014 0.028 0.046
data write00:00:15.910 0.0%128988 0.067 0.123 0.209
idle23:58:18.302100.0%202343 271.845 426.495 1659.512
status file00:00:00.162 0.0%183 0.000 0.885 6.000
TOTAL: 23:58:46.44323:58:38.974100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:10:03.380 99.8%155 44.000 3892.774 4080.483
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.034 0.0%410 0.000 0.083 1.500
readart00:00:00.090 0.0%535 0.168 0.168 0.168
TOTAL: 00:10:04.68400:10:03.504 99.8%----

Control commands to INND:

CommandNumber
cancel 8
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 12 176

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de0001100
TOTAL0001100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 29 21268 5473 15680 115 25%40:19:52
2peer.alt119.net 2 14594 3436 10164 994 23%26:49:54
3usenet.blueworldhosting.com 13 13323 3275 9315 733 24%24:58:53
4newsfeed.xs3.de 4 11585 260 11322 3 2%23:59:39
5news.samoylyk.net 2 11724 95 11627 2 0%23:59:38
6glockenspiel.complete.org 2 12223 45 12163 15 0%23:59:51
7news.bbs.nz 4 17731 20 17710 1 0%36:42:30
8eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:07
9news.nk.ca 2 0 0 0 0 0%00:20:00
10localhost 25 0 0 0 0 0%00:00:00
TOTAL: 10 84 102448 12604 87981 1863 12%201:20:24
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net25.1 MB0.0 KB227.0 KB25.3 MB 99%4.6 KB
2usenet.blueworldhosting.com16.0 MB0.0 KB1.6 MB17.5 MB 91%4.5 KB
3peer.alt119.net8.6 MB0.0 KB5.5 MB14.1 MB 60%3.3 KB
4newsfeed.xs3.de1.1 MB0.0 KB3.7 KB1.1 MB 99%4.4 KB
5glockenspiel.complete.org468.1 KB0.0 KB32.0 KB500.1 KB 93%8.3 KB
6news.samoylyk.net379.4 KB0.0 KB2.4 KB381.8 KB 99%3.9 KB
7news.bbs.nz40.0 KB138.2 KB138.2 KB316.3 KB 12%15.1 KB
8eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
9news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1051.6 MB138.2 KB7.5 MB59.3 MB 87%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 12 00:01:01 - 00:59:59 374 3.0% 0.111.2 MB 2.5% 0.36
May 12 01:00:00 - 01:59:59 496 4.0% 0.142.5 MB 4.9% 0.70
May 12 02:00:00 - 02:59:59 609 4.9% 0.171.8 MB 3.5% 0.50
May 12 03:00:00 - 03:59:59 652 5.2% 0.181.7 MB 3.4% 0.48
May 12 04:00:00 - 04:59:59 615 4.9% 0.172.0 MB 4.0% 0.56
May 12 05:00:00 - 05:59:59 565 4.5% 0.161.9 MB 3.9% 0.55
May 12 06:00:00 - 06:59:59 645 5.1% 0.182.3 MB 4.7% 0.66
May 12 07:00:00 - 07:59:59 599 4.8% 0.171.7 MB 3.3% 0.47
May 12 08:00:00 - 08:59:59 712 5.7% 0.202.5 MB 5.0% 0.71
May 12 09:00:00 - 09:59:59 638 5.1% 0.182.2 MB 4.5% 0.64
May 12 10:00:00 - 10:59:59 741 5.9% 0.213.7 MB 7.4% 1.05
May 12 11:00:00 - 11:59:59 605 4.8% 0.171.9 MB 3.8% 0.55
May 12 12:00:00 - 12:59:59 600 4.8% 0.172.5 MB 5.0% 0.72
May 12 13:00:00 - 13:59:59 649 5.2% 0.182.4 MB 4.8% 0.68
May 12 14:00:00 - 14:59:59 678 5.4% 0.193.6 MB 7.2% 1.03
May 12 15:00:00 - 15:59:59 569 4.5% 0.162.1 MB 4.1% 0.59
May 12 16:00:00 - 16:59:59 559 4.5% 0.162.4 MB 4.8% 0.69
May 12 17:00:00 - 17:59:59 493 3.9% 0.142.8 MB 5.5% 0.79
May 12 18:00:00 - 18:59:59 378 3.0% 0.101.3 MB 2.5% 0.36
May 12 19:00:00 - 19:59:59 335 2.7% 0.091.6 MB 3.2% 0.46
May 12 20:00:00 - 20:59:59 308 2.5% 0.092.0 MB 3.9% 0.56
May 12 21:00:00 - 21:59:59 275 2.2% 0.081.9 MB 3.7% 0.53
May 12 22:00:00 - 22:59:59 199 1.6% 0.061.1 MB 2.2% 0.31
May 12 23:00:00 - 23:59:59 238 1.9% 0.071.1 MB 2.2% 0.32
May 13 00:00:00 - 00:01:01 2 0.0% 0.032.5 KB 0.0% 0.04
TOTAL: 24:00:00 12534 100.0% 0.1550.1 MB 100.0% 0.59
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1peer.alt119.net 970 29 0 0 0 0 0 0 941
2usenet.blueworldhosting.com 734 131 0 0 0 0 0 0 603
3endofthelinebbs-out.news.weretis.net 237 4 0 1 0 0 0 0 232
4glockenspiel.complete.org 10 0 0 0 0 0 0 0 10
5newsfeed.xs3.de 6 0 0 0 0 0 0 0 6
6news.samoylyk.net 3 0 0 0 0 0 0 0 3
7news.bbs.nz 1 0 0 1 0 0 0 0 0
TOTAL: 7 1961 164 0 2 0 0 0 0 1795

Unwanted newsgroups [Top 20]:

NewsgroupCount
talk.english.tg 59
talk.generic.ua.ws 31
talk.english.tw 16
comp.hackers 11
at.jobs 9
att.jobs 9
aus.jobs 6
talk.from.war.diary 3
rocksolid.feeds.news 3
grisbi.devel 3
talk.from.diary 2
grisbi.user 2
alt.anonymous.test 2
grisbi.user.french 2
alt.radio.networks 1
de.comm.geraete.mobil 1
alt.bestjobsusa.philly.jobs 1
alphanet.general 1
tottenham.hotspurs 1
grisbi.test 1
TOTAL: 20 164

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 835
EMP (md5) 482
EMP (phn nph) 182
EMP (phl) 68
Too many newsgroups 43
Scoring filter 7
HTML Multipart 4
HTML post 2
TOTAL: 8 1623

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 120
peer.alt119.net 28
usenet.blueworldhosting.com 19
newsfeed.xs3.de 3
glockenspiel.complete.org 1
news.samoylyk.net 1
TOTAL: 6 172
TOTAL: 1 172

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1peer.alt119.net 11404 589 8592 3 0 20 5%23:59:57
2news.nk.ca 13974 64 12431 7 0 30 0%23:59:57
3news.bbs.nz 24410 53 12395 71 0 234 0%23:59:43
4news.quux.org 13050 18 12356 93 0 30 0%23:59:57
5usenet.blueworldhosting.com 9641 2 9211 0 0 78 0%23:59:57
6news.tnetconsulting.net 13739 2 12531 0 0 69 0%23:59:30
7news.weretis.net 12667 2 12528 2 0 28 0%23:59:57
8newsfeed.xs3.de 15023 1 12269 1 0 14 0%23:59:48
9news.samoylyk.net 15317 0 12432 0 0 25 0%23:59:57
TOTAL: 9 129225 731 104745 177 0 528 0%215:58:43
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1peer.alt119.net2.7 MB173.1 KB2.9 MB0.0 KB/s5.0 KB23:59:57
2news.nk.ca458.5 KB11.2 KB469.7 KB0.0 KB/s6.6 KB23:59:57
3news.bbs.nz308.7 KB572.9 KB881.6 KB0.0 KB/s7.1 KB23:59:43
4news.quux.org61.9 KB651.4 KB713.4 KB0.0 KB/s6.4 KB23:59:57
5news.weretis.net5.3 KB4.2 KB9.5 KB0.0 KB/s2.4 KB23:59:57
6usenet.blueworldhosting.com3.6 KB0.0 KB3.6 KB0.0 KB/s1.8 KB23:59:57
7news.tnetconsulting.net3.6 KB0.0 KB3.6 KB0.0 KB/s1.8 KB23:59:30
8newsfeed.xs3.de1.8 KB2.2 KB4.0 KB0.0 KB/s2.0 KB23:59:48
9news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
TOTAL: 93.5 MB1.4 MB4.9 MB0.0 KB/s5.5 KB215:58:43
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.186.45.8 2 1031.6 KB 7 0 000:10:00
TOTAL: 1 2 1031.6 KB 7 0 000:10:04

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 5 1031.6 KB 7 0 000:10:02
2*.stormycloud.org 1 00.0 KB 0 0 000:00:02
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 3 7 1031.6 KB 7 0 000:10:04

NNRP auth users [Top 20]:

UserConn
nelgin 2
TOTAL: 1 2

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47.186.45.8 0.148 0.135 0.00400:10:00
tor-exit64.stormycloud.org 0.049 0.062 0.00000:00:02
50.31.21.5 0.151 0.049 0.00000:00:01
40.113.202.73 0.090 0.020 0.00000:00:00
scanner-05.ch1.censys-scanner.com 0.064 0.041 0.00000:00:00
TOTAL: 5 0.502 0.307 0.00400:10:04

Curious NNRP server explorers [Top 20]:

SystemConn
50.31.21.5 2
40.113.202.73 1
tor-exit64.stormycloud.org 1
scanner-05.ch1.censys-scanner.com 1
TOTAL: 4 5

NNRP unrecognized commands (by host):

SystemConn
40.113.202.73 3
scanner-05.ch1.censys-scanner.com 1
TOTAL: 2 4

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 1
EHLO www.censys.io 1
Connection: Keep-Alive 1
Host: 192.138.210.162:119 1
TOTAL: 4 4

Newsgroup request counts (by category):

CategoryCountPct
1news 6 35.3%
2dfw 5 29.4%
3alt 4 23.5%
4soc 1 5.9%
5comp 1 5.9%
TOTAL: 5 17100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1news.admin.peering 6
2alt.music.lyrics 4
3dfw.games 3
4dfw.forsale 1
5soc.genealogy.britain 1
6comp.sys.cbm 1
7dfw.politics 1
TOTAL: 7 17