Daily Usenet report

Oct 12 03:00:05 -- Oct 13 03:00:04

Unknown entries from news log file:

First 50 / 996 lines (5.0%)

Oct 12 03:01:31 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 12 03:07:04 twilightnode nnrpd[1238278]: Reading access from /usr/local/news/etc/readers.conf
Oct 12 03:07:04 twilightnode nnrpd[1238278]: Auth strategy 'localhost' does not match client. Removing.
Oct 12 03:07:04 twilightnode nnrpd[1238278]: 66.61.98.95 res <anonymous>
Oct 12 03:07:04 twilightnode nnrpd[1238278]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 12 03:07:04 twilightnode nnrpd[1238278]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 12 03:07:04 twilightnode nnrpd[1238278]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 12 03:07:04 twilightnode nnrpd[1238278]: 66.61.98.95 no_post anonymous-read
Oct 12 03:07:04 twilightnode nnrpd[1238279]: Reading access from /usr/local/news/etc/readers.conf
Oct 12 03:07:04 twilightnode nnrpd[1238279]: Auth strategy 'localhost' does not match client. Removing.
Oct 12 03:07:04 twilightnode nnrpd[1238279]: 66.61.98.95 res <anonymous>
Oct 12 03:07:04 twilightnode nnrpd[1238279]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 12 03:07:04 twilightnode nnrpd[1238279]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 12 03:07:04 twilightnode nnrpd[1238279]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 12 03:07:04 twilightnode nnrpd[1238279]: 66.61.98.95 no_post anonymous-read
Oct 12 03:07:27 twilightnode nnrpd[1238280]: Reading access from /usr/local/news/etc/readers.conf
Oct 12 03:07:27 twilightnode nnrpd[1238280]: Auth strategy 'localhost' does not match client. Removing.
Oct 12 03:07:27 twilightnode nnrpd[1238280]: 66.61.98.95 res <anonymous>
Oct 12 03:07:27 twilightnode nnrpd[1238280]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 12 03:07:27 twilightnode nnrpd[1238280]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 12 03:07:27 twilightnode nnrpd[1238280]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 12 03:07:27 twilightnode nnrpd[1238280]: 66.61.98.95 no_post anonymous-read
Oct 12 03:07:27 twilightnode nnrpd[1238281]: Reading access from /usr/local/news/etc/readers.conf
Oct 12 03:07:27 twilightnode nnrpd[1238281]: Auth strategy 'localhost' does not match client. Removing.
Oct 12 03:07:27 twilightnode nnrpd[1238281]: 66.61.98.95 res <anonymous>
Oct 12 03:07:27 twilightnode nnrpd[1238281]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 12 03:07:27 twilightnode nnrpd[1238281]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 12 03:07:27 twilightnode nnrpd[1238281]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 12 03:07:27 twilightnode nnrpd[1238281]: 66.61.98.95 no_post anonymous-read
Oct 12 03:08:53 twilightnode nnrpd[1238302]: Reading access from /usr/local/news/etc/readers.conf
Oct 12 03:08:53 twilightnode nnrpd[1238302]: Auth strategy 'localhost' does not match client. Removing.
Oct 12 03:08:53 twilightnode nnrpd[1238302]: 66.61.98.95 res <anonymous>
Oct 12 03:08:53 twilightnode nnrpd[1238302]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 12 03:08:53 twilightnode nnrpd[1238302]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 12 03:08:53 twilightnode nnrpd[1238302]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 12 03:08:53 twilightnode nnrpd[1238302]: 66.61.98.95 no_post anonymous-read
Oct 12 03:08:53 twilightnode nnrpd[1238303]: Reading access from /usr/local/news/etc/readers.conf
Oct 12 03:08:53 twilightnode nnrpd[1238303]: Auth strategy 'localhost' does not match client. Removing.
Oct 12 03:08:53 twilightnode nnrpd[1238303]: 66.61.98.95 res <anonymous>
Oct 12 03:08:53 twilightnode nnrpd[1238303]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 12 03:08:53 twilightnode nnrpd[1238303]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 12 03:08:53 twilightnode nnrpd[1238303]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 12 03:08:53 twilightnode nnrpd[1238303]: 66.61.98.95 no_post anonymous-read
Oct 12 03:09:11 twilightnode nnrpd[1238369]: Reading access from /usr/local/news/etc/readers.conf
Oct 12 03:09:11 twilightnode nnrpd[1238369]: Auth strategy 'localhost' does not match client. Removing.
Oct 12 03:09:11 twilightnode nnrpd[1238369]: 66.61.98.95 res <anonymous>
Oct 12 03:09:11 twilightnode nnrpd[1238369]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 12 03:09:11 twilightnode nnrpd[1238369]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 12 03:09:11 twilightnode nnrpd[1238369]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 12 03:09:11 twilightnode nnrpd[1238369]: 66.61.98.95 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 8382 43.1%788.8 KB 29.8%
inn 7067 36.4%1.2 MB 47.5%
innd 2735 14.1%393.8 KB 14.9%
innfeed 1250 6.4%207.3 KB 7.8%
controlchan 3 0.0%0.5 KB 0.0%
TOTAL: 5 19437 100.0%2.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19176 52.1%
Negative hits 10583 28.7%
Do not exist 7042 19.1%
Cache misses 25 0.1%
TOTAL: 4 36826 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.058 0.0%19 1.200 3.053 11.000
article cleanup00:00:00.095 0.0%7061 0.000 0.013 0.074
article logging00:00:00.347 0.0%7073 0.000 0.049 0.200
article parse00:00:00.128 0.0%9657 0.000 0.013 0.074
article write00:00:07.421 0.0%5943 0.724 1.249 2.656
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.264 0.0%39175 0.000 0.007 0.056
hisgrep/artcncl00:00:00.016 0.0%19 0.250 0.842 3.000
hissync/artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.045 0.0%36943 0.051 0.137 0.295
history sync00:00:00.085 0.0%411 0.000 0.207 8.667
history write00:00:03.649 0.0%7062 0.111 0.517 1.259
idle24:00:18.771 99.9%39568 979.444 2184.057 8953.866
nntp read00:00:00.795 0.0%38682 0.000 0.021 0.063
overview write00:00:05.546 0.0%5943 0.400 0.933 2.242
perl filter00:00:17.179 0.0%7061 1.145 2.433 9.593
python filter00:00:01.153 0.0%7061 0.000 0.163 1.111
site send00:00:00.280 0.0%11852 0.000 0.024 0.571
TOTAL: 2024:01:00.832100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.047 0.0%6179 0.000 0.008 0.222
article prepare00:00:00.002 0.0%25036 0.000 0.000 0.005
article read00:00:01.308 0.0%4135 0.000 0.316 1.500
backlog stats00:00:00.072 0.0%79333 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.595 0.0%44493 0.000 0.013 0.040
data write00:00:00.972 0.0%38493 0.000 0.025 0.081
idle23:59:15.550100.0%79333 309.303 1088.520 4427.787
status file00:00:00.130 0.0%159 0.000 0.818 4.000
TOTAL: 923:59:18.676100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:22:47.864100.0%8108 122.000 168.705 20076.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.332 0.0%19310 0.000 0.017 0.333
readart00:00:00.136 0.0%276 0.000 0.493 1.000
TOTAL: 400:22:48.332100.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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org020002
TOTAL020002

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 2 10228 3969 6212 47 38%45:16:18
2usenet.blueworldhosting.com 288 7004 1546 4426 1032 22%23:54:01
3newsfeed.endofthelinebbs.com 2 9129 251 8823 55 2%42:39:37
4news.tebibyte.org 1 3631 87 3541 3 2%23:59:54
5news.sklaffkom.se 39 96 81 14 1 84%10:32:35
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 357 30088 5934 23016 1138 19%146:22:25
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.6 MB0.0 KB184.3 KB12.7 MB 98%3.2 KB
2usenet.blueworldhosting.com8.5 MB0.0 KB1.3 MB9.7 MB 86%3.9 KB
3newsfeed.endofthelinebbs.com2.8 MB0.0 KB120.4 KB2.9 MB 95%9.7 KB
4news.tebibyte.org276.6 KB0.0 KB4.0 KB280.6 KB 98%3.1 KB
5news.sklaffkom.se254.3 KB0.0 KB0.9 KB255.2 KB 99%3.1 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 624.3 MB0.0 KB1.6 MB25.9 MB 93%3.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 12 03:00:05 - 03:59:59 120 2.0% 0.03546.2 KB 2.2% 0.15
Oct 12 04:00:00 - 04:59:59 122 2.1% 0.03667.2 KB 2.7% 0.19
Oct 12 05:00:00 - 05:59:59 136 2.3% 0.04511.8 KB 2.1% 0.14
Oct 12 06:00:00 - 06:59:59 186 3.1% 0.05772.9 KB 3.2% 0.21
Oct 12 07:00:00 - 07:59:59 115 1.9% 0.03486.9 KB 2.0% 0.14
Oct 12 08:00:00 - 08:59:59 232 3.9% 0.06845.0 KB 3.5% 0.23
Oct 12 09:00:00 - 09:59:59 301 5.1% 0.081.0 MB 4.2% 0.28
Oct 12 10:00:00 - 10:59:59 251 4.2% 0.071014.9 KB 4.2% 0.28
Oct 12 11:00:00 - 11:59:59 367 6.2% 0.101.3 MB 5.3% 0.36
Oct 12 12:00:00 - 12:59:59 366 6.2% 0.101.3 MB 5.3% 0.36
Oct 12 13:00:00 - 13:59:59 313 5.3% 0.091.5 MB 6.2% 0.42
Oct 12 14:00:00 - 14:59:59 297 5.0% 0.081.3 MB 5.2% 0.36
Oct 12 15:00:00 - 15:59:59 273 4.6% 0.081.1 MB 4.7% 0.32
Oct 12 16:00:00 - 16:59:59 333 5.6% 0.091.4 MB 5.7% 0.39
Oct 12 17:00:00 - 17:59:59 356 6.0% 0.101.6 MB 6.6% 0.45
Oct 12 18:00:00 - 18:59:59 303 5.1% 0.081.3 MB 5.3% 0.36
Oct 12 19:00:00 - 19:59:59 323 5.4% 0.091.6 MB 6.8% 0.46
Oct 12 20:00:00 - 20:59:59 308 5.2% 0.091.2 MB 4.8% 0.33
Oct 12 21:00:00 - 21:59:59 297 5.0% 0.081.2 MB 5.0% 0.34
Oct 12 22:00:00 - 22:59:59 228 3.8% 0.06738.3 KB 3.0% 0.21
Oct 12 23:00:00 - 23:59:59 230 3.9% 0.061.0 MB 4.4% 0.30
Oct 13 00:00:00 - 00:59:59 207 3.5% 0.06718.6 KB 2.9% 0.20
Oct 13 01:00:00 - 01:59:59 133 2.2% 0.04624.1 KB 2.6% 0.17
Oct 13 02:00:00 - 02:59:59 131 2.2% 0.04555.7 KB 2.3% 0.15
Oct 13 03:00:00 - 03:00:04 9 0.2% 2.258.8 KB 0.0% 2.19
TOTAL: 23:59:59 5937 100.0% 0.0723.9 MB 100.0% 0.28
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1031 375 0 0 0 0 0 0 656
2twilightnode-out.news.weretis.net 65 9 0 0 0 0 0 0 56
3newsfeed.endofthelinebbs.com 31 4 0 0 0 0 0 0 27
4news.tebibyte.org 2 1 0 0 0 0 0 0 1
5news.sklaffkom.se 1 0 0 0 0 0 0 0 1
TOTAL: 5 1130 389 0 0 0 0 0 0 741

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 71
hispagatos.mastodon.tag.hacking 8
lada.auto 6
newsgrouper.support 2
alt.agnosticism 1
alt.autos.performance-cars 1
alt.bbs.files-announce 1
alt.fan.publius 1
alt.sports.football.pro.no-saints 1
alt.test.1 1
chmurka.statystyki 1
fido.pushkin.local 1
fido.su.pol 1
gnu.misc.discuss 1
hispagatos.mastodon.tag.hackers 1
hispagatos.test 1
osnet.test 1
pl.listserv.chomor-l 1
TOTAL: 19 389

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 573
Too many newsgroups 99
EMP (phn path) 52
Too many newsgroups (meow) 2
User-issued cancel 2
TOTAL: 5 728

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8090 2046 3727 129 0 25 25%23:59:01
2sklaffkom.se 6100 901 1870 3129 0 9 14%23:59:56
3newsfeed.endofthelinebbs.com 10372 26 5664 29 0 244 0%23:59:56
4weretis.net 1904 6 1852 0 0 0 0%23:59:45
5usenet.blueworldhosting.com 6861 0 4117 0 0 9 0%23:59:32
TOTAL: 5 33327 2979 17230 3287 0 287 8%119:58:10
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org5.3 MB1.3 MB6.7 MB0.1 KB/s3.1 KB23:59:01
2sklaffkom.se1.7 MB15.5 MB17.2 MB0.2 KB/s4.4 KB23:59:56
3newsfeed.endofthelinebbs.com144.7 KB63.8 KB208.4 KB0.0 KB/s3.8 KB23:59:56
4weretis.net6.4 KB0.0 KB6.4 KB0.0 KB/s1.1 KB23:59:45
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:32
TOTAL: 57.2 MB16.9 MB24.1 MB0.1 KB/s3.9 KB119:58:10
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 90 2454.2 MB 39 0 000:16:54
22603:6010:6802:4ca5:1a03:73ff:fe28:be0f 38 3188.4 KB 10 0 000:04:59
TOTAL: 2 128 2764.3 MB 49 0 000:21:53

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 129 2764.3 MB 49 0 000:22:13
2*.66.censys-scanner.com 7 00.0 KB 0 0 000:00:20
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
4*.infrawat.ch 1 00.0 KB 0 0 000:00:00
5*.stretchoid.com 1 00.0 KB 0 0 000:00:09
TOTAL: 5 140 2764.3 MB 49 0 000:22:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 0.974 2.944 0.00000:16:54
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f 0.355 1.031 0.00000:04:59
77.67.26.64 0.002 0.017 0.00000:00:20
182.172.132.66.censys-scanner.com 0.037 0.030 0.00000:00:11
azpdssh2asbw.stretchoid.com 0.008 0.010 0.00000:00:09
36.195.132.66.censys-scanner.com 0.035 0.031 0.00000:00:07
andy.probe.onyphe.net 0.005 0.004 0.00000:00:03
183.186.132.66.censys-scanner.com 0.034 0.022 0.00000:00:01
diaz.probe.onyphe.net 0.004 0.011 0.00000:00:00
89-37-172-156.infrawat.ch 0.007 0.002 0.00000:00:00
TOTAL: 10 1.461 4.102 0.00000:22:48

