Daily Usenet report

Oct 1 03:00:04 -- Oct 2 03:00:04

Unknown entries from news log file:

First 50 / 201 lines (24.9%)

Oct 1 03:01:19 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 1 03:10:45 twilightnode nnrpd[430712]: Reading access from /usr/local/news/etc/readers.conf
Oct 1 03:10:45 twilightnode nnrpd[430712]: Auth strategy 'localhost' does not match client. Removing.
Oct 1 03:10:45 twilightnode nnrpd[430712]: 66.61.98.95 res <anonymous>
Oct 1 03:10:45 twilightnode nnrpd[430712]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 1 03:10:45 twilightnode nnrpd[430712]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 1 03:10:45 twilightnode nnrpd[430712]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 1 03:10:45 twilightnode nnrpd[430712]: 66.61.98.95 no_post anonymous-read
Oct 1 03:10:45 twilightnode nnrpd[430713]: Reading access from /usr/local/news/etc/readers.conf
Oct 1 03:10:45 twilightnode nnrpd[430713]: Auth strategy 'localhost' does not match client. Removing.
Oct 1 03:10:45 twilightnode nnrpd[430713]: 66.61.98.95 res <anonymous>
Oct 1 03:10:45 twilightnode nnrpd[430713]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 1 03:10:45 twilightnode nnrpd[430713]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 1 03:10:45 twilightnode nnrpd[430713]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 1 03:10:45 twilightnode nnrpd[430713]: 66.61.98.95 no_post anonymous-read
Oct 1 03:12:52 twilightnode nnrpd[430735]: Reading access from /usr/local/news/etc/readers.conf
Oct 1 03:12:52 twilightnode nnrpd[430735]: Auth strategy 'localhost' does not match client. Removing.
Oct 1 03:12:52 twilightnode nnrpd[430735]: 66.61.98.95 res <anonymous>
Oct 1 03:12:52 twilightnode nnrpd[430735]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 1 03:12:52 twilightnode nnrpd[430735]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 1 03:12:52 twilightnode nnrpd[430735]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 1 03:12:52 twilightnode nnrpd[430735]: 66.61.98.95 no_post anonymous-read
Oct 1 03:12:52 twilightnode nnrpd[430736]: Reading access from /usr/local/news/etc/readers.conf
Oct 1 03:12:52 twilightnode nnrpd[430736]: Auth strategy 'localhost' does not match client. Removing.
Oct 1 03:12:52 twilightnode nnrpd[430736]: 66.61.98.95 res <anonymous>
Oct 1 03:12:52 twilightnode nnrpd[430736]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 1 03:12:52 twilightnode nnrpd[430736]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 1 03:12:52 twilightnode nnrpd[430736]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 1 03:12:52 twilightnode nnrpd[430736]: 66.61.98.95 no_post anonymous-read
Oct 1 03:28:15 twilightnode nnrpd[431427]: Reading access from /usr/local/news/etc/readers.conf
Oct 1 03:28:15 twilightnode nnrpd[431427]: Auth strategy 'localhost' does not match client. Removing.
Oct 1 03:28:15 twilightnode nnrpd[431427]: 66.61.98.95 res <anonymous>
Oct 1 03:28:15 twilightnode nnrpd[431427]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 1 03:28:15 twilightnode nnrpd[431427]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 1 03:28:15 twilightnode nnrpd[431427]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 1 03:28:15 twilightnode nnrpd[431427]: 66.61.98.95 no_post anonymous-read
Oct 1 03:28:15 twilightnode nnrpd[431428]: Reading access from /usr/local/news/etc/readers.conf
Oct 1 03:28:15 twilightnode nnrpd[431428]: Auth strategy 'localhost' does not match client. Removing.
Oct 1 03:28:15 twilightnode nnrpd[431428]: 66.61.98.95 res <anonymous>
Oct 1 03:28:15 twilightnode nnrpd[431428]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 1 03:28:15 twilightnode nnrpd[431428]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 1 03:28:15 twilightnode nnrpd[431428]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 1 03:28:15 twilightnode nnrpd[431428]: 66.61.98.95 no_post anonymous-read
Oct 1 03:34:43 twilightnode nnrpd[431729]: Reading access from /usr/local/news/etc/readers.conf
Oct 1 03:34:43 twilightnode nnrpd[431729]: Auth strategy 'localhost' does not match client. Removing.
Oct 1 03:34:43 twilightnode nnrpd[431729]: 66.61.98.95 res <anonymous>
Oct 1 03:34:43 twilightnode nnrpd[431729]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 1 03:34:43 twilightnode nnrpd[431729]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 1 03:34:43 twilightnode nnrpd[431729]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 1 03:34:43 twilightnode nnrpd[431729]: 66.61.98.95 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 7875 65.2%1.4 MB 70.5%
innd 2338 19.4%338.4 KB 17.0%
innfeed 1253 10.4%190.3 KB 9.5%
nnrpd 581 4.8%53.1 KB 2.7%
controlchan 25 0.2%6.2 KB 0.3%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 6 12073 100.0%1.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20856 51.4%
Negative hits 11914 29.3%
Do not exist 7828 19.3%
Cache misses 10 0.0%
TOTAL: 4 40608 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.171 0.0%33 1.000 5.182 21.000
article cleanup00:00:00.106 0.0%7863 0.000 0.013 0.091
article logging00:00:00.330 0.0%7885 0.000 0.042 0.410
article parse00:00:00.214 0.0%10663 0.000 0.020 0.087
article write00:00:09.019 0.0%6592 0.729 1.368 3.464
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.307 0.0%42565 0.000 0.007 0.045
hisgrep/artcncl00:00:00.045 0.0%33 0.000 1.364 5.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.645 0.0%40781 0.060 0.138 0.299
history sync00:00:00.088 0.0%421 0.000 0.209 1.667
history write00:00:03.905 0.0%7864 0.182 0.497 1.077
idle24:00:01.926 99.9%42361 1077.786 2039.657 8112.473
nntp read00:00:00.989 0.0%41883 0.000 0.024 0.114
overview write00:00:06.417 0.0%6592 0.500 0.973 2.500
perl filter00:00:18.901 0.0%7863 1.265 2.404 10.500
python filter00:00:01.384 0.0%7863 0.034 0.176 1.167
site send00:00:00.418 0.0%13152 0.000 0.032 0.125
TOTAL: 1824:00:49.865100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.021 0.0%7067 0.000 0.003 0.083
article prepare00:00:00.000 0.0%27740 0.000 0.000 0.000
article read00:00:01.362 0.0%4581 0.051 0.297 1.125
backlog stats00:00:00.053 0.0%85003 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.751 0.0%48417 0.000 0.016 0.071
data write00:00:01.135 0.0%41853 0.000 0.027 0.071
idle23:58:41.408100.0%85003 389.377 1015.510 5363.491
status file00:00:00.214 0.0%158 0.000 1.354 9.000
TOTAL: 923:58:44.944100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:41.754100.0%414 74.000 245.783 9843.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.022 0.0%1170 0.000 0.019 0.025
readart00:00:00.006 0.0%32 0.000 0.188 0.250
TOTAL: 400:01:41.782100.0%----

