Daily Usenet report

Oct 18 03:00:06 -- Oct 19 03:00:05

Unknown entries from news log file:

First 50 / 2127 lines (2.4%)

Oct 18 03:01:21 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 18 03:01:29 twilightnode nnrpd[273906]: Reading access from /usr/local/news/etc/readers.conf
Oct 18 03:01:29 twilightnode nnrpd[273906]: Auth strategy 'localhost' does not match client. Removing.
Oct 18 03:01:29 twilightnode nnrpd[273906]: 174.96.78.81 res <anonymous>
Oct 18 03:01:29 twilightnode nnrpd[273906]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 18 03:01:29 twilightnode nnrpd[273906]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 18 03:01:29 twilightnode nnrpd[273906]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 18 03:01:29 twilightnode nnrpd[273906]: 174.96.78.81 no_post anonymous-read
Oct 18 03:01:30 twilightnode nnrpd[273907]: Reading access from /usr/local/news/etc/readers.conf
Oct 18 03:01:30 twilightnode nnrpd[273907]: Auth strategy 'localhost' does not match client. Removing.
Oct 18 03:01:30 twilightnode nnrpd[273907]: 174.96.78.81 res <anonymous>
Oct 18 03:01:30 twilightnode nnrpd[273907]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 18 03:01:30 twilightnode nnrpd[273907]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 18 03:01:30 twilightnode nnrpd[273907]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 18 03:01:30 twilightnode nnrpd[273907]: 174.96.78.81 no_post anonymous-read
Oct 18 03:05:07 twilightnode nnrpd[274040]: Reading access from /usr/local/news/etc/readers.conf
Oct 18 03:05:07 twilightnode nnrpd[274040]: Auth strategy 'localhost' does not match client. Removing.
Oct 18 03:05:07 twilightnode nnrpd[274040]: 174.96.78.81 res <anonymous>
Oct 18 03:05:07 twilightnode nnrpd[274040]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 18 03:05:07 twilightnode nnrpd[274040]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 18 03:05:07 twilightnode nnrpd[274040]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 18 03:05:07 twilightnode nnrpd[274040]: 174.96.78.81 no_post anonymous-read
Oct 18 03:05:07 twilightnode nnrpd[274041]: Reading access from /usr/local/news/etc/readers.conf
Oct 18 03:05:07 twilightnode nnrpd[274041]: Auth strategy 'localhost' does not match client. Removing.
Oct 18 03:05:07 twilightnode nnrpd[274041]: 174.96.78.81 res <anonymous>
Oct 18 03:05:07 twilightnode nnrpd[274041]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 18 03:05:07 twilightnode nnrpd[274041]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 18 03:05:07 twilightnode nnrpd[274041]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 18 03:05:07 twilightnode nnrpd[274041]: 174.96.78.81 no_post anonymous-read
Oct 18 03:06:04 twilightnode nnrpd[274094]: Reading access from /usr/local/news/etc/readers.conf
Oct 18 03:06:04 twilightnode nnrpd[274094]: Auth strategy 'localhost' does not match client. Removing.
Oct 18 03:06:04 twilightnode nnrpd[274094]: 174.96.78.81 res <anonymous>
Oct 18 03:06:04 twilightnode nnrpd[274094]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 18 03:06:04 twilightnode nnrpd[274094]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 18 03:06:04 twilightnode nnrpd[274094]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 18 03:06:04 twilightnode nnrpd[274094]: 174.96.78.81 no_post anonymous-read
Oct 18 03:06:04 twilightnode nnrpd[274095]: Reading access from /usr/local/news/etc/readers.conf
Oct 18 03:06:04 twilightnode nnrpd[274095]: Auth strategy 'localhost' does not match client. Removing.
Oct 18 03:06:04 twilightnode nnrpd[274095]: 174.96.78.81 res <anonymous>
Oct 18 03:06:04 twilightnode nnrpd[274095]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 18 03:06:04 twilightnode nnrpd[274095]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 18 03:06:04 twilightnode nnrpd[274095]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 18 03:06:04 twilightnode nnrpd[274095]: 174.96.78.81 no_post anonymous-read
Oct 18 03:07:19 twilightnode nnrpd[274107]: Reading access from /usr/local/news/etc/readers.conf
Oct 18 03:07:19 twilightnode nnrpd[274107]: Auth strategy 'localhost' does not match client. Removing.
Oct 18 03:07:19 twilightnode nnrpd[274107]: 174.96.78.81 res <anonymous>
Oct 18 03:07:19 twilightnode nnrpd[274107]: 174.96.78.81 no_match_user <anonymous> <localhost>
Oct 18 03:07:19 twilightnode nnrpd[274107]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 18 03:07:19 twilightnode nnrpd[274107]: 174.96.78.81 match_user <anonymous> <anonymous>
Oct 18 03:07:19 twilightnode nnrpd[274107]: 174.96.78.81 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 9479 41.2%1.7 MB 54.4%
nnrpd 9186 40.0%821.8 KB 25.9%
innd 3079 13.4%420.7 KB 13.3%
innfeed 1248 5.4%206.1 KB 6.5%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 22993 100.0%3.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 25336 51.9%
Negative hits 14055 28.8%
Do not exist 9452 19.4%
Cache misses 3 0.0%
TOTAL: 4 48846 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.037 0.0%18 1.000 2.056 5.000
article cleanup00:00:00.162 0.0%9471 0.000 0.017 0.087
article logging00:00:00.495 0.0%9483 0.000 0.052 0.170
article parse00:00:00.252 0.0%11819 0.000 0.021 0.130
article write00:00:10.732 0.0%8081 0.536 1.328 2.806
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.286 0.0%49500 0.000 0.006 0.039
hisgrep/artcncl00:00:00.015 0.0%18 0.000 0.833 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:07.883 0.0%48931 0.093 0.161 0.375
history sync00:00:00.090 0.0%335 0.000 0.269 4.667
history write00:00:05.200 0.0%9470 0.273 0.549 1.182
idle23:59:19.170 99.9%50327 822.436 1715.961 4916.811
nntp read00:00:01.487 0.0%49247 0.008 0.030 0.070
overview write00:00:07.384 0.0%8081 0.330 0.914 2.462
perl filter00:00:23.936 0.0%9470 1.170 2.528 7.211
python filter00:00:02.036 0.0%9470 0.084 0.215 0.737
site send00:00:00.722 0.0%16119 0.000 0.045 0.148
TOTAL: 1824:00:19.887100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.086 0.0%8483 0.000 0.010 0.087
article prepare00:00:00.004 0.0%32516 0.000 0.000 0.006
article read00:00:01.737 0.0%5089 0.000 0.341 2.533
backlog stats00:00:00.102 0.0%105129 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.129 0.0%59775 0.005 0.019 0.058
data write00:00:01.782 0.0%52023 0.000 0.034 0.071
idle23:50:00.940100.0%105129 356.392 816.149 1949.812
status file00:00:00.208 0.0%157 0.000 1.325 12.000
TOTAL: 923:50:05.988100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:21:30.247100.0%6809 121.000 189.491 10058.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.351 0.0%15678 0.000 0.022 0.500
readart00:00:00.061 0.0%185 0.000 0.330 0.604
TOTAL: 400:21:30.659100.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 4 14518 5632 8813 73 38%44:13:37
2usenet.blueworldhosting.com 287 9328 2188 5861 1279 23%23:40:31
3news.sklaffkom.se 37 103 88 14 1 85%10:06:36
4newsfeed.endofthelinebbs.com 2 4447 69 4353 25 1%23:59:24
5news.tebibyte.org 2 5769 32 5736 1 0%29:34:19
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 357 34165 8009 24777 1379 23%131:34:27
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net16.4 MB0.0 KB139.9 KB16.6 MB 99%3.0 KB
2usenet.blueworldhosting.com6.8 MB1.2 KB2.8 MB9.6 MB 70%2.8 KB
3newsfeed.endofthelinebbs.com1.4 MB0.0 KB58.9 KB1.4 MB 95%15.5 KB
4news.sklaffkom.se291.3 KB0.0 KB0.8 KB292.1 KB 99%3.3 KB
5news.tebibyte.org183.7 KB0.0 KB1.3 KB185.0 KB 99%5.6 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 625.1 MB1.2 KB3.0 MB28.1 MB 89%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 18 03:00:06 - 03:59:59 191 2.4% 0.05742.2 KB 2.8% 0.21
Oct 18 04:00:00 - 04:59:59 255 3.2% 0.071.0 MB 3.9% 0.29
Oct 18 05:00:00 - 05:59:59 201 2.5% 0.061023.7 KB 3.9% 0.28
Oct 18 06:00:00 - 06:59:59 203 2.5% 0.06626.0 KB 2.4% 0.17
Oct 18 07:00:00 - 07:59:59 361 4.5% 0.10758.5 KB 2.9% 0.21
Oct 18 08:00:00 - 08:59:59 259 3.2% 0.07652.9 KB 2.5% 0.18
Oct 18 09:00:00 - 09:59:59 320 4.0% 0.09681.5 KB 2.6% 0.19
Oct 18 10:00:00 - 10:59:59 388 4.8% 0.11875.4 KB 3.3% 0.24
Oct 18 11:00:00 - 11:59:59 453 5.6% 0.131.1 MB 4.2% 0.30
Oct 18 12:00:00 - 12:59:59 474 5.9% 0.131.5 MB 5.7% 0.42
Oct 18 13:00:00 - 13:59:59 431 5.3% 0.121.1 MB 4.4% 0.32
Oct 18 14:00:00 - 14:59:59 420 5.2% 0.121.1 MB 4.4% 0.32
Oct 18 15:00:00 - 15:59:59 387 4.8% 0.111.0 MB 4.0% 0.30
Oct 18 16:00:00 - 16:59:59 358 4.4% 0.101.0 MB 4.0% 0.30
Oct 18 17:00:00 - 17:59:59 448 5.5% 0.121.4 MB 5.4% 0.40
Oct 18 18:00:00 - 18:59:59 353 4.4% 0.101.5 MB 5.8% 0.43
Oct 18 19:00:00 - 19:59:59 406 5.0% 0.111.1 MB 4.4% 0.32
Oct 18 20:00:00 - 20:59:59 344 4.3% 0.101.1 MB 4.3% 0.32
Oct 18 21:00:00 - 21:59:59 457 5.7% 0.132.9 MB 11.2% 0.82
Oct 18 22:00:00 - 22:59:59 375 4.6% 0.101.5 MB 5.8% 0.42
Oct 18 23:00:00 - 23:59:59 265 3.3% 0.07888.4 KB 3.4% 0.25
Oct 19 00:00:00 - 00:59:59 254 3.1% 0.07781.6 KB 3.0% 0.22
Oct 19 01:00:00 - 01:59:59 291 3.6% 0.081017.5 KB 3.9% 0.28
Oct 19 02:00:00 - 02:59:59 174 2.2% 0.05518.4 KB 2.0% 0.14
Oct 19 03:00:00 - 03:00:05 10 0.1% 2.0011.0 KB 0.0% 2.20
TOTAL: 23:59:59 8078 100.0% 0.0925.8 MB 100.0% 0.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1284 324 0 1 0 0 0 0 959
2twilightnode-out.news.weretis.net 83 14 0 0 0 0 0 0 69
3newsfeed.endofthelinebbs.com 33 2 0 0 0 0 0 0 31
4news.sklaffkom.se 1 0 0 0 0 0 0 0 1
TOTAL: 4 1401 340 0 1 0 0 0 0 1060

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 25
hispagatos.mastodon.tag.hacking 7
grenzland.club 3
alt.sports.football.pro.tennessee 2
sfnet.harrastus.musiikki 2
alt.bbs.files-announce 1
alt.collecting.records 1
alt.sports.football.pro.buffalo-bills 1
alt.sports.football.pro.cinci-bengals 1
alt.sports.football.pro.dallas-cowboys 1
alt.sports.football.pro.houston-oilers 1
alt.sports.football.pro.kc-chiefs 1
alt.sports.football.pro.la-raiders 1
alt.sports.football.pro.no-saints 1
alt.sports.football.pro.wash-redskins 1
brasil.ciencia.fisica 1
chmurka.statystyki 1
hamster.de.tools 1
TOTAL: 19 340

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 884
Too many newsgroups 129
User-issued cancel 34
HTML Multipart 1
TOTAL: 4 1048

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 10
usenet.blueworldhosting.com 2
TOTAL: 2 12
TOTAL: 1 12

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 11394 3109 4885 45 0 71 27%23:59:56
2sklaffkom.se 8802 918 3138 3982 0 1 10%23:59:56
3newsfeed.endofthelinebbs.com 13833 68 7885 17 0 362 0%23:59:44
4weretis.net 2425 9 2376 0 0 0 0%23:58:19
5usenet.blueworldhosting.com 9150 1 5514 0 0 26 0%23:59:56
TOTAL: 5 45604 4105 23798 4044 0 460 9%119:57:51
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org7.1 MB1.5 MB8.6 MB0.1 KB/s2.8 KB23:59:56
2sklaffkom.se1.5 MB16.0 MB17.4 MB0.2 KB/s3.6 KB23:59:56
3newsfeed.endofthelinebbs.com216.4 KB35.4 KB251.7 KB0.0 KB/s3.0 KB23:59:44
4weretis.net9.3 KB0.0 KB9.3 KB0.0 KB/s1.0 KB23:58:19
5usenet.blueworldhosting.com7.3 KB0.0 KB7.3 KB0.0 KB/s7.3 KB23:59:56
TOTAL: 58.8 MB17.5 MB26.3 MB0.1 KB/s3.3 KB119:57:51
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1174.96.78.81 292 18512.2 MB 5 0 000:20:58
TOTAL: 1 292 18512.2 MB 5 0 000:20:58

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 297 18512.2 MB 5 0 000:21:15
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
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
6*.stretchoid.com 1 00.0 KB 0 0 000:00:09
TOTAL: 6 303 18512.2 MB 5 0 000:21:30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
174.96.78.81 2.330 5.046 0.00000:20:58
43.98.184.52 0.011 0.035 0.00000:00:15
azpdsso5arwf.stretchoid.com 0.008 0.015 0.00000:00:09
103.172.132.66.censys-scanner.com 0.051 0.029 0.00000:00:02
r4-76-4c.monitoring.internet-measurement.com 0.007 0.010 0.00000:00:01
31-14-254-72.infrawat.ch 0.006 0.005 0.00000:00:00
o042.scanner.modat.io 0.003 0.015 0.00000:00:00
205.210.31.28 0.006 0.012 0.00000:00:00
198.235.24.115 0.007 0.023 0.00000:00:00
69-5-169-105.infrawat.ch 0.005 0.017 0.00000:00:00
TOTAL: 10 2.434 5.207 0.00000:21:30

Curious NNRP explorers [Top 20]:

SystemConn
43.98.184.52 3
103.172.132.66.censys-scanner.com 1
198.235.24.115 1
205.210.31.28 1
31-14-254-72.infrawat.ch 1
69-5-169-105.infrawat.ch 1
azpdsso5arwf.stretchoid.com 1
o042.scanner.modat.io 1
r4-76-4c.monitoring.internet-measurement.com 1
TOTAL: 9 11

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

SystemConn
43.98.184.52 3
103.172.132.66.censys-scanner.com 1
TOTAL: 2 4

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

CommandCount
#026#003#001 1
?#024 1
?#?' 1
EHLO www.censys.io 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
31-14-254-72.infrawat.ch 1 1
TOTAL: 1 1 4

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

HierarchyCountPct
1microsoft 182 98.4%
2alt 2 1.1%
3uk 1 0.5%
TOTAL: 3 185100.0%

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

NewsgroupCount
1microsoft.public.windowsxp.general 182
2alt.arts.poetry.comments 1
3alt.checkmate 1
4uk.politics.misc 1
TOTAL: 4 185
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