Daily Usenet report

Oct 22 03:00:04 -- Oct 23 03:00:04

Unknown entries from news log file:

First 50 / 629 lines (7.9%)

Oct 22 03:01:16 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 22 03:08:38 twilightnode nnrpd[110988]: Reading access from /usr/local/news/etc/readers.conf
Oct 22 03:08:38 twilightnode nnrpd[110988]: Auth strategy 'localhost' does not match client. Removing.
Oct 22 03:08:38 twilightnode nnrpd[110988]: 174.96.78.81 res <anonymous>
Oct 22 03:08:38 twilightnode nnrpd[110988]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 22 03:08:38 twilightnode nnrpd[110988]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 22 03:08:38 twilightnode nnrpd[110988]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 22 03:08:38 twilightnode nnrpd[110988]: 174.96.78.81 no_post anonymous-read
Oct 22 03:08:38 twilightnode nnrpd[110989]: Reading access from /usr/local/news/etc/readers.conf
Oct 22 03:08:38 twilightnode nnrpd[110989]: Auth strategy 'localhost' does not match client. Removing.
Oct 22 03:08:38 twilightnode nnrpd[110989]: 174.96.78.81 res <anonymous>
Oct 22 03:08:38 twilightnode nnrpd[110989]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 22 03:08:38 twilightnode nnrpd[110989]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 22 03:08:38 twilightnode nnrpd[110989]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 22 03:08:38 twilightnode nnrpd[110989]: 174.96.78.81 no_post anonymous-read
Oct 22 03:18:27 twilightnode nnrpd[111470]: Reading access from /usr/local/news/etc/readers.conf
Oct 22 03:18:27 twilightnode nnrpd[111470]: Auth strategy 'localhost' does not match client. Removing.
Oct 22 03:18:27 twilightnode nnrpd[111470]: 174.96.78.81 res <anonymous>
Oct 22 03:18:27 twilightnode nnrpd[111470]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 22 03:18:27 twilightnode nnrpd[111470]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 22 03:18:27 twilightnode nnrpd[111470]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 22 03:18:27 twilightnode nnrpd[111470]: 174.96.78.81 no_post anonymous-read
Oct 22 03:18:27 twilightnode nnrpd[111471]: Reading access from /usr/local/news/etc/readers.conf
Oct 22 03:18:27 twilightnode nnrpd[111471]: Auth strategy 'localhost' does not match client. Removing.
Oct 22 03:18:27 twilightnode nnrpd[111471]: 174.96.78.81 res <anonymous>
Oct 22 03:18:27 twilightnode nnrpd[111471]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 22 03:18:27 twilightnode nnrpd[111471]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 22 03:18:27 twilightnode nnrpd[111471]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 22 03:18:27 twilightnode nnrpd[111471]: 174.96.78.81 no_post anonymous-read
Oct 22 03:24:16 twilightnode nnrpd[111651]: Reading access from /usr/local/news/etc/readers.conf
Oct 22 03:24:16 twilightnode nnrpd[111651]: Auth strategy 'localhost' does not match client. Removing.
Oct 22 03:24:16 twilightnode nnrpd[111651]: 174.96.78.81 res <anonymous>
Oct 22 03:24:16 twilightnode nnrpd[111651]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 22 03:24:16 twilightnode nnrpd[111651]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 22 03:24:16 twilightnode nnrpd[111651]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 22 03:24:16 twilightnode nnrpd[111651]: 174.96.78.81 no_post anonymous-read
Oct 22 03:24:16 twilightnode nnrpd[111652]: Reading access from /usr/local/news/etc/readers.conf
Oct 22 03:24:16 twilightnode nnrpd[111652]: Auth strategy 'localhost' does not match client. Removing.
Oct 22 03:24:16 twilightnode nnrpd[111652]: 174.96.78.81 res <anonymous>
Oct 22 03:24:16 twilightnode nnrpd[111652]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 22 03:24:16 twilightnode nnrpd[111652]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 22 03:24:16 twilightnode nnrpd[111652]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 22 03:24:16 twilightnode nnrpd[111652]: 174.96.78.81 no_post anonymous-read
Oct 22 03:42:13 twilightnode nnrpd[112512]: Reading access from /usr/local/news/etc/readers.conf
Oct 22 03:42:13 twilightnode nnrpd[112512]: Auth strategy 'localhost' does not match client. Removing.
Oct 22 03:42:13 twilightnode nnrpd[112512]: 174.96.78.81 res <anonymous>
Oct 22 03:42:13 twilightnode nnrpd[112512]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 22 03:42:13 twilightnode nnrpd[112512]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 22 03:42:13 twilightnode nnrpd[112512]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 22 03:42:13 twilightnode nnrpd[112512]: 174.96.78.81 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 8455 61.0%1.5 MB 68.0%
innd 2781 20.1%380.5 KB 16.7%
nnrpd 1368 9.9%129.4 KB 5.7%
innfeed 1264 9.1%220.1 KB 9.7%
TOTAL: 4 13868 100.0%2.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 22489 51.5%
Negative hits 12723 29.1%
Do not exist 8422 19.3%
Cache misses 26 0.1%
TOTAL: 4 43660 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.077 0.0%30 0.000 2.567 5.000
article cleanup00:00:00.129 0.0%8438 0.000 0.015 0.292
article logging00:00:00.378 0.0%8457 0.000 0.045 0.244
article parse00:00:00.163 0.0%11867 0.000 0.014 0.069
article write00:00:09.654 0.0%7359 0.702 1.312 3.625
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.274 0.0%46869 0.000 0.006 0.047
hisgrep/artcncl00:00:00.034 0.0%30 0.000 1.133 1.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.192 0.0%43754 0.084 0.142 0.298
history sync00:00:00.100 0.0%416 0.000 0.240 4.000
history write00:00:04.551 0.0%8439 0.179 0.539 1.405
idle24:00:07.033 99.9%46876 510.094 1843.311 8960.925
nntp read00:00:01.176 0.0%46107 0.000 0.026 0.064
overview write00:00:07.202 0.0%7359 0.311 0.979 19.263
perl filter00:00:15.960 0.0%8438 0.760 1.891 5.368
python filter00:00:01.274 0.0%8438 0.030 0.151 0.684
site send00:00:00.510 0.0%14694 0.000 0.035 0.091
TOTAL: 1824:00:54.707100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.032 0.0%7613 0.000 0.004 0.091
article prepare00:00:00.009 0.0%32832 0.000 0.000 0.016
article read00:00:01.521 0.0%5125 0.000 0.297 1.250
backlog stats00:00:00.070 0.0%97635 0.000 0.001 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.903 0.0%55647 0.000 0.016 0.068
data write00:00:01.542 0.0%48197 0.011 0.032 0.080
idle23:59:17.465100.0%97635 275.684 884.493 4448.304
status file00:00:00.182 0.0%167 0.000 1.090 3.000
TOTAL: 923:59:21.724100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:02:11.108100.0%547 31.000 239.686 9831.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.039 0.0%1503 0.000 0.026 0.667
readart00:00:00.004 0.0%29 0.000 0.138 0.375
TOTAL: 400:02:11.151100.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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 6 13445 5070 8283 92 37%50:31:33
2usenet.blueworldhosting.com 288 8329 2026 5352 951 24%23:49:12
3news.tebibyte.org 2 4505 189 4314 2 4%23:58:37
4news.sklaffkom.se 46 125 111 14 0 88%12:16:05
5newsfeed.endofthelinebbs.com 1 2282 104 2161 17 4%13:04:17
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 368 28686 7500 20124 1062 26%123:39:44
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.1 MB0.0 KB344.5 KB15.5 MB 97%3.1 KB
2usenet.blueworldhosting.com9.0 MB0.0 KB1.2 MB10.1 MB 88%3.5 KB
3newsfeed.endofthelinebbs.com2.6 MB0.0 KB54.9 KB2.7 MB 97%22.5 KB
4news.tebibyte.org785.4 KB0.0 KB2.7 KB788.1 KB 99%4.1 KB
5news.sklaffkom.se287.2 KB0.0 KB0.0 KB287.2 KB100%2.6 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 627.7 MB0.0 KB1.6 MB29.3 MB 94%3.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 22 03:00:04 - 03:59:59 155 2.1% 0.04794.9 KB 2.7% 0.22
Oct 22 04:00:00 - 04:59:59 105 1.4% 0.03510.9 KB 1.7% 0.14
Oct 22 05:00:00 - 05:59:59 98 1.3% 0.03549.0 KB 1.8% 0.15
Oct 22 06:00:00 - 06:59:59 136 1.8% 0.04436.7 KB 1.5% 0.12
Oct 22 07:00:00 - 07:59:59 175 2.4% 0.05630.7 KB 2.1% 0.18
Oct 22 08:00:00 - 08:59:59 254 3.5% 0.07719.6 KB 2.4% 0.20
Oct 22 09:00:00 - 09:59:59 316 4.3% 0.091.4 MB 4.7% 0.39
Oct 22 10:00:00 - 10:59:59 332 4.5% 0.091.0 MB 3.6% 0.30
Oct 22 11:00:00 - 11:59:59 375 5.1% 0.101.4 MB 4.8% 0.40
Oct 22 12:00:00 - 12:59:59 304 4.1% 0.081.2 MB 4.1% 0.34
Oct 22 13:00:00 - 13:59:59 337 4.6% 0.091.0 MB 3.6% 0.30
Oct 22 14:00:00 - 14:59:59 318 4.3% 0.091.4 MB 4.7% 0.39
Oct 22 15:00:00 - 15:59:59 279 3.8% 0.08837.9 KB 2.8% 0.23
Oct 22 16:00:00 - 16:59:59 423 5.7% 0.122.5 MB 8.6% 0.72
Oct 22 17:00:00 - 17:59:59 351 4.8% 0.101.4 MB 4.8% 0.40
Oct 22 18:00:00 - 18:59:59 558 7.6% 0.153.7 MB 12.8% 1.07
Oct 22 19:00:00 - 19:59:59 480 6.5% 0.131.6 MB 5.5% 0.45
Oct 22 20:00:00 - 20:59:59 333 4.5% 0.091.3 MB 4.4% 0.36
Oct 22 21:00:00 - 21:59:59 332 4.5% 0.091.2 MB 4.1% 0.34
Oct 22 22:00:00 - 22:59:59 609 8.3% 0.171.7 MB 5.9% 0.49
Oct 22 23:00:00 - 23:59:59 432 5.9% 0.121.7 MB 5.7% 0.47
Oct 23 00:00:00 - 00:59:59 206 2.8% 0.06969.9 KB 3.2% 0.27
Oct 23 01:00:00 - 01:59:59 306 4.2% 0.09814.5 KB 2.7% 0.23
Oct 23 02:00:00 - 02:59:59 142 1.9% 0.04533.1 KB 1.8% 0.15
Oct 23 03:00:00 - 03:00:04 1 0.0% 0.255.6 KB 0.0% 1.41
TOTAL: 24:00:00 7357 100.0% 0.0929.2 MB 100.0% 0.35
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 950 343 0 0 0 0 0 0 607
2twilightnode-out.news.weretis.net 113 14 0 0 0 0 0 0 99
3newsfeed.endofthelinebbs.com 33 5 0 0 0 0 0 0 28
4news.tebibyte.org 2 2 0 0 0 0 0 0 0
TOTAL: 4 1098 364 0 0 0 0 0 0 734

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 21
hispagatos.mastodon.tag.hacking 21
swnet.sys.amiga 5
alt.politics.communism 4
grenzland.club 3
hispagatos.mastodon.tag.hackers 3
alt.sex.stories.d 2
europa.politica 2
fido.su.pol 2
alt.2600.madrid 1
alt.autos.performance-cars 1
alt.autos.porsche 1
alt.bbs.files-announce 1
alt.marketing.online.ebay 1
alt.music 1
alt.scientology 1
alt.sports.basketball.nba.la-lakers 1
be.politics 1
chmurka.statystyki 1
TOTAL: 23 364

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 526
Too many newsgroups 106
EMP (phn path) 59
User-issued cancel 18
HTML Multipart 5
TOTAL: 5 714

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 9850 2930 4132 270 0 7 29%23:59:50
2sklaffkom.se 7624 1002 2427 3904 0 0 13%23:59:56
3newsfeed.endofthelinebbs.com 12565 52 7088 38 0 271 0%23:59:46
4weretis.net 2409 7 2321 1 0 0 0%23:59:00
5usenet.blueworldhosting.com 8776 4 5091 0 0 6 0%23:58:54
TOTAL: 5 41224 3995 21059 4213 0 284 9%119:57:26
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.9 MB1.6 MB8.5 MB0.1 KB/s2.7 KB23:59:50
2sklaffkom.se1.5 MB19.5 MB21.1 MB0.2 KB/s4.4 KB23:59:56
3newsfeed.endofthelinebbs.com120.1 KB117.8 KB237.9 KB0.0 KB/s2.6 KB23:59:46
4weretis.net21.4 KB2.1 KB23.5 KB0.0 KB/s2.9 KB23:59:00
5usenet.blueworldhosting.com13.0 KB0.0 KB13.0 KB0.0 KB/s3.2 KB23:58:54
TOTAL: 58.6 MB21.2 MB29.9 MB0.1 KB/s3.7 KB119:57:26
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1174.96.78.81 80 29261.0 KB 13 0 000:01:54
TOTAL: 1 80 29261.0 KB 13 0 000:01:54

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 85 29261.0 KB 13 0 000:01:59
2*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
3*.infrawat.ch 1 00.0 KB 0 0 000:00:00
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
5*.stretchoid.com 1 00.0 KB 0 0 000:00:09
TOTAL: 5 89 29261.0 KB 13 0 000:02:11

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
174.96.78.81 0.501 1.119 0.00000:01:54
azpdws8xyrej.stretchoid.com 0.004 0.012 0.00000:00:09
47.245.142.53 0.002 0.017 0.00000:00:03
147.185.133.207 0.007 0.014 0.00000:00:00
o002.scanner.modat.io 0.008 0.024 0.00000:00:00
85.195.132.66.censys-scanner.com 0.024 0.019 0.00000:00:00
205.210.31.35 0.001 0.024 0.00000:00:00
185.242.226.72 0.009 0.020 0.00000:00:00
81-19-219-227.infrawat.ch 0.009 0.013 0.00000:00:00
TOTAL: 9 0.565 1.262 0.00000:02:11

