Daily Usenet report

Oct 10 03:00:05 -- Oct 11 03:00:05

Unknown entries from news log file:

First 50 / 1993 lines (2.5%)

Oct 10 03:00:06 twilightnode nnrpd[1098838]: Reading access from /usr/local/news/etc/readers.conf
Oct 10 03:00:06 twilightnode nnrpd[1098838]: Auth strategy 'localhost' does not match client. Removing.
Oct 10 03:00:06 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com res <anonymous>
Oct 10 03:00:06 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Oct 10 03:00:06 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 10 03:00:06 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Oct 10 03:00:06 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com no_post anonymous-read
Oct 10 03:00:10 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com res <anonymous>
Oct 10 03:00:10 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Oct 10 03:00:10 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 10 03:00:10 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Oct 10 03:00:10 twilightnode nnrpd[1098838]: 232.224.132.66.censys-scanner.com no_post anonymous-read
Oct 10 03:01:22 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 10 03:04:27 twilightnode nnrpd[1099115]: Reading access from /usr/local/news/etc/readers.conf
Oct 10 03:04:27 twilightnode nnrpd[1099115]: Auth strategy 'localhost' does not match client. Removing.
Oct 10 03:04:27 twilightnode nnrpd[1099115]: 66.61.98.95 res <anonymous>
Oct 10 03:04:27 twilightnode nnrpd[1099115]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 10 03:04:27 twilightnode nnrpd[1099115]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 10 03:04:27 twilightnode nnrpd[1099115]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 10 03:04:27 twilightnode nnrpd[1099115]: 66.61.98.95 no_post anonymous-read
Oct 10 03:04:27 twilightnode nnrpd[1099116]: Reading access from /usr/local/news/etc/readers.conf
Oct 10 03:04:27 twilightnode nnrpd[1099116]: Auth strategy 'localhost' does not match client. Removing.
Oct 10 03:04:27 twilightnode nnrpd[1099116]: 66.61.98.95 res <anonymous>
Oct 10 03:04:27 twilightnode nnrpd[1099116]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 10 03:04:27 twilightnode nnrpd[1099116]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 10 03:04:27 twilightnode nnrpd[1099116]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 10 03:04:27 twilightnode nnrpd[1099116]: 66.61.98.95 no_post anonymous-read
Oct 10 03:04:33 twilightnode nnrpd[1099117]: Reading access from /usr/local/news/etc/readers.conf
Oct 10 03:04:33 twilightnode nnrpd[1099117]: Auth strategy 'localhost' does not match client. Removing.
Oct 10 03:04:33 twilightnode nnrpd[1099117]: 66.61.98.95 res <anonymous>
Oct 10 03:04:33 twilightnode nnrpd[1099117]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 10 03:04:33 twilightnode nnrpd[1099117]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 10 03:04:33 twilightnode nnrpd[1099117]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 10 03:04:33 twilightnode nnrpd[1099117]: 66.61.98.95 no_post anonymous-read
Oct 10 03:04:33 twilightnode nnrpd[1099118]: Reading access from /usr/local/news/etc/readers.conf
Oct 10 03:04:33 twilightnode nnrpd[1099118]: Auth strategy 'localhost' does not match client. Removing.
Oct 10 03:04:33 twilightnode nnrpd[1099118]: 66.61.98.95 res <anonymous>
Oct 10 03:04:33 twilightnode nnrpd[1099118]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 10 03:04:33 twilightnode nnrpd[1099118]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 10 03:04:33 twilightnode nnrpd[1099118]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 10 03:04:33 twilightnode nnrpd[1099118]: 66.61.98.95 no_post anonymous-read
Oct 10 03:04:43 twilightnode nnrpd[1099119]: Reading access from /usr/local/news/etc/readers.conf
Oct 10 03:04:43 twilightnode nnrpd[1099119]: Auth strategy 'localhost' does not match client. Removing.
Oct 10 03:04:43 twilightnode nnrpd[1099119]: 66.61.98.95 res <anonymous>
Oct 10 03:04:43 twilightnode nnrpd[1099119]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 10 03:04:43 twilightnode nnrpd[1099119]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 10 03:04:43 twilightnode nnrpd[1099119]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 10 03:04:43 twilightnode nnrpd[1099119]: 66.61.98.95 no_post anonymous-read
Oct 10 03:04:43 twilightnode nnrpd[1099120]: Reading access from /usr/local/news/etc/readers.conf
Oct 10 03:04:43 twilightnode nnrpd[1099120]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 14825 52.3%1.4 MB 39.9%
inn 7344 25.9%1.3 MB 35.6%
innd 4911 17.3%689.9 KB 19.1%
innfeed 1260 4.4%195.3 KB 5.4%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 28341 100.0%3.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19113 51.8%
Negative hits 10489 28.4%
Do not exist 7287 19.8%
Cache misses 1 0.0%
TOTAL: 4 36890 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.071 0.0%21 2.000 3.381 9.000
article cleanup00:00:00.093 0.0%7308 0.000 0.013 0.192
article logging00:00:00.329 0.0%7345 0.000 0.045 0.310
article parse00:00:00.150 0.0%9665 0.000 0.016 0.063
article write00:00:06.632 0.0%5750 0.658 1.153 2.763
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.248 0.0%38648 0.000 0.006 0.049
hisgrep/artcncl00:00:00.019 0.0%21 0.000 0.905 2.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:04.996 0.0%37011 0.070 0.135 1.365
history sync00:00:00.075 0.0%414 0.000 0.181 1.000
history write00:00:03.706 0.0%7309 0.182 0.507 1.200
idle24:00:18.198 99.9%40490 956.376 2134.310 8581.343
nntp read00:00:00.947 0.0%38918 0.000 0.024 0.101
overview write00:00:04.750 0.0%5750 0.265 0.826 2.091
perl filter00:00:15.953 0.0%7308 1.141 2.183 7.667
python filter00:00:01.108 0.0%7308 0.000 0.152 0.786
site send00:00:00.294 0.0%11436 0.000 0.026 0.132
TOTAL: 2024:00:57.569100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.032 0.0%5825 0.000 0.005 0.120
article prepare00:00:00.000 0.0%25856 0.000 0.000 0.000
article read00:00:01.199 0.0%4074 0.030 0.294 1.077
backlog stats00:00:00.075 0.0%76863 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.594 0.0%42931 0.000 0.014 0.039
data write00:00:00.943 0.0%37295 0.000 0.025 0.070
idle23:50:18.510100.0%76863 385.315 1116.513 5160.639
status file00:00:00.244 0.0%164 0.000 1.488 7.000
TOTAL: 923:50:21.597100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:35:47.115100.0%13426 76.000 159.922 9838.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.557 0.0%31172 0.000 0.018 0.333
readart00:00:00.181 0.0%319 0.000 0.567 1.000
TOTAL: 400:35:47.853100.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 11735 4261 7311 163 36%52:53:26
2usenet.blueworldhosting.com 708 7227 1106 4808 1313 15%23:46:59
3newsfeed.endofthelinebbs.com 2 4574 294 4189 91 6%23:07:58
4news.sklaffkom.se 39 77 66 10 1 85%09:12:08
5news.tebibyte.org 1 2162 20 2140 2 0%16:57:53
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 779 25775 5747 18458 1570 22%125:58:24
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.7 MB0.0 KB797.8 KB13.5 MB 94%3.1 KB
2newsfeed.endofthelinebbs.com7.3 MB0.0 KB146.7 KB7.4 MB 98%19.7 KB
3usenet.blueworldhosting.com5.2 MB0.0 KB2.3 MB7.5 MB 69%3.2 KB
4news.sklaffkom.se218.4 KB0.0 KB1.5 KB219.9 KB 99%3.3 KB
5news.tebibyte.org124.2 KB0.0 KB12.2 KB136.4 KB 91%6.2 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 625.5 MB0.0 KB3.2 MB28.7 MB 88%4.0 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 10 03:00:05 - 03:59:59 115 2.0% 0.03522.7 KB 2.5% 0.15
Oct 10 04:00:00 - 04:59:59 107 1.9% 0.03490.2 KB 2.3% 0.14
Oct 10 05:00:00 - 05:59:59 98 1.7% 0.03502.4 KB 2.4% 0.14
Oct 10 06:00:00 - 06:59:59 131 2.3% 0.04525.2 KB 2.5% 0.15
Oct 10 07:00:00 - 07:59:59 134 2.3% 0.04546.1 KB 2.6% 0.15
Oct 10 08:00:00 - 08:59:59 192 3.3% 0.05596.3 KB 2.8% 0.17
Oct 10 09:00:00 - 09:59:59 278 4.8% 0.08912.9 KB 4.3% 0.25
Oct 10 10:00:00 - 10:59:59 313 5.4% 0.09953.1 KB 4.5% 0.26
Oct 10 11:00:00 - 11:59:59 326 5.7% 0.09908.5 KB 4.3% 0.25
Oct 10 12:00:00 - 12:59:59 340 5.9% 0.091.6 MB 7.6% 0.45
Oct 10 13:00:00 - 13:59:59 401 7.0% 0.111.5 MB 7.5% 0.44
Oct 10 14:00:00 - 14:59:59 313 5.4% 0.09981.0 KB 4.6% 0.27
Oct 10 15:00:00 - 15:59:59 252 4.4% 0.07885.9 KB 4.2% 0.25
Oct 10 16:00:00 - 16:59:59 298 5.2% 0.081.1 MB 5.5% 0.33
Oct 10 17:00:00 - 17:59:59 247 4.3% 0.07817.8 KB 3.9% 0.23
Oct 10 18:00:00 - 18:59:59 292 5.1% 0.08865.6 KB 4.1% 0.24
Oct 10 19:00:00 - 19:59:59 338 5.9% 0.091.2 MB 5.8% 0.34
Oct 10 20:00:00 - 20:59:59 239 4.2% 0.07760.8 KB 3.6% 0.21
Oct 10 21:00:00 - 21:59:59 295 5.1% 0.081.0 MB 4.9% 0.29
Oct 10 22:00:00 - 22:59:59 254 4.4% 0.07854.9 KB 4.0% 0.24
Oct 10 23:00:00 - 23:59:59 277 4.8% 0.081.2 MB 5.6% 0.33
Oct 11 00:00:00 - 00:59:59 204 3.5% 0.06819.4 KB 3.9% 0.23
Oct 11 01:00:00 - 01:59:59 169 2.9% 0.05814.0 KB 3.8% 0.23
Oct 11 02:00:00 - 02:59:59 125 2.2% 0.03626.4 KB 2.9% 0.17
Oct 11 03:00:00 - 03:00:05 10 0.2% 2.0011.1 KB 0.1% 2.22
TOTAL: 24:00:00 5748 100.0% 0.0720.7 MB 100.0% 0.25
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1312 336 0 0 0 0 0 0 976
2twilightnode-out.news.weretis.net 182 14 0 0 0 0 0 0 168
3newsfeed.endofthelinebbs.com 101 1 0 0 0 0 0 0 100
4news.sklaffkom.se 1 0 0 0 0 0 0 0 1
TOTAL: 4 1596 351 0 0 0 0 0 0 1245

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 29
osnet.test 9
alt.politics.cuba 4
hispagatos.mastodon.tag.hacking 4
pl.listserv.chomor-l 3
alt.journalism.newspapers 2
alt.2600.madrid 1
alt.bbs.files-announce 1
alt.culture.welsh 1
alt.religion.christian.last-days 1
alt.religion.end-times.prophecies 1
alt.sports.football.pro.dallas-cowboys 1
alt.startrek.animated 1
alt.sys.pdp11 1
be.politics 1
chmurka.statystyki 1
grisbi.user 1
italia.campobasso.discussioni 1
TOTAL: 19 351

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 965
EMP (phn path) 116
Too many newsgroups 102
HTML Multipart 10
Too many newsgroups (meow) 9
User-issued cancel 4
hierarchy violation - crosspost outside netscape 1
TOTAL: 7 1207

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7617 2389 3229 65 0 31 31%23:59:56
2sklaffkom.se 5782 1158 1711 2815 0 1 20%23:59:56
3newsfeed.endofthelinebbs.com 10013 27 5456 21 0 109 0%23:59:56
4weretis.net 1591 5 1516 0 0 0 0%23:48:52
5usenet.blueworldhosting.com 6997 4 4187 0 0 22 0%23:59:56
TOTAL: 5 32000 3583 16099 2901 0 163 11%119:48:36
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.1 MB556.8 KB6.6 MB0.1 KB/s2.8 KB23:59:56
2sklaffkom.se1.9 MB12.7 MB14.6 MB0.2 KB/s3.8 KB23:59:56
3newsfeed.endofthelinebbs.com91.5 KB61.3 KB152.7 KB0.0 KB/s3.2 KB23:59:56
4usenet.blueworldhosting.com11.9 KB0.0 KB11.9 KB0.0 KB/s3.0 KB23:59:56
5weretis.net11.7 KB0.0 KB11.7 KB0.0 KB/s2.3 KB23:48:52
TOTAL: 58.1 MB13.3 MB21.4 MB0.1 KB/s3.4 KB119:48:36
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 164 30114.2 MB 37 0 000:24:55
22603:6010:6802:4ca5:1a03:73ff:fe28:be0f 81 1840.7 KB 7 0 000:10:21
TOTAL: 2 245 31914.2 MB 44 0 000:35:17

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 247 31914.2 MB 44 0 000:35:27
2*.ip.linodeusercontent.com 21 00.0 KB 0 0 000:00:13
3*.infrawat.ch 2 00.0 KB 0 0 000:00:00
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:06
TOTAL: 4 271 31914.2 MB 44 0 000:35:47

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 1.627 3.939 0.00000:24:55
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f 0.750 1.769 0.00000:10:21
172-235-235-248.ip.linodeusercontent.com 0.529 0.183 0.00000:00:13
40.124.185.217 0.008 0.009 0.00000:00:09
232.224.132.66.censys-scanner.com 0.027 0.011 0.00000:00:06
198.235.24.84 0.003 0.018 0.00000:00:00
195-206-182-213.infrawat.ch 0.005 0.009 0.00000:00:00
81-19-219-217.infrawat.ch 0.006 0.009 0.00000:00:00
TOTAL: 8 2.955 5.947 0.00000:35:47

