Daily Usenet report

Sep 22 03:00:04 -- Sep 23 03:00:04

Unknown entries from news log file:

First 50 / 118 lines (42.4%)

Sep 22 03:01:13 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 22 03:59:10 twilightnode nnrpd[3976969]: Reading access from /usr/local/news/etc/readers.conf
Sep 22 03:59:10 twilightnode nnrpd[3976969]: Auth strategy 'localhost' does not match client. Removing.
Sep 22 03:59:10 twilightnode nnrpd[3976969]: 194-88-98-117.infrawat.ch res <anonymous>
Sep 22 03:59:10 twilightnode nnrpd[3976969]: 194-88-98-117.infrawat.ch no_match_user <anonymous> <localhost>
Sep 22 03:59:10 twilightnode nnrpd[3976969]: 194-88-98-117.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 22 03:59:10 twilightnode nnrpd[3976969]: 194-88-98-117.infrawat.ch match_user <anonymous> <anonymous>
Sep 22 03:59:10 twilightnode nnrpd[3976969]: 194-88-98-117.infrawat.ch no_post anonymous-read
Sep 22 03:59:12 twilightnode nnrpd[3976970]: Reading access from /usr/local/news/etc/readers.conf
Sep 22 03:59:12 twilightnode nnrpd[3976970]: Auth strategy 'localhost' does not match client. Removing.
Sep 22 03:59:12 twilightnode nnrpd[3976970]: 31-14-254-95.infrawat.ch res <anonymous>
Sep 22 03:59:12 twilightnode nnrpd[3976970]: 31-14-254-95.infrawat.ch no_match_user <anonymous> <localhost>
Sep 22 03:59:12 twilightnode nnrpd[3976970]: 31-14-254-95.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 22 03:59:12 twilightnode nnrpd[3976970]: 31-14-254-95.infrawat.ch match_user <anonymous> <anonymous>
Sep 22 03:59:12 twilightnode nnrpd[3976970]: 31-14-254-95.infrawat.ch no_post anonymous-read
Sep 22 05:09:10 twilightnode nnrpd[3980104]: Reading access from /usr/local/news/etc/readers.conf
Sep 22 05:09:10 twilightnode nnrpd[3980104]: Auth strategy 'localhost' does not match client. Removing.
Sep 22 05:09:10 twilightnode nnrpd[3980104]: 97.172.132.66.censys-scanner.com res <anonymous>
Sep 22 05:09:10 twilightnode nnrpd[3980104]: 97.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 22 05:09:10 twilightnode nnrpd[3980104]: 97.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 22 05:09:10 twilightnode nnrpd[3980104]: 97.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 22 05:09:10 twilightnode nnrpd[3980104]: 97.172.132.66.censys-scanner.com no_post anonymous-read
Sep 22 05:09:17 twilightnode nnrpd[3980105]: Reading access from /usr/local/news/etc/readers.conf
Sep 22 05:09:17 twilightnode nnrpd[3980105]: Auth strategy 'localhost' does not match client. Removing.
Sep 22 05:09:17 twilightnode nnrpd[3980105]: 97.172.132.66.censys-scanner.com res <anonymous>
Sep 22 05:09:17 twilightnode nnrpd[3980105]: 97.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 22 05:09:17 twilightnode nnrpd[3980105]: 97.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 22 05:09:17 twilightnode nnrpd[3980105]: 97.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 22 05:09:17 twilightnode nnrpd[3980105]: 97.172.132.66.censys-scanner.com no_post anonymous-read
Sep 22 05:09:23 twilightnode nnrpd[3980106]: Reading access from /usr/local/news/etc/readers.conf
Sep 22 05:09:23 twilightnode nnrpd[3980106]: Auth strategy 'localhost' does not match client. Removing.
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com res <anonymous>
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com no_post anonymous-read
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com res <anonymous>
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 22 05:09:23 twilightnode nnrpd[3980106]: 97.172.132.66.censys-scanner.com no_post anonymous-read
Sep 22 05:48:19 twilightnode nnrpd[3981808]: Reading access from /usr/local/news/etc/readers.conf
Sep 22 05:48:19 twilightnode nnrpd[3981808]: Auth strategy 'localhost' does not match client. Removing.
Sep 22 05:48:19 twilightnode nnrpd[3981808]: azpdssk88rgm.stretchoid.com res <anonymous>
Sep 22 05:48:19 twilightnode nnrpd[3981808]: azpdssk88rgm.stretchoid.com no_match_user <anonymous> <localhost>
Sep 22 05:48:19 twilightnode nnrpd[3981808]: azpdssk88rgm.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 22 05:48:19 twilightnode nnrpd[3981808]: azpdssk88rgm.stretchoid.com match_user <anonymous> <anonymous>
Sep 22 05:48:19 twilightnode nnrpd[3981808]: azpdssk88rgm.stretchoid.com no_post anonymous-read
Sep 22 05:48:30 twilightnode nnrpd[3981809]: Reading access from /usr/local/news/etc/readers.conf
Sep 22 05:48:30 twilightnode nnrpd[3981809]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7519 55.7%1.3 MB 61.1%
innd 4526 33.5%638.0 KB 28.9%
innfeed 1264 9.4%199.4 KB 9.0%
nnrpd 191 1.4%20.0 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 13501 100.0%2.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20393 52.2%
Negative hits 11213 28.7%
Do not exist 7471 19.1%
Cache misses 2 0.0%
TOTAL: 4 39079 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.050 0.0%27 0.000 1.852 12.000
article cleanup00:00:00.086 0.0%7489 0.000 0.011 0.085
article logging00:00:00.279 0.0%7521 0.000 0.037 0.200
article parse00:00:00.132 0.0%10802 0.000 0.012 0.068
article write00:00:07.174 0.0%6333 0.600 1.133 2.889
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.256 0.0%42076 0.000 0.006 0.051
hisgrep/artcncl00:00:00.014 0.0%27 0.000 0.519 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.833 0.0%39168 0.070 0.123 0.701
history sync00:00:00.059 0.0%327 0.000 0.180 1.667
history write00:00:03.558 0.0%7489 0.167 0.475 1.014
idle23:59:45.589 99.9%42543 1034.312 2030.548 6743.764
nntp read00:00:00.822 0.0%41579 0.003 0.020 0.079
overview write00:00:05.079 0.0%6333 0.300 0.802 3.000
perl filter00:00:14.020 0.0%7489 0.974 1.872 9.893
python filter00:00:01.008 0.0%7489 0.000 0.135 0.750
site send00:00:00.317 0.0%12624 0.000 0.025 0.082
TOTAL: 1824:00:23.276100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.023 0.0%6681 0.000 0.003 0.083
article prepare00:00:00.004 0.0%27324 0.000 0.000 0.010
article read00:00:01.067 0.0%4390 0.050 0.243 2.250
backlog stats00:00:00.047 0.0%81709 0.000 0.001 0.014
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.572 0.0%46296 0.000 0.012 0.033
data write00:00:00.921 0.0%40192 0.000 0.023 0.078
idle23:59:12.060100.0%81709 381.277 1056.824 3511.854
status file00:00:00.164 0.0%167 0.000 0.982 12.000
TOTAL: 923:59:14.858100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:20.879100.0%24 75.000 869.958 10512.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%161 0.000 0.012 0.500
TOTAL: 300:00:20.881100.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 3 11540 4686 6706 148 40%49:07:25
2usenet.blueworldhosting.com 717 7425 1323 5199 903 17%23:47:08
3newsfeed.endofthelinebbs.com 1 4960 352 4529 79 7%22:55:47
4news.tebibyte.org 2 3525 120 3405 0 3%23:59:29
5news.sklaffkom.se 36 86 67 18 1 77%09:11:50
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 784 27536 6548 19857 1131 23%129:01:39
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.8 MB0.0 KB474.7 KB16.2 MB 97%3.4 KB
2usenet.blueworldhosting.com6.8 MB0.0 KB1.0 MB7.9 MB 86%3.6 KB
3newsfeed.endofthelinebbs.com3.8 MB0.0 KB116.4 KB3.9 MB 97%9.3 KB
4news.tebibyte.org496.8 KB0.0 KB0.0 KB496.8 KB100%4.1 KB
5news.sklaffkom.se178.3 KB0.0 KB0.9 KB179.3 KB 99%2.6 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 627.1 MB0.0 KB1.6 MB28.7 MB 94%3.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 22 03:00:04 - 03:59:59 112 1.8% 0.03795.9 KB 2.9% 0.22
Sep 22 04:00:00 - 04:59:59 102 1.6% 0.03482.3 KB 1.8% 0.13
Sep 22 05:00:00 - 05:59:59 103 1.6% 0.03505.9 KB 1.9% 0.14
Sep 22 06:00:00 - 06:59:59 140 2.2% 0.04556.1 KB 2.0% 0.15
Sep 22 07:00:00 - 07:59:59 160 2.5% 0.04557.3 KB 2.1% 0.15
Sep 22 08:00:00 - 08:59:59 225 3.6% 0.06781.8 KB 2.9% 0.22
Sep 22 09:00:00 - 09:59:59 312 4.9% 0.091.1 MB 4.2% 0.32
Sep 22 10:00:00 - 10:59:59 307 4.8% 0.091.1 MB 4.2% 0.32
Sep 22 11:00:00 - 11:59:59 318 5.0% 0.091.2 MB 4.4% 0.33
Sep 22 12:00:00 - 12:59:59 298 4.7% 0.081.0 MB 3.8% 0.29
Sep 22 13:00:00 - 13:59:59 368 5.8% 0.101.3 MB 5.1% 0.38
Sep 22 14:00:00 - 14:59:59 340 5.4% 0.091.6 MB 5.9% 0.45
Sep 22 15:00:00 - 15:59:59 300 4.7% 0.081.4 MB 5.2% 0.39
Sep 22 16:00:00 - 16:59:59 383 6.0% 0.111.6 MB 6.0% 0.46
Sep 22 17:00:00 - 17:59:59 379 6.0% 0.111.5 MB 5.5% 0.41
Sep 22 18:00:00 - 18:59:59 358 5.7% 0.101.4 MB 5.3% 0.40
Sep 22 19:00:00 - 19:59:59 397 6.3% 0.111.3 MB 4.8% 0.37
Sep 22 20:00:00 - 20:59:59 362 5.7% 0.101.4 MB 5.3% 0.40
Sep 22 21:00:00 - 21:59:59 291 4.6% 0.08937.4 KB 3.4% 0.26
Sep 22 22:00:00 - 22:59:59 304 4.8% 0.082.5 MB 9.2% 0.70
Sep 22 23:00:00 - 23:59:59 243 3.8% 0.071.4 MB 5.3% 0.40
Sep 23 00:00:00 - 00:59:59 196 3.1% 0.05923.4 KB 3.4% 0.26
Sep 23 01:00:00 - 01:59:59 167 2.6% 0.05599.2 KB 2.2% 0.17
Sep 23 02:00:00 - 02:59:59 156 2.5% 0.04807.5 KB 3.0% 0.22
Sep 23 03:00:00 - 03:00:04 11 0.2% 2.7512.8 KB 0.0% 3.20
TOTAL: 24:00:00 6332 100.0% 0.0726.5 MB 100.0% 0.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 903 341 0 0 0 0 0 0 562
2twilightnode-out.news.weretis.net 180 15 0 0 0 0 0 0 165
3newsfeed.endofthelinebbs.com 102 4 0 0 0 0 0 0 98
4news.sklaffkom.se 1 0 0 0 0 0 0 0 1
5news.tebibyte.org 1 1 0 0 0 0 0 0 0
TOTAL: 5 1187 361 0 0 0 0 0 0 826

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 30
newsreader.test 8
alt.test.test.jojo 6
hispagatos.mastodon.tag.hacking 6
fido.ru.home 4
hispagatos.test 3
fido.su.tormoz 2
kraft.talks 2
local.general 2
alt.bbs.files-announce 1
alt.fan.toki 1
alt.music 1
chmurka.statystyki 1
chmurka.test 1
fido.ru.fidonet.today 1
fido.ru.grob 1
fido.su.pol 1
newsgrouper.support 1
realms.bbs 1
TOTAL: 20 361

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 587
EMP (phn path) 94
Too many newsgroups 65
User-issued cancel 42
HTML Multipart 5
HTML post 1
TOTAL: 6 794

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8429 2379 3780 132 0 34 28%23:59:39
2sklaffkom.se 6399 913 2019 3363 0 0 14%23:59:44
3newsfeed.endofthelinebbs.com 10697 26 5915 17 0 333 0%23:59:30
4weretis.net 1844 7 1771 1 0 0 0%23:59:48
5usenet.blueworldhosting.com 7463 5 4533 0 0 29 0%23:57:41
TOTAL: 5 34832 3330 18018 3513 0 396 9%119:56:22
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.4 MB1.6 MB7.9 MB0.1 KB/s3.2 KB23:59:39
2sklaffkom.se1.5 MB17.0 MB18.5 MB0.2 KB/s4.4 KB23:59:44
3newsfeed.endofthelinebbs.com76.9 KB47.8 KB124.7 KB0.0 KB/s2.9 KB23:59:30
4weretis.net22.2 KB10.3 KB32.5 KB0.0 KB/s4.1 KB23:59:48
5usenet.blueworldhosting.com17.7 KB0.0 KB17.7 KB0.0 KB/s3.5 KB23:57:41
TOTAL: 58.0 MB18.7 MB26.6 MB0.1 KB/s4.0 KB119:56:22
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1184.186.132.66.censys-scanner.com 4 00.0 KB 0 0 000:00:05
297.172.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:01
3azpdssk88rgm.stretchoid.com 2 00.0 KB 0 0 000:00:10
4194-88-98-117.infrawat.ch 1 00.0 KB 0 0 000:00:00
5195-206-182-220.infrawat.ch 1 00.0 KB 0 0 000:00:00
6205.210.31.248 1 00.0 KB 0 0 000:00:01
731-14-254-102.infrawat.ch 1 00.0 KB 0 0 000:00:00
831-14-254-95.infrawat.ch 1 00.0 KB 0 0 000:00:00
9o009.scanner.modat.io 1 00.0 KB 0 0 000:00:00
10o020.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 10 16 00.0 KB 0 0 000:00:20

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 7 00.0 KB 0 0 000:00:07
2*.infrawat.ch 4 00.0 KB 0 0 000:00:01
3*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
5unresolved 1 00.0 KB 0 0 000:00:01
TOTAL: 5 16 00.0 KB 0 0 000:00:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdssk88rgm.stretchoid.com 0.006 0.023 0.00000:00:10
184.186.132.66.censys-scanner.com 0.020 0.030 0.00000:00:05
97.172.132.66.censys-scanner.com 0.037 0.024 0.00000:00:01
205.210.31.248 0.004 0.013 0.00000:00:01
o020.scanner.modat.io 0.004 0.010 0.00000:00:00
31-14-254-102.infrawat.ch 0.004 0.009 0.00000:00:00
195-206-182-220.infrawat.ch 0.004 0.013 0.00000:00:00
31-14-254-95.infrawat.ch 0.004 0.008 0.00000:00:00
o009.scanner.modat.io 0.005 0.010 0.00000:00:00
194-88-98-117.infrawat.ch 0.004 0.017 0.00000:00:00
TOTAL: 10 0.092 0.157 0.00000:00:20

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

SystemConn
97.172.132.66.censys-scanner.com 1
azpdssk88rgm.stretchoid.com 1
TOTAL: 2 2

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
184.186.132.66.censys-scanner.com 1 4
31-14-254-102.infrawat.ch 1 1
31-14-254-95.infrawat.ch 1 1
97.172.132.66.censys-scanner.com 1 2
TOTAL: 4 4 11
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