Curious NNRP explorers [Top 20]:

SystemConn
182.172.132.66.censys-scanner.com 3
36.195.132.66.censys-scanner.com 3
183.186.132.66.censys-scanner.com 1
77.67.26.64 1
89-37-172-156.infrawat.ch 1
andy.probe.onyphe.net 1
azpdssh2asbw.stretchoid.com 1
diaz.probe.onyphe.net 1
TOTAL: 8 12

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

SystemConn
diaz.probe.onyphe.net 2
182.172.132.66.censys-scanner.com 1
183.186.132.66.censys-scanner.com 1
36.195.132.66.censys-scanner.com 1
TOTAL: 4 5

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

CommandCount
EHLO www.censys.io 3
#026#003#003#002c#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
TOTAL: 3 5

NNRP client timeouts [Top 20]:

SystemConnPeer
182.172.132.66.censys-scanner.com 1 2
36.195.132.66.censys-scanner.com 1 2
89-37-172-156.infrawat.ch 1 1
TOTAL: 3 3 5

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

HierarchyCountPct
1junk 122 44.2%
2microsoft 44 15.9%
3aus 22 8.0%
4alt 19 6.9%
5lada 15 5.4%
6uk 11 4.0%
7muc 9 3.3%
8cn 5 1.8%
9it 4 1.4%
10rec 4 1.4%
11linux 3 1.1%
12pl 3 1.1%
13talk 3 1.1%
14al 2 0.7%
15comp 2 0.7%
16de 2 0.7%
17sci 2 0.7%
18spline 2 0.7%
19free 1 0.4%
20grisbi 1 0.4%
TOTAL: 20 276100.0%

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