Curious NNRP explorers [Top 20]:

SystemConn
172-235-235-248.ip.linodeusercontent.com 21
195-206-182-213.infrawat.ch 1
198.235.24.84 1
232.224.132.66.censys-scanner.com 1
40.124.185.217 1
81-19-219-217.infrawat.ch 1
TOTAL: 6 26

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

SystemConn
232.224.132.66.censys-scanner.com 1
TOTAL: 1 1

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

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
172-235-235-248.ip.linodeusercontent.com 1 1
195-206-182-213.infrawat.ch 1 1
TOTAL: 2 2 4

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

HierarchyCountPct
1microsoft 132 41.4%
2alt 114 35.7%
3de 12 3.8%
4hfx 12 3.8%
5ger 11 3.4%
6linux 10 3.1%
7fr 7 2.2%
8england 5 1.6%
9it 4 1.3%
10rec 4 1.3%
11comp 2 0.6%
12free 2 0.6%
13muc 2 0.6%
14sci 1 0.3%
15talk 1 0.3%
TOTAL: 15 319100.0%

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

NewsgroupCount
1alt.anonymous.messages 88
2microsoft.public.windowsxp.general 88
3microsoft.public.windowsxp.help_and_support 44
4hfx.general 12
5ger.ct 11
6alt.test 10
7de.sci.electronics 10
8fr.sci.maths 7
9england.religion.christian 5
10alt.prophecies.nostradamus 4
11alt.fan.rush-limbaugh 3
12alt.tv.survivor 3
13linux.debian.bugs.dist 3
14linux.debian.l10n.german 3
15linux.kernel.netdev 3
16alt.computer.workshop 2
17free.usenet 2
18rec.bicycles.tech 2
19alt.comp.software.firefox 1
20alt.dss.hack 1
21alt.politics.international 1
22alt.russian.z1 1
23comp.mobile.android 1
24comp.os.linux.misc 1
25de.etc.fahrzeug.auto 1
26de.etc.sprache.deutsch 1
27it.comp.programmare 1
28it.discussioni.giustizia 1
29it.scienza.fisica 1
30it.tlc.gestori.fastweb 1
31linux.debian.devel.qa 1
32muc.lists.freebsd.emulation 1
33muc.lists.freebsd.ports 1
34rec.arts.movies.current-films 1
35rec.games.trivia 1
36sci.med.cardiology 1
37talk.politics.misc 1
TOTAL: 37 319