Control commands to innd:

CommandNumber
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 168

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
control@eternal-september.org001001
moderator@dana.de001101
TOTAL002102

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 3 11061 4198 6714 149 37%45:19:07
2usenet.blueworldhosting.com 144 7775 2075 4608 1092 26%23:39:43
3news.sklaffkom.se 39 94 85 9 0 90%10:23:34
4news.tebibyte.org 2 3821 79 3742 0 2%22:28:11
5newsfeed.endofthelinebbs.com 1 2586 57 2526 3 2%14:09:18
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 214 25337 6494 17599 1244 25%115:59:53
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.6 MB0.0 KB364.6 KB13.9 MB 97%3.3 KB
2usenet.blueworldhosting.com9.9 MB0.0 KB1.7 MB11.6 MB 85%3.8 KB
3newsfeed.endofthelinebbs.com3.1 MB0.0 KB6.3 KB3.1 MB 99%52.5 KB
4news.tebibyte.org337.9 KB0.0 KB0.0 KB337.9 KB100%4.3 KB
5news.sklaffkom.se269.7 KB0.0 KB0.0 KB269.7 KB100%3.2 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 627.1 MB0.0 KB2.1 MB29.2 MB 92%3.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 1 03:00:04 - 03:59:59 136 2.1% 0.04650.6 KB 2.4% 0.18
Oct 1 04:00:00 - 04:59:59 85 1.3% 0.02260.0 KB 1.0% 0.07
Oct 1 05:00:00 - 05:59:59 117 1.8% 0.03435.6 KB 1.6% 0.12
Oct 1 06:00:00 - 06:59:59 162 2.5% 0.04857.4 KB 3.2% 0.24
Oct 1 07:00:00 - 07:59:59 142 2.2% 0.04634.5 KB 2.3% 0.18
Oct 1 08:00:00 - 08:59:59 187 2.8% 0.05666.8 KB 2.5% 0.19
Oct 1 09:00:00 - 09:59:59 286 4.3% 0.081.2 MB 4.5% 0.34
Oct 1 10:00:00 - 10:59:59 332 5.0% 0.09977.7 KB 3.6% 0.27
Oct 1 11:00:00 - 11:59:59 367 5.6% 0.101.5 MB 5.5% 0.42
Oct 1 12:00:00 - 12:59:59 403 6.1% 0.112.2 MB 8.2% 0.61
Oct 1 13:00:00 - 13:59:59 391 5.9% 0.111.3 MB 4.9% 0.36
Oct 1 14:00:00 - 14:59:59 353 5.4% 0.101.1 MB 4.2% 0.32
Oct 1 15:00:00 - 15:59:59 306 4.6% 0.091.1 MB 4.1% 0.31
Oct 1 16:00:00 - 16:59:59 279 4.2% 0.081015.9 KB 3.8% 0.28
Oct 1 17:00:00 - 17:59:59 328 5.0% 0.091.1 MB 4.2% 0.31
Oct 1 18:00:00 - 18:59:59 347 5.3% 0.101.4 MB 5.3% 0.40
Oct 1 19:00:00 - 19:59:59 393 6.0% 0.111.4 MB 5.4% 0.41
Oct 1 20:00:00 - 20:59:59 374 5.7% 0.101.2 MB 4.7% 0.35
Oct 1 21:00:00 - 21:59:59 329 5.0% 0.091.1 MB 4.2% 0.31
Oct 1 22:00:00 - 22:59:59 324 4.9% 0.091.4 MB 5.3% 0.40
Oct 1 23:00:00 - 23:59:59 290 4.4% 0.08998.0 KB 3.7% 0.28
Oct 2 00:00:00 - 00:59:59 324 4.9% 0.091.9 MB 7.1% 0.53
Oct 2 01:00:00 - 01:59:59 163 2.5% 0.05789.0 KB 2.9% 0.22
Oct 2 02:00:00 - 02:59:59 155 2.4% 0.041.5 MB 5.5% 0.41
Oct 2 03:00:00 - 03:00:04 9 0.1% 2.258.6 KB 0.0% 2.15
TOTAL: 24:00:00 6582 100.0% 0.0826.4 MB 100.0% 0.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1092 344 0 0 1 0 0 0 747
2twilightnode-out.news.weretis.net 174 27 0 0 0 0 0 0 147
3newsfeed.endofthelinebbs.com 25 4 0 0 0 0 0 0 21
4news.tebibyte.org 2 1 0 0 0 0 0 0 1
TOTAL: 4 1293 376 0 0 1 0 0 0 916

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 27
fr.rec.son-image.hifi 9
hispagatos.mastodon.tag.hacking 6
nc.charlotte.forsale 4
dfw.forsale 3
fido.ru.golded 3
phl.forsale 3
alt.forsale.seattle 2
chi.forsale 2
de.comm.abuse 2
de.comm.software.mailserver 2
fido.ru.home 2
nyc.forsale 2
pl.listserv.chomor-l 2
3dfx.game 1
alt.2600.madrid 1
alt.aviation 1
alt.bbs.files-announce 1
alt.crackers 1
TOTAL: 34 376

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
uk.religion.christian 1
TOTAL: 1 1

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 639
Too many newsgroups 124
User-issued cancel 68
EMP (phn path) 62
TOTAL: 4 893

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 22
TOTAL: 1 22
No colon-space in header field
usenet.blueworldhosting.com 1
TOTAL: 1 1
TOTAL: 2 23

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 9045 2277 4142 131 0 278 25%23:59:57
2sklaffkom.se 6698 933 2084 3532 0 0 13%23:59:57
3newsfeed.endofthelinebbs.com 11100 56 6356 22 0 217 0%23:59:57
4usenet.blueworldhosting.com 7171 0 4191 0 0 20 0%23:59:54
5weretis.net 2441 0 2387 0 0 0 0%23:59:57
TOTAL: 5 36455 3266 19160 3685 0 515 8%119:59:42
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.1 MB1.3 MB7.4 MB0.1 KB/s3.1 KB23:59:57
2sklaffkom.se1.5 MB17.5 MB19.0 MB0.2 KB/s4.4 KB23:59:57
3newsfeed.endofthelinebbs.com190.8 KB57.6 KB248.3 KB0.0 KB/s3.2 KB23:59:57
4usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
5weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
TOTAL: 57.8 MB18.9 MB26.6 MB0.1 KB/s3.9 KB119:59:42
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 16 32249.6 KB 11 0 000:01:25
TOTAL: 1 16 32249.6 KB 11 0 000:01:25

