Daily Usenet report

Sep 29 03:00:04 -- Sep 30 03:00:04

Unknown entries from news log file:

First 50 / 622 lines (8.0%)

Sep 29 03:01:16 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 29 03:02:55 twilightnode nnrpd[293344]: Reading access from /usr/local/news/etc/readers.conf
Sep 29 03:02:55 twilightnode nnrpd[293344]: Auth strategy 'localhost' does not match client. Removing.
Sep 29 03:02:55 twilightnode nnrpd[293344]: 66.61.98.95 res <anonymous>
Sep 29 03:02:55 twilightnode nnrpd[293344]: 66.61.98.95 no_match_user <anonymous> <localhost>
Sep 29 03:02:55 twilightnode nnrpd[293344]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 29 03:02:55 twilightnode nnrpd[293344]: 66.61.98.95 match_user <anonymous> <anonymous>
Sep 29 03:02:55 twilightnode nnrpd[293344]: 66.61.98.95 no_post anonymous-read
Sep 29 03:02:56 twilightnode nnrpd[293345]: Reading access from /usr/local/news/etc/readers.conf
Sep 29 03:02:56 twilightnode nnrpd[293345]: Auth strategy 'localhost' does not match client. Removing.
Sep 29 03:02:56 twilightnode nnrpd[293345]: 66.61.98.95 res <anonymous>
Sep 29 03:02:56 twilightnode nnrpd[293345]: 66.61.98.95 no_match_user <anonymous> <localhost>
Sep 29 03:02:56 twilightnode nnrpd[293345]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 29 03:02:56 twilightnode nnrpd[293345]: 66.61.98.95 match_user <anonymous> <anonymous>
Sep 29 03:02:56 twilightnode nnrpd[293345]: 66.61.98.95 no_post anonymous-read
Sep 29 03:04:56 twilightnode nnrpd[293368]: Reading access from /usr/local/news/etc/readers.conf
Sep 29 03:04:56 twilightnode nnrpd[293368]: Auth strategy 'localhost' does not match client. Removing.
Sep 29 03:04:56 twilightnode nnrpd[293368]: 66.61.98.95 res <anonymous>
Sep 29 03:04:56 twilightnode nnrpd[293368]: 66.61.98.95 no_match_user <anonymous> <localhost>
Sep 29 03:04:56 twilightnode nnrpd[293368]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 29 03:04:56 twilightnode nnrpd[293368]: 66.61.98.95 match_user <anonymous> <anonymous>
Sep 29 03:04:56 twilightnode nnrpd[293368]: 66.61.98.95 no_post anonymous-read
Sep 29 03:04:56 twilightnode nnrpd[293369]: Reading access from /usr/local/news/etc/readers.conf
Sep 29 03:04:56 twilightnode nnrpd[293369]: Auth strategy 'localhost' does not match client. Removing.
Sep 29 03:04:56 twilightnode nnrpd[293369]: 66.61.98.95 res <anonymous>
Sep 29 03:04:56 twilightnode nnrpd[293369]: 66.61.98.95 no_match_user <anonymous> <localhost>
Sep 29 03:04:56 twilightnode nnrpd[293369]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 29 03:04:56 twilightnode nnrpd[293369]: 66.61.98.95 match_user <anonymous> <anonymous>
Sep 29 03:04:56 twilightnode nnrpd[293369]: 66.61.98.95 no_post anonymous-read
Sep 29 03:05:36 twilightnode nnrpd[293471]: Reading access from /usr/local/news/etc/readers.conf
Sep 29 03:05:36 twilightnode nnrpd[293471]: Auth strategy 'localhost' does not match client. Removing.
Sep 29 03:05:36 twilightnode nnrpd[293471]: 20.98.162.219 res <anonymous>
Sep 29 03:05:36 twilightnode nnrpd[293471]: 20.98.162.219 no_match_user <anonymous> <localhost>
Sep 29 03:05:36 twilightnode nnrpd[293471]: 20.98.162.219 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 29 03:05:36 twilightnode nnrpd[293471]: 20.98.162.219 match_user <anonymous> <anonymous>
Sep 29 03:05:36 twilightnode nnrpd[293471]: 20.98.162.219 no_post anonymous-read
Sep 29 03:05:46 twilightnode nnrpd[293480]: Reading access from /usr/local/news/etc/readers.conf
Sep 29 03:05:46 twilightnode nnrpd[293480]: Auth strategy 'localhost' does not match client. Removing.
Sep 29 03:05:46 twilightnode nnrpd[293480]: 20.98.162.219 res <anonymous>
Sep 29 03:05:46 twilightnode nnrpd[293480]: 20.98.162.219 no_match_user <anonymous> <localhost>
Sep 29 03:05:46 twilightnode nnrpd[293480]: 20.98.162.219 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 29 03:05:46 twilightnode nnrpd[293480]: 20.98.162.219 match_user <anonymous> <anonymous>
Sep 29 03:05:46 twilightnode nnrpd[293480]: 20.98.162.219 no_post anonymous-read
Sep 29 03:08:22 twilightnode nnrpd[293543]: Reading access from /usr/local/news/etc/readers.conf
Sep 29 03:08:22 twilightnode nnrpd[293543]: Auth strategy 'localhost' does not match client. Removing.
Sep 29 03:08:22 twilightnode nnrpd[293543]: 66.61.98.95 res <anonymous>
Sep 29 03:08:22 twilightnode nnrpd[293543]: 66.61.98.95 no_match_user <anonymous> <localhost>
Sep 29 03:08:22 twilightnode nnrpd[293543]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 29 03:08:22 twilightnode nnrpd[293543]: 66.61.98.95 match_user <anonymous> <anonymous>
Sep 29 03:08:22 twilightnode nnrpd[293543]: 66.61.98.95 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 7312 42.9%1.3 MB 55.5%
nnrpd 6318 37.1%544.5 KB 22.9%
innd 2155 12.6%316.0 KB 13.3%
innfeed 1266 7.4%197.1 KB 8.3%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 17052 100.0%2.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19800 52.1%
Negative hits 10955 28.8%
Do not exist 7253 19.1%
Cache misses 7 0.0%
TOTAL: 4 38015 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.075 0.0%26 1.000 2.885 14.000
article cleanup00:00:00.063 0.0%7281 0.000 0.009 0.111
article logging00:00:00.256 0.0%7307 0.000 0.035 0.250
article parse00:00:00.193 0.0%9755 0.000 0.020 0.154
article write00:00:07.156 0.0%6236 0.628 1.148 2.043
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.231 0.0%39307 0.000 0.006 0.048
hisgrep/artcncl00:00:00.019 0.0%26 0.000 0.731 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.782 0.0%38168 0.049 0.125 0.328
history sync00:00:00.082 0.0%422 0.000 0.194 1.667
history write00:00:03.655 0.0%7281 0.222 0.502 1.263
idle24:00:20.263 99.9%39604 1154.737 2182.109 8575.357
nntp read00:00:00.852 0.0%38947 0.000 0.022 0.088
overview write00:00:05.681 0.0%6236 0.385 0.911 2.857
perl filter00:00:15.130 0.0%7281 0.918 2.078 15.375
python filter00:00:01.111 0.0%7281 0.027 0.153 1.500
site send00:00:00.291 0.0%12445 0.000 0.023 0.094
TOTAL: 1824:00:59.840100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.052 0.0%6580 0.000 0.008 0.278
article prepare00:00:00.006 0.0%26940 0.000 0.000 0.005
article read00:00:01.126 0.0%4383 0.000 0.257 2.125
backlog stats00:00:00.056 0.0%81757 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.649 0.0%46227 0.000 0.014 0.050
data write00:00:01.045 0.0%40219 0.000 0.026 0.069
idle23:49:53.083100.0%81757 440.843 1049.367 4320.748
status file00:00:00.173 0.0%163 0.000 1.061 6.000
TOTAL: 923:49:56.190100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:17:55.955 99.9%6105 76.500 176.242 9846.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.491 0.0%14004 0.000 0.035 0.500
readart00:00:00.049 0.0%134 0.000 0.366 1.000
TOTAL: 400:17:56.495100.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 10717 3998 6655 64 37%46:30:09
2usenet.blueworldhosting.com 144 7123 1835 4311 977 25%23:39:53
3news.tebibyte.org 2 3625 185 3440 0 5%23:59:15
4news.sklaffkom.se 44 106 87 18 1 82%11:31:31
5newsfeed.endofthelinebbs.com 1 2442 39 2397 6 1%13:12:59
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 220 24013 6144 16821 1048 25%118:53:47
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.1 MB0.0 KB154.5 KB13.3 MB 98%3.3 KB
2usenet.blueworldhosting.com9.1 MB0.0 KB1.3 MB10.3 MB 87%3.8 KB
3newsfeed.endofthelinebbs.com1.0 MB0.0 KB12.5 KB1.0 MB 98%23.6 KB
4news.tebibyte.org679.9 KB0.0 KB0.0 KB679.9 KB100%3.7 KB
5news.sklaffkom.se257.1 KB0.0 KB0.9 KB258.1 KB 99%2.9 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 624.1 MB0.0 KB1.4 MB25.6 MB 94%3.6 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 29 03:00:04 - 03:59:59 89 1.4% 0.02358.1 KB 1.5% 0.10
Sep 29 04:00:00 - 04:59:59 155 2.5% 0.04738.0 KB 3.0% 0.21
Sep 29 05:00:00 - 05:59:59 124 2.0% 0.03878.6 KB 3.6% 0.24
Sep 29 06:00:00 - 06:59:59 123 2.0% 0.03485.2 KB 2.0% 0.13
Sep 29 07:00:00 - 07:59:59 137 2.2% 0.04353.2 KB 1.4% 0.10
Sep 29 08:00:00 - 08:59:59 186 3.0% 0.05637.9 KB 2.6% 0.18
Sep 29 09:00:00 - 09:59:59 290 4.6% 0.081.1 MB 4.5% 0.31
Sep 29 10:00:00 - 10:59:59 325 5.2% 0.091.0 MB 4.4% 0.30
Sep 29 11:00:00 - 11:59:59 317 5.1% 0.091.2 MB 5.1% 0.35
Sep 29 12:00:00 - 12:59:59 314 5.0% 0.091.0 MB 4.2% 0.29
Sep 29 13:00:00 - 13:59:59 268 4.3% 0.071.0 MB 4.3% 0.29
Sep 29 14:00:00 - 14:59:59 295 4.7% 0.08976.9 KB 4.0% 0.27
Sep 29 15:00:00 - 15:59:59 336 5.4% 0.091.2 MB 5.2% 0.35
Sep 29 16:00:00 - 16:59:59 293 4.7% 0.081.1 MB 4.5% 0.30
Sep 29 17:00:00 - 17:59:59 323 5.2% 0.091.6 MB 6.6% 0.45
Sep 29 18:00:00 - 18:59:59 365 5.8% 0.101.3 MB 5.5% 0.38
Sep 29 19:00:00 - 19:59:59 288 4.6% 0.081.1 MB 4.8% 0.32
Sep 29 20:00:00 - 20:59:59 359 5.8% 0.101.1 MB 4.5% 0.30
Sep 29 21:00:00 - 21:59:59 323 5.2% 0.091.1 MB 4.7% 0.32
Sep 29 22:00:00 - 22:59:59 261 4.2% 0.07952.5 KB 3.9% 0.26
Sep 29 23:00:00 - 23:59:59 266 4.3% 0.071.2 MB 5.2% 0.35
Sep 30 00:00:00 - 00:59:59 317 5.1% 0.091.4 MB 6.0% 0.41
Sep 30 01:00:00 - 01:59:59 260 4.2% 0.071.0 MB 4.3% 0.29
Sep 30 02:00:00 - 02:59:59 217 3.5% 0.061.0 MB 4.3% 0.29
Sep 30 03:00:00 - 03:00:04 9 0.1% 2.258.4 KB 0.0% 2.11
TOTAL: 24:00:00 6240 100.0% 0.0723.9 MB 100.0% 0.28
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 977 341 0 0 2 0 0 0 634
2twilightnode-out.news.weretis.net 85 12 0 0 0 0 0 0 73
3newsfeed.endofthelinebbs.com 7 1 0 0 0 0 0 0 6
4news.tebibyte.org 2 0 0 0 0 0 0 0 2
5news.sklaffkom.se 1 0 0 0 0 0 0 0 1
TOTAL: 5 1072 354 0 0 2 0 0 0 716

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 290
hispagatos.hacking.ransomware 27
hispagatos.mastodon.tag.hacking 8
de.rec.sf.perry-rhodan 5
fido.ru.golded 5
fido.fidotest 3
alt.happy.valley 2
osnet.test 2
alt.autos.performance-cars 1
alt.bbs.files-announce 1
alt.internet.search-engines 1
alt.sewing 1
alt.sports 1
alt.test.9 1
chmurka.statystyki 1
de.comp.office-pakete.ms-office.misc 1
fido.ru.fidonet.today 1
fido.ru.modem 1
fr.soc.divers 1
pl.listserv.chomor-l 1
TOTAL: 20 354

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
uk.religion.christian 2
TOTAL: 1 2

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 539
Too many newsgroups 102
User-issued cancel 44
Too many newsgroups (meow) 2
Binary: misplaced binary 1
EMP (phn path) 1
HTML Multipart 1
TOTAL: 7 690

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8715 2321 3751 140 0 36 26%23:59:55
2sklaffkom.se 6359 801 1983 3430 0 3 12%23:59:55
3newsfeed.endofthelinebbs.com 10850 55 6072 24 0 311 0%23:59:28
4usenet.blueworldhosting.com 7046 1 4108 0 0 50 0%23:59:26
5weretis.net 2255 1 2177 0 0 0 0%23:58:43
TOTAL: 5 35225 3179 18091 3594 0 400 9%119:57:27
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.1 MB1.5 MB7.6 MB0.1 KB/s3.2 KB23:59:55
2sklaffkom.se1.3 MB15.4 MB16.7 MB0.2 KB/s4.0 KB23:59:55
3newsfeed.endofthelinebbs.com160.0 KB56.5 KB216.6 KB0.0 KB/s2.7 KB23:59:28
4weretis.net1.9 KB0.0 KB1.9 KB0.0 KB/s1.9 KB23:58:43
5usenet.blueworldhosting.com1.8 KB0.0 KB1.8 KB0.0 KB/s1.8 KB23:59:26
TOTAL: 57.5 MB17.0 MB24.5 MB0.1 KB/s3.7 KB119:57:27
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 66 134782.7 KB 40 0 000:16:51
TOTAL: 1 66 134782.7 KB 40 0 000:16:51

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 82 134782.7 KB 40 0 000:17:30
2*.probe.onyphe.net 2 00.0 KB 0 0 000:00:21
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4*.infrawat.ch 1 00.0 KB 0 0 000:00:00
5*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
6*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 6 88 134782.7 KB 40 0 000:17:56

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 0.853 2.422 0.00000:16:51
devin.probe.onyphe.net 0.005 0.005 0.00000:00:14
74.249.131.231 0.014 0.010 0.00000:00:09
20.98.162.219 0.009 0.017 0.00000:00:09
124.88.113.40 0.005 0.009 0.00000:00:08
rod.probe.onyphe.net 0.004 0.014 0.00000:00:07
47.250.132.207 0.004 0.013 0.00000:00:03
36.255.97.33 0.006 0.016 0.00000:00:02
r5-117-75.monitoring.internet-measurement.com 0.009 0.006 0.00000:00:01
183.184.55.38 0.004 0.006 0.00000:00:01
o020.scanner.modat.io 0.002 0.016 0.00000:00:00
111.195.132.66.censys-scanner.com 0.027 0.016 0.00000:00:00
124.31.107.199 0.006 0.008 0.00000:00:00
60.166.83.230 0.004 0.005 0.00000:00:00
124.31.107.11 0.003 0.009 0.00000:00:00
81.19.216.120 0.005 0.010 0.00000:00:00
71.6.134.231 0.010 0.016 0.00000:00:00
216.226.78.20 0.007 0.010 0.00000:00:00
193-176-31-217.infrawat.ch 0.007 0.008 0.00000:00:00
198.235.24.88 0.005 0.013 0.00000:00:00
TOTAL: 20 0.989 2.629 0.00000:17:56

