Daily Usenet report

Oct 7 03:00:04 -- Oct 8 03:00:04

Unknown entries from news log file:

First 50 / 9883 lines (0.5%)

Oct 7 03:00:57 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 7 03:03:36 twilightnode nnrpd[881163]: Reading access from /usr/local/news/etc/readers.conf
Oct 7 03:03:36 twilightnode nnrpd[881163]: Auth strategy 'localhost' does not match client. Removing.
Oct 7 03:03:36 twilightnode nnrpd[881163]: 66.61.98.95 res <anonymous>
Oct 7 03:03:36 twilightnode nnrpd[881163]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 7 03:03:36 twilightnode nnrpd[881163]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 7 03:03:36 twilightnode nnrpd[881163]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 7 03:03:36 twilightnode nnrpd[881163]: 66.61.98.95 no_post anonymous-read
Oct 7 03:03:36 twilightnode nnrpd[881164]: Reading access from /usr/local/news/etc/readers.conf
Oct 7 03:03:36 twilightnode nnrpd[881164]: Auth strategy 'localhost' does not match client. Removing.
Oct 7 03:03:36 twilightnode nnrpd[881164]: 66.61.98.95 res <anonymous>
Oct 7 03:03:36 twilightnode nnrpd[881164]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 7 03:03:36 twilightnode nnrpd[881164]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 7 03:03:36 twilightnode nnrpd[881164]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 7 03:03:36 twilightnode nnrpd[881164]: 66.61.98.95 no_post anonymous-read
Oct 7 03:06:21 twilightnode nnrpd[881340]: Reading access from /usr/local/news/etc/readers.conf
Oct 7 03:06:21 twilightnode nnrpd[881340]: Auth strategy 'localhost' does not match client. Removing.
Oct 7 03:06:21 twilightnode nnrpd[881340]: 66.61.98.95 res <anonymous>
Oct 7 03:06:21 twilightnode nnrpd[881340]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 7 03:06:21 twilightnode nnrpd[881340]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 7 03:06:21 twilightnode nnrpd[881340]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 7 03:06:21 twilightnode nnrpd[881340]: 66.61.98.95 no_post anonymous-read
Oct 7 03:06:21 twilightnode nnrpd[881341]: Reading access from /usr/local/news/etc/readers.conf
Oct 7 03:06:21 twilightnode nnrpd[881341]: Auth strategy 'localhost' does not match client. Removing.
Oct 7 03:06:21 twilightnode nnrpd[881341]: 66.61.98.95 res <anonymous>
Oct 7 03:06:21 twilightnode nnrpd[881341]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 7 03:06:21 twilightnode nnrpd[881341]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 7 03:06:21 twilightnode nnrpd[881341]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 7 03:06:21 twilightnode nnrpd[881341]: 66.61.98.95 no_post anonymous-read
Oct 7 03:10:31 twilightnode nnrpd[881686]: Reading access from /usr/local/news/etc/readers.conf
Oct 7 03:10:31 twilightnode nnrpd[881686]: Auth strategy 'localhost' does not match client. Removing.
Oct 7 03:10:31 twilightnode nnrpd[881686]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f res <anonymous>
Oct 7 03:10:31 twilightnode nnrpd[881686]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> <localhost>
Oct 7 03:10:31 twilightnode nnrpd[881686]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 7 03:10:31 twilightnode nnrpd[881686]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f match_user <anonymous> <anonymous>
Oct 7 03:10:31 twilightnode nnrpd[881686]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_post anonymous-read
Oct 7 03:10:31 twilightnode nnrpd[881687]: Reading access from /usr/local/news/etc/readers.conf
Oct 7 03:10:31 twilightnode nnrpd[881687]: Auth strategy 'localhost' does not match client. Removing.
Oct 7 03:10:31 twilightnode nnrpd[881687]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f res <anonymous>
Oct 7 03:10:31 twilightnode nnrpd[881687]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> <localhost>
Oct 7 03:10:31 twilightnode nnrpd[881687]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 7 03:10:31 twilightnode nnrpd[881687]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f match_user <anonymous> <anonymous>
Oct 7 03:10:31 twilightnode nnrpd[881687]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_post anonymous-read
Oct 7 03:18:18 twilightnode nnrpd[881993]: Reading access from /usr/local/news/etc/readers.conf
Oct 7 03:18:18 twilightnode nnrpd[881993]: Auth strategy 'localhost' does not match client. Removing.
Oct 7 03:18:18 twilightnode nnrpd[881993]: 66.61.98.95 res <anonymous>
Oct 7 03:18:18 twilightnode nnrpd[881993]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 7 03:18:18 twilightnode nnrpd[881993]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 7 03:18:18 twilightnode nnrpd[881993]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 7 03:18:18 twilightnode nnrpd[881993]: 66.61.98.95 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 19735 64.8%2.1 MB 54.4%
inn 7145 23.5%1.2 MB 32.4%
innd 2296 7.5%325.4 KB 8.2%
innfeed 1263 4.1%197.1 KB 5.0%
TOTAL: 4 30439 100.0%3.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20270 53.3%
Negative hits 10674 28.1%
Do not exist 7090 18.6%
Cache misses 4 0.0%
TOTAL: 4 38038 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.055 0.0%37 0.000 1.486 2.333
article cleanup00:00:00.079 0.0%7129 0.000 0.011 0.167
article logging00:00:00.269 0.0%7147 0.000 0.038 0.211
article parse00:00:00.110 0.0%9247 0.000 0.012 0.088
article write00:00:05.598 0.0%6052 0.500 0.925 5.321
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.173 0.0%39551 0.000 0.004 0.058
hisgrep/artcncl00:00:00.023 0.0%37 0.000 0.622 1.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:03.698 0.0%38250 0.054 0.097 0.252
history sync00:00:00.056 0.0%415 0.000 0.135 1.667
history write00:00:02.862 0.0%7129 0.083 0.401 0.789
idle24:00:29.365100.0%42445 1120.873 2036.267 9844.984
nntp read00:00:00.780 0.0%39137 0.000 0.020 0.078
overview write00:00:03.319 0.0%6052 0.227 0.548 1.938
perl filter00:00:10.156 0.0%7129 0.890 1.425 5.528
python filter00:00:00.789 0.0%7129 0.000 0.111 0.389
site send00:00:00.245 0.0%12065 0.000 0.020 0.088
TOTAL: 2024:00:57.577100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.027 0.0%6321 0.000 0.004 0.100
article prepare00:00:00.001 0.0%25244 0.000 0.000 0.004
article read00:00:00.804 0.0%4044 0.000 0.199 0.714
backlog stats00:00:00.040 0.0%82270 0.000 0.000 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.594 0.0%45760 0.000 0.013 0.042
data write00:00:00.989 0.0%39722 0.000 0.025 0.079
idle23:59:12.139100.0%82270 395.619 1049.619 6268.885
status file00:00:00.077 0.0%166 0.000 0.464 2.000
TOTAL: 923:59:14.671100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:27:52.915 99.9%6627 80.500 252.439 9836.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.331 0.0%17866 0.000 0.019 0.667
readart00:00:00.650 0.0%1212 0.000 0.536 1.311
TOTAL: 400:27:53.896100.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 10940 4611 6249 80 42%47:34:14
2usenet.blueworldhosting.com 173 7025 1336 4734 955 19%23:40:08
3news.sklaffkom.se 34 89 74 15 0 83%09:40:23
4news.tebibyte.org 2 4359 51 4308 0 1%28:38:19
5newsfeed.endofthelinebbs.com 1 643 42 595 6 6%06:04:55
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 239 23056 6114 15901 1041 26%115:37:59
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.0 MB0.0 KB434.1 KB15.5 MB 97%3.4 KB
2usenet.blueworldhosting.com6.5 MB0.0 KB1.2 MB7.8 MB 84%3.5 KB
3news.sklaffkom.se330.2 KB0.0 KB0.0 KB330.2 KB100%4.5 KB
4newsfeed.endofthelinebbs.com296.9 KB0.0 KB42.3 KB339.2 KB 87%7.1 KB
5news.tebibyte.org176.0 KB0.0 KB0.0 KB176.0 KB100%3.5 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 622.4 MB0.0 KB1.7 MB24.1 MB 92%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 7 03:00:04 - 03:59:59 123 2.0% 0.03657.6 KB 2.8% 0.18
Oct 7 04:00:00 - 04:59:59 84 1.4% 0.02274.8 KB 1.2% 0.08
Oct 7 05:00:00 - 05:59:59 121 2.0% 0.03461.2 KB 2.0% 0.13
Oct 7 06:00:00 - 06:59:59 116 1.9% 0.03438.4 KB 1.9% 0.12
Oct 7 07:00:00 - 07:59:59 146 2.4% 0.04629.0 KB 2.7% 0.17
Oct 7 08:00:00 - 08:59:59 211 3.5% 0.06650.9 KB 2.8% 0.18
Oct 7 09:00:00 - 09:59:59 310 5.1% 0.091.1 MB 4.8% 0.31
Oct 7 10:00:00 - 10:59:59 316 5.2% 0.091.1 MB 4.7% 0.30
Oct 7 11:00:00 - 11:59:59 280 4.6% 0.08771.6 KB 3.3% 0.21
Oct 7 12:00:00 - 12:59:59 321 5.3% 0.091002.8 KB 4.3% 0.28
Oct 7 13:00:00 - 13:59:59 344 5.7% 0.101.1 MB 5.0% 0.32
Oct 7 14:00:00 - 14:59:59 277 4.6% 0.081019.4 KB 4.4% 0.28
Oct 7 15:00:00 - 15:59:59 372 6.1% 0.101.4 MB 6.2% 0.40
Oct 7 16:00:00 - 16:59:59 317 5.2% 0.091.2 MB 5.4% 0.35
Oct 7 17:00:00 - 17:59:59 333 5.5% 0.091.0 MB 4.5% 0.29
Oct 7 18:00:00 - 18:59:59 360 6.0% 0.101.1 MB 5.1% 0.33
Oct 7 19:00:00 - 19:59:59 340 5.6% 0.091.2 MB 5.3% 0.34
Oct 7 20:00:00 - 20:59:59 362 6.0% 0.101.4 MB 6.3% 0.40
Oct 7 21:00:00 - 21:59:59 316 5.2% 0.091.6 MB 7.1% 0.46
Oct 7 22:00:00 - 22:59:59 276 4.6% 0.081.1 MB 4.9% 0.31
Oct 7 23:00:00 - 23:59:59 225 3.7% 0.061.0 MB 4.6% 0.30
Oct 8 00:00:00 - 00:59:59 186 3.1% 0.051015.4 KB 4.4% 0.28
Oct 8 01:00:00 - 01:59:59 175 2.9% 0.05669.0 KB 2.9% 0.19
Oct 8 02:00:00 - 02:59:59 129 2.1% 0.04726.9 KB 3.1% 0.20
Oct 8 03:00:00 - 03:00:04 10 0.2% 2.5043.8 KB 0.2% 10.96
TOTAL: 24:00:00 6050 100.0% 0.0722.6 MB 100.0% 0.27
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 961 319 0 0 0 0 0 0 642
2twilightnode-out.news.weretis.net 98 11 0 0 0 0 0 0 87
3newsfeed.endofthelinebbs.com 36 1 0 0 0 0 0 0 35
TOTAL: 3 1095 331 0 0 0 0 0 0 764

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 21
alt.test.9 5
pl.listserv.chomor-l 3
alt.sports.basketball.nba.la-lakers 2
fido.ru.home 2
alt.2600.madrid 1
alt.autos.porsche 1
alt.bbs.files-announce 1
alt.sports.football.oak-raiders 1
alt.sports.football.pro.houston-oilers 1
chmurka.statystyki 1
co.general 1
de.alt.astrologie 1
de.soc.recht.datennetze 1
fido.golded 1
TOTAL: 16 331

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 585
Too many newsgroups 97
EMP (phn path) 52
User-issued cancel 9
Too many newsgroups (meow) 2
HTML Multipart 1
TOTAL: 6 746

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8268 2185 3693 134 0 27 26%23:59:58
2sklaffkom.se 6132 977 2077 2955 0 2 15%23:59:18
3newsfeed.endofthelinebbs.com 10930 49 5826 16 0 295 0%23:56:56
4weretis.net 1607 1 1568 0 0 0 0%23:57:35
5usenet.blueworldhosting.com 7733 0 4389 0 0 3 0%23:59:57
TOTAL: 5 34670 3212 17553 3105 0 327 9%119:53:44
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org5.4 MB1.3 MB6.7 MB0.1 KB/s3.0 KB23:59:58
2sklaffkom.se1.6 MB13.3 MB14.9 MB0.2 KB/s3.9 KB23:59:18
3newsfeed.endofthelinebbs.com236.8 KB43.8 KB280.6 KB0.0 KB/s4.3 KB23:56:56
4weretis.net1.2 KB0.0 KB1.2 KB0.0 KB/s1.2 KB23:57:35
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
TOTAL: 57.3 MB14.6 MB21.9 MB0.1 KB/s3.5 KB119:53:44
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 708 8621.5 MB 22 0 000:13:01
22603:6010:6802:4ca5:1a03:73ff:fe28:be0f 692 3502.0 MB 31 0 000:14:36
TOTAL: 21400 12123.5 MB 53 0 000:27:38

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved1404 12123.5 MB 53 0 000:27:48
2*.infrawat.ch 5 00.0 KB 0 0 000:00:01
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 41411 12123.5 MB 53 0 000:27:53

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f 3.635 6.327 0.00000:14:36
66.61.98.95 3.593 5.526 0.00000:13:01
20.163.59.140 0.009 0.015 0.00000:00:09
105.172.132.66.censys-scanner.com 0.025 0.012 0.00000:00:02
193-176-31-211.infrawat.ch 0.004 0.009 0.00000:00:01
71.6.134.234 0.005 0.006 0.00000:00:00
213-166-84-60.infrawat.ch 0.006 0.009 0.00000:00:00
198.235.24.247 0.005 0.008 0.00000:00:00
89-21-67-178.infrawat.ch 0.006 0.010 0.00000:00:00
o002.scanner.modat.io 0.002 0.011 0.00000:00:00
5-226-140-14.infrawat.ch 0.005 0.005 0.00000:00:00
89-21-67-140.infrawat.ch 0.001 0.016 0.00000:00:00
TOTAL: 12 7.296 11.954 0.00000:27:53