Curious NNRP explorers [Top 20]:

SystemConn
185.242.226.72 2
147.185.133.207 1
205.210.31.35 1
47.245.142.53 1
81-19-219-227.infrawat.ch 1
85.195.132.66.censys-scanner.com 1
azpdws8xyrej.stretchoid.com 1
o002.scanner.modat.io 1
TOTAL: 8 9

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

SystemConn
185.242.226.72 5
47.245.142.53 3
85.195.132.66.censys-scanner.com 1
TOTAL: 3 9

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

CommandCount
#023#001#023#002#023#003#001 1
#026#003#001 1
?#024 1
Accept-Encoding: gzip 1
Accept: */* 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
TOTAL: 9 9

NNRP client timeouts [Top 20]:

SystemConnPeer
47.245.142.53 1 1
TOTAL: 1 1 4

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

HierarchyCountPct
1linux 17 58.6%
2free 8 27.6%
3talk 3 10.3%
4alt 1 3.4%
TOTAL: 4 29100.0%

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

NewsgroupCount
1linux.kernel.netdev 17
2free.usenet 8
3talk.politics.guns 3
4alt.test 1
TOTAL: 4 29
innreport from INN 2.7.3 based on initial work (©) 1996-2001 by Fabien Tassin <fta@sofaraway.org> and enhanced by several contributors since then