NewsgroupCount
1junk 122
2microsoft.public.windowsxp.help_and_support 44
3aus.cars 18
4lada.talk 15
5uk.d-i-y 8
6muc.lists.freebsd.bugs 7
7cn.test 5
8alt.anonymous.messages 4
9aus.politics 4
10rec.food.cooking 4
11alt.politics.trump 3
12alt.russian.z1 3
13pl.misc.elektronika 3
14al.christnet.bible 2
15alt.buddha.short.fat.guy 2
16alt.os.linux.mint 2
17comp.os.linux.advocacy 2
18de.etc.fahrzeug.auto 2
19linux.kernel.git 2
20muc.lists.freebsd.current 2
21spline.eisfair 2
22talk.politics.guns 2
23alt.arts.poetry.comments 1
24alt.christnet 1
25alt.os.linux.debian 1
26alt.sports.football.pro.sd-chargers 1
27alt.tv.general-hospital 1
28free.pt 1
29grisbi.test 1
30it.comp.giochi.action 1
31it.comp.lang.javascript 1
32it.comp.os.linux.software 1
33it.fan.culo 1
34linux.debian.changes.devel 1
35sci.logic 1
36sci.physics.relativity 1
37talk.origins 1
38uk.comp.sys.mac 1
39uk.games.video.misc 1
40uk.railway 1
TOTAL: 40 276
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