Curious NNRP explorers [Top 20]:

SystemConn
20.163.59.140 2
105.172.132.66.censys-scanner.com 1
193-176-31-211.infrawat.ch 1
198.235.24.247 1
213-166-84-60.infrawat.ch 1
5-226-140-14.infrawat.ch 1
71.6.134.234 1
89-21-67-140.infrawat.ch 1
89-21-67-178.infrawat.ch 1
o002.scanner.modat.io 1
TOTAL: 10 11

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

SystemConn
105.172.132.66.censys-scanner.com 1
20.163.59.140 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
193-176-31-211.infrawat.ch 1 1
89-21-67-178.infrawat.ch 1 1
TOTAL: 2 2 6

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

HierarchyCountPct
1free 926 76.4%
2alt 110 9.1%
3aus 35 2.9%
4microsoft 30 2.5%
5lada 29 2.4%
6it 27 2.2%
7uk 21 1.7%
8linux 19 1.6%
9junk 4 0.3%
10rec 4 0.3%
11nz 2 0.2%
12pl 2 0.2%
13comp 1 0.1%
14fr 1 0.1%
15sac 1 0.1%
TOTAL: 15 1212100.0%

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

NewsgroupCount
1free.usenet 801
2free.pt 125
3alt.fan.rush-limbaugh 54
4aus.cars 35
5microsoft.public.windowsxp.general 30
6lada.talk 29
7alt.anonymous.messages 28
8it.politica 27
9uk.current-events.terrorism 21
10linux.kernel.netdev 16
11alt.test 10
12alt.slack 8
13junk 4
14rec.arts.tv 4
15alt.politics.trump 3
16alt.bible 2
17nz.politics 2
18pl.soc.polityka 2
19alt.comp.os.windows-10 1
20alt.messianic 1
21alt.survival 1
22alt.tv.survivor 1
23alt.usage.english 1
24comp.sys.mac.apps 1
25fr.rec.photo 1
26linux.debian.bugs.dist 1
27linux.debian.bugs.rc 1
28linux.debian.changes.devel 1
29sac.politics 1
TOTAL: 29 1212