Curious NNRP explorers [Top 20]:

SystemConn
20.98.162.219 2
36.255.97.33 2
74.249.131.231 2
111.195.132.66.censys-scanner.com 1
124.31.107.11 1
124.31.107.199 1
124.88.113.40 1
183.184.55.38 1
193-176-31-217.infrawat.ch 1
198.235.24.88 1
216.226.78.20 1
47.250.132.207 1
60.166.83.230 1
71.6.134.231 1
81.19.216.120 1
devin.probe.onyphe.net 1
o020.scanner.modat.io 1
r5-117-75.monitoring.internet-measurement.com 1
rod.probe.onyphe.net 1
TOTAL: 19 22

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

SystemConn
183.184.55.38 9
124.31.107.199 8
devin.probe.onyphe.net 6
124.31.107.11 3
47.250.132.207 3
rod.probe.onyphe.net 2
111.195.132.66.censys-scanner.com 1
124.88.113.40 1
20.98.162.219 1
74.249.131.231 1
TOTAL: 10 35

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

CommandCount
#021? 2
#026#003#001#005?#001 2
?#024#023#001#023#002#023#003#001 2
GET / HTTP/1.1 2
Host: 172.234.115.63:119 2
MGLNDD_172.234.115.63_119 2
#023#001#023#002#023#003#001 1
#026#003#001 1
#026#003#003#002c#001 1
+T?N?#020?`K2h2#030#036D?#026?l?#024S#032t&#006FB?#004?+?P?P... 1
2#031*"A?jX?c?[?;?0[8#025?J?2?#024F#037?8?=?#011?... 1
?#015i?E#026!#034X?=?7?<B?#021? 1
?#024 1
?#026X#021 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
?4#020_?Y>/J-#0331Q?v? `?U?Y,#0067?Td?#001?#013... 1
?E#004f@?e?=?;p? 4?~?CJ?#011?P?#014)?g... 1
?Z?#003 1
?^?mk#007? 1
?r[$1?2?>?5@?Z?#?p#015!?#014?#004?... 1
TOTAL: 29 35

NNRP client timeouts [Top 20]:

SystemConnPeer
124.88.113.40 1 1
183.184.55.38 1 1
193-176-31-217.infrawat.ch 1 1
47.250.132.207 1 1
TOTAL: 4 4 6

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

HierarchyCountPct
1alt 49 36.6%
2linux 25 18.7%
3junk 17 12.7%
4grisbi 8 6.0%
5fr 6 4.5%
6uk 6 4.5%
7lada 5 3.7%
8comp 4 3.0%
9rec 4 3.0%
10hispagatos 2 1.5%
11talk 2 1.5%
12aus 1 0.7%
13control 1 0.7%
14de 1 0.7%
15it 1 0.7%
16sac 1 0.7%
17sci 1 0.7%
TOTAL: 17 134100.0%

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

NewsgroupCount
1alt.test 26
2linux.kernel.netdev 24
3junk 17
4grisbi.bugsreports 8
5alt.buddha.short.fat.guy 7
6uk.d-i-y 6
7lada.talk 5
8alt.bible 3
9alt.comp.os.windows-10 3
10fr.rec.moto 3
11rec.food.cooking 3
12alt.atheism 2
13alt.christnet.christianlife 2
14alt.fan.rush-limbaugh 2
15alt.russian.z1 2
16comp.lang.c 2
17fr.soc.religion 2
18alt.os.linux.slackware 1
19alt.politics.democrats.d 1
20aus.tv 1
21comp.arch 1
22comp.os.linux.advocacy 1
23control.cancel 1
24de.etc.fahrzeug.auto 1
25fr.soc.politique 1
26hispagatos.comunicados 1
27hispagatos.hacking.news 1
28it.sport.calcio.milan 1
29linux.debian.ports.hurd 1
30rec.games.trivia 1
31sac.politics 1
32sci.misc 1
33talk.politics.guns 1
34talk.politics.misc 1
TOTAL: 34 134