Daily Usenet report

Sep 27 03:00:03 -- Sep 28 03:00:04

Unknown entries from news log file:

First 50 / 69 lines (72.5%)

Sep 27 03:01:17 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 27 09:09:53 twilightnode nnrpd[175466]: Reading access from /usr/local/news/etc/readers.conf
Sep 27 09:09:53 twilightnode nnrpd[175466]: Auth strategy 'localhost' does not match client. Removing.
Sep 27 09:09:53 twilightnode nnrpd[175466]: azpdes5og16z.stretchoid.com res <anonymous>
Sep 27 09:09:53 twilightnode nnrpd[175466]: azpdes5og16z.stretchoid.com no_match_user <anonymous> <localhost>
Sep 27 09:09:53 twilightnode nnrpd[175466]: azpdes5og16z.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 27 09:09:53 twilightnode nnrpd[175466]: azpdes5og16z.stretchoid.com match_user <anonymous> <anonymous>
Sep 27 09:09:53 twilightnode nnrpd[175466]: azpdes5og16z.stretchoid.com no_post anonymous-read
Sep 27 09:10:03 twilightnode nnrpd[175581]: Reading access from /usr/local/news/etc/readers.conf
Sep 27 09:10:03 twilightnode nnrpd[175581]: Auth strategy 'localhost' does not match client. Removing.
Sep 27 09:10:03 twilightnode nnrpd[175581]: azpdes5og16z.stretchoid.com res <anonymous>
Sep 27 09:10:03 twilightnode nnrpd[175581]: azpdes5og16z.stretchoid.com no_match_user <anonymous> <localhost>
Sep 27 09:10:03 twilightnode nnrpd[175581]: azpdes5og16z.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 27 09:10:03 twilightnode nnrpd[175581]: azpdes5og16z.stretchoid.com match_user <anonymous> <anonymous>
Sep 27 09:10:03 twilightnode nnrpd[175581]: azpdes5og16z.stretchoid.com no_post anonymous-read
Sep 27 14:36:10 twilightnode nnrpd[190447]: Reading access from /usr/local/news/etc/readers.conf
Sep 27 14:36:10 twilightnode nnrpd[190447]: Auth strategy 'localhost' does not match client. Removing.
Sep 27 14:36:10 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com res <anonymous>
Sep 27 14:36:10 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 27 14:36:10 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 27 14:36:10 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 27 14:36:10 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com no_post anonymous-read
Sep 27 14:36:11 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com res <anonymous>
Sep 27 14:36:11 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 27 14:36:11 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 27 14:36:11 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 27 14:36:11 twilightnode nnrpd[190447]: 41.172.132.66.censys-scanner.com no_post anonymous-read
Sep 27 14:45:52 twilightnode nnrpd[190900]: Reading access from /usr/local/news/etc/readers.conf
Sep 27 14:45:52 twilightnode nnrpd[190900]: Auth strategy 'localhost' does not match client. Removing.
Sep 27 14:45:52 twilightnode nnrpd[190900]: 217-146-80-98.infrawat.ch res <anonymous>
Sep 27 14:45:52 twilightnode nnrpd[190900]: 217-146-80-98.infrawat.ch no_match_user <anonymous> <localhost>
Sep 27 14:45:52 twilightnode nnrpd[190900]: 217-146-80-98.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 27 14:45:52 twilightnode nnrpd[190900]: 217-146-80-98.infrawat.ch match_user <anonymous> <anonymous>
Sep 27 14:45:52 twilightnode nnrpd[190900]: 217-146-80-98.infrawat.ch no_post anonymous-read
Sep 27 14:48:18 twilightnode nnrpd[190935]: Reading access from /usr/local/news/etc/readers.conf
Sep 27 14:48:18 twilightnode nnrpd[190935]: Auth strategy 'localhost' does not match client. Removing.
Sep 27 14:48:18 twilightnode nnrpd[190935]: 193-176-31-217.infrawat.ch res <anonymous>
Sep 27 14:48:18 twilightnode nnrpd[190935]: 193-176-31-217.infrawat.ch no_match_user <anonymous> <localhost>
Sep 27 14:48:18 twilightnode nnrpd[190935]: 193-176-31-217.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 27 14:48:18 twilightnode nnrpd[190935]: 193-176-31-217.infrawat.ch match_user <anonymous> <anonymous>
Sep 27 14:48:18 twilightnode nnrpd[190935]: 193-176-31-217.infrawat.ch no_post anonymous-read
Sep 27 19:49:08 twilightnode nnrpd[205296]: Reading access from /usr/local/news/etc/readers.conf
Sep 27 19:49:08 twilightnode nnrpd[205296]: Auth strategy 'localhost' does not match client. Removing.
Sep 27 19:49:08 twilightnode nnrpd[205296]: 147.185.132.235 res <anonymous>
Sep 27 19:49:08 twilightnode nnrpd[205296]: 147.185.132.235 no_match_user <anonymous> <localhost>
Sep 27 19:49:08 twilightnode nnrpd[205296]: 147.185.132.235 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 27 19:49:08 twilightnode nnrpd[205296]: 147.185.132.235 match_user <anonymous> <anonymous>
Sep 27 19:49:08 twilightnode nnrpd[205296]: 147.185.132.235 no_post anonymous-read
Sep 28 01:19:41 twilightnode nnrpd[220248]: Reading access from /usr/local/news/etc/readers.conf
Sep 28 01:19:41 twilightnode nnrpd[220248]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7064 61.6%1.2 MB 68.0%
innd 2042 17.8%299.7 KB 16.2%
innfeed 1254 10.9%197.1 KB 10.7%
nnrpd 1111 9.7%95.1 KB 5.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 11472 100.0%1.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 18653 50.8%
Negative hits 11043 30.1%
Do not exist 6958 18.9%
Cache misses 71 0.2%
TOTAL: 4 36725 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.063 0.0%24 0.000 2.625 10.000
article cleanup00:00:00.063 0.0%7039 0.000 0.009 0.100
article logging00:00:00.248 0.0%7063 0.000 0.035 0.125
article parse00:00:00.132 0.0%11139 0.000 0.012 0.111
article write00:00:06.212 0.0%5949 0.398 1.044 2.556
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.217 0.0%34756 0.000 0.006 0.034
hisgrep/artcncl00:00:00.018 0.0%24 0.000 0.750 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.142 0.0%36928 0.030 0.112 0.241
history sync00:00:00.066 0.0%364 0.000 0.181 1.500
history write00:00:03.266 0.0%6991 0.188 0.467 1.089
idle24:00:13.750 99.9%34533 416.364 2502.353 13902.814
nntp read00:00:00.854 0.0%34217 0.000 0.025 0.077
overview write00:00:04.208 0.0%5949 0.228 0.707 3.632
perl filter00:00:13.804 0.0%6991 0.688 1.975 13.273
python filter00:00:01.049 0.0%6991 0.000 0.150 1.364
site send00:00:00.336 0.0%11864 0.000 0.028 0.111
TOTAL: 1824:00:48.428100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.039 0.0%7642 0.000 0.005 0.087
article prepare00:00:00.007 0.0%33712 0.000 0.000 0.008
article read00:00:01.315 0.0%5567 0.000 0.236 1.000
backlog stats00:00:00.080 0.0%71112 0.000 0.001 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.528 0.0%40355 0.000 0.013 0.045
data write00:00:00.917 0.0%35667 0.000 0.026 0.076
idle23:50:33.816100.0%71112 142.876 1207.023 4968.058
status file00:00:00.127 0.0%161 0.000 0.789 3.000
TOTAL: 923:50:36.829100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:02:46.295 99.9%1084 66.000 153.409 9880.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.186 0.1%2336 0.000 0.080 0.500
readart00:00:00.002 0.0%2 1.000 1.000 1.000
TOTAL: 400:02:46.483100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 3 11624 3859 7566 199 33%51:25:55
2usenet.blueworldhosting.com 144 6889 1754 4233 902 25%23:39:41
3newsfeed.endofthelinebbs.com 1 2345 183 2156 6 7%13:34:14
4news.tebibyte.org 3 5665 169 5490 6 2%43:07:23
5news.sklaffkom.se 8 13 10 3 0 76%01:44:05
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 184 26536 5975 19448 1113 22%133:31:18
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.5 MB149.8 KB520.8 KB13.2 MB 95%3.3 KB
2usenet.blueworldhosting.com7.9 MB0.0 KB1.2 MB9.1 MB 86%3.5 KB
3newsfeed.endofthelinebbs.com5.3 MB0.0 KB8.2 KB5.3 MB 99%29.0 KB
4news.tebibyte.org558.2 KB15.3 KB2.4 KB575.9 KB 96%3.3 KB
5news.sklaffkom.se33.1 KB0.0 KB0.0 KB33.1 KB100%3.3 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 626.4 MB165.1 KB1.8 MB28.3 MB 93%4.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 27 03:00:03 - 03:59:59 92 1.5% 0.03422.7 KB 1.6% 0.12
Sep 27 04:00:00 - 04:59:59 188 3.2% 0.052.9 MB 11.1% 0.83
Sep 27 05:00:00 - 05:59:59 807 13.6% 0.223.7 MB 14.2% 1.06
Sep 27 06:00:00 - 06:59:59 106 1.8% 0.03391.4 KB 1.5% 0.11
Sep 27 07:00:00 - 07:59:59 131 2.2% 0.04471.6 KB 1.7% 0.13
Sep 27 08:00:00 - 08:59:59 246 4.1% 0.071.1 MB 4.0% 0.30
Sep 27 09:00:00 - 09:59:59 261 4.4% 0.07970.4 KB 3.6% 0.27
Sep 27 10:00:00 - 10:59:59 289 4.9% 0.08867.0 KB 3.2% 0.24
Sep 27 11:00:00 - 11:59:59 329 5.5% 0.091.2 MB 4.5% 0.34
Sep 27 12:00:00 - 12:59:59 367 6.2% 0.101.3 MB 4.8% 0.36
Sep 27 13:00:00 - 13:59:59 240 4.0% 0.071.2 MB 4.6% 0.34
Sep 27 14:00:00 - 14:59:59 214 3.6% 0.06985.6 KB 3.7% 0.27
Sep 27 15:00:00 - 15:59:59 207 3.5% 0.06770.3 KB 2.9% 0.21
Sep 27 16:00:00 - 16:59:59 388 6.5% 0.111.3 MB 4.9% 0.37
Sep 27 17:00:00 - 17:59:59 243 4.1% 0.07996.7 KB 3.7% 0.28
Sep 27 18:00:00 - 18:59:59 249 4.2% 0.071.1 MB 4.1% 0.30
Sep 27 19:00:00 - 19:59:59 270 4.5% 0.07875.1 KB 3.2% 0.24
Sep 27 20:00:00 - 20:59:59 252 4.2% 0.07986.3 KB 3.7% 0.27
Sep 27 21:00:00 - 21:59:59 238 4.0% 0.07786.9 KB 2.9% 0.22
Sep 27 22:00:00 - 22:59:59 201 3.4% 0.06969.7 KB 3.6% 0.27
Sep 27 23:00:00 - 23:59:59 173 2.9% 0.05999.1 KB 3.7% 0.28
Sep 28 00:00:00 - 00:59:59 237 4.0% 0.071.5 MB 5.7% 0.43
Sep 28 01:00:00 - 01:59:59 113 1.9% 0.03430.6 KB 1.6% 0.12
Sep 28 02:00:00 - 02:59:59 102 1.7% 0.03419.3 KB 1.6% 0.12
Sep 28 03:00:00 - 03:00:04 9 0.2% 2.258.1 KB 0.0% 2.02
TOTAL: 24:00:01 5952 100.0% 0.0726.3 MB 100.0% 0.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 900 308 0 0 0 0 0 0 592
2twilightnode-out.news.weretis.net 191 6 0 48 0 0 0 0 137
3newsfeed.endofthelinebbs.com 20 0 0 0 0 0 0 0 20
4news.tebibyte.org 1 1 0 0 0 0 0 0 0
TOTAL: 4 1112 315 0 48 0 0 0 0 749

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 9
hispagatos.mastodon.tag.hacking 4
fr.comp.securite 3
realms.sysops 2
alt.bbs.files-announce 1
alt.sports.basketball.nba.la-lakers 1
alt.sports.football.pro.dallas-cowboys 1
alt.sports.football.pro.jville-jaguars 1
alt.test.1 1
ch.admin 1
fido.golded 1
fido.ru.golded 1
realms.general 1
TOTAL: 14 315

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 529
EMP (phn path) 80
Too many newsgroups 76
User-issued cancel 32
HTML Multipart 8
TOTAL: 5 725

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 24
TOTAL: 1 24
TOTAL: 1 24

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7870 2770 3119 35 0 540 35%23:59:57
2sklaffkom.se 5963 950 355 4616 0 0 15%23:59:57
3newsfeed.endofthelinebbs.com 8953 53 5567 39 0 719 0%23:59:57
4usenet.blueworldhosting.com 5466 9 3808 0 0 490 0%23:59:43
5weretis.net 2095 4 2064 0 0 0 0%23:57:57
TOTAL: 5 30347 3786 14913 4690 0 1749 12%119:57:31
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org7.6 MB987.1 KB8.6 MB0.1 KB/s3.1 KB23:59:57
2sklaffkom.se1.6 MB23.1 MB24.7 MB0.3 KB/s4.5 KB23:59:57
3newsfeed.endofthelinebbs.com145.4 KB139.8 KB285.2 KB0.0 KB/s3.1 KB23:59:57
4usenet.blueworldhosting.com20.2 KB0.0 KB20.2 KB0.0 KB/s2.2 KB23:59:43
5weretis.net11.4 KB0.0 KB11.4 KB0.0 KB/s2.9 KB23:57:57
TOTAL: 59.4 MB24.2 MB33.6 MB0.1 KB/s4.1 KB119:57:31
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 2 2352.9 KB 2 0 000:02:33
TOTAL: 1 2 2352.9 KB 2 0 000:02:33

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 2352.9 KB 2 0 000:02:33
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.66.censys-scanner.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 9 2352.9 KB 2 0 000:02:46

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 0.096 0.322 0.00000:02:33
azpdes5og16z.stretchoid.com 0.009 0.020 0.00000:00:10
41.172.132.66.censys-scanner.com 0.025 0.020 0.00000:00:01
193-176-31-217.infrawat.ch 0.004 0.009 0.00000:00:00
o034.scanner.modat.io 0.004 0.011 0.00000:00:00
147.185.132.235 0.006 0.013 0.00000:00:00
217-146-80-98.infrawat.ch 0.006 0.007 0.00000:00:00
TOTAL: 7 0.150 0.402 0.00000:02:46

Curious NNRP explorers [Top 20]:

SystemConn
azpdes5og16z.stretchoid.com 2
147.185.132.235 1
193-176-31-217.infrawat.ch 1
217-146-80-98.infrawat.ch 1
41.172.132.66.censys-scanner.com 1
o034.scanner.modat.io 1
TOTAL: 6 7

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

SystemConn
147.185.132.235 4
41.172.132.66.censys-scanner.com 1
azpdes5og16z.stretchoid.com 1
TOTAL: 3 6

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

CommandCount
Accept-Encoding: gzip 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
MGLNDD_172.234.115.63_119 1
User-Agent: Hello from Palo Alto Network... 1
TOTAL: 6 6

NNRP client timeouts [Top 20]:

SystemConnPeer
193-176-31-217.infrawat.ch 1 1
TOTAL: 1 1 2

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

HierarchyCountPct
1alt 1 50.0%
2lada 1 50.0%
TOTAL: 2 2100.0%

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

NewsgroupCount
1alt.os.linux.slackware 1
2lada.talk 1
TOTAL: 2 2
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