NNRP connection statistics (by domain) [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 19 32249.6 KB 11 0 000:01:35
2*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:03
3*.infrawat.ch 2 00.0 KB 0 0 000:00:00
4*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
5*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 5 27 32249.6 KB 11 0 000:01:41

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 0.129 0.370 0.00000:01:25
20.80.75.172 0.013 0.024 0.00000:00:09
80.195.132.66.censys-scanner.com 0.024 0.023 0.00000:00:02
r4-58-3a.monitoring.internet-measurement.com 0.005 0.013 0.00000:00:01
47.195.132.66.censys-scanner.com 0.041 0.033 0.00000:00:01
198.235.24.179 0.003 0.016 0.00000:00:00
185-223-235-16.infrawat.ch 0.006 0.007 0.00000:00:00
o040.scanner.modat.io 0.004 0.015 0.00000:00:00
188-240-59-54.infrawat.ch 0.004 0.023 0.00000:00:00
TOTAL: 9 0.229 0.524 0.00000:01:41

Curious NNRP explorers [Top 20]:

SystemConn
47.195.132.66.censys-scanner.com 3
20.80.75.172 2
185-223-235-16.infrawat.ch 1
188-240-59-54.infrawat.ch 1
198.235.24.179 1
80.195.132.66.censys-scanner.com 1
o040.scanner.modat.io 1
r4-58-3a.monitoring.internet-measurement.com 1
TOTAL: 8 11

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
20.80.75.172 1
47.195.132.66.censys-scanner.com 1
80.195.132.66.censys-scanner.com 1
TOTAL: 3 3

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
EHLO www.censys.io 2
MGLNDD_172.234.115.63_119 1
TOTAL: 2 3

NNRP client timeouts [Top 20]:

SystemConnPeer
185-223-235-16.infrawat.ch 1 1
47.195.132.66.censys-scanner.com 1 2
TOTAL: 2 2 5

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

HierarchyCountPct
1linux 22 68.8%
2alt 6 18.8%
3aus 2 6.2%
4comp 1 3.1%
5talk 1 3.1%
TOTAL: 5 32100.0%

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

NewsgroupCount
1linux.kernel.netdev 19
2alt.fan.rush-limbaugh 3
3alt.buddha.short.fat.guy 2
4aus.cars 2
5alt.computer.workshop 1
6comp.sys.ibm.pc.games.action 1
7linux.debian.bugs.rc 1
8linux.debian.devel.mentors 1
9linux.kernel.git 1
10talk.origins 1
TOTAL: 10 32