Daily Usenet report

Nov 3 03:00:04 -- Nov 4 03:00:04

Unknown entries from news log file:

First 50 / 4267 lines (1.2%)

Nov 3 03:01:12 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Nov 3 03:18:35 twilightnode nnrpd[1002961]: Reading access from /usr/local/news/etc/readers.conf
Nov 3 03:18:35 twilightnode nnrpd[1002961]: Auth strategy 'localhost' does not match client. Removing.
Nov 3 03:18:35 twilightnode nnrpd[1002961]: 174.96.78.81 res <anonymous>
Nov 3 03:18:35 twilightnode nnrpd[1002961]: 174.96.78.81 no_match_user <anonymous> <localhost>
Nov 3 03:18:35 twilightnode nnrpd[1002961]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Nov 3 03:18:35 twilightnode nnrpd[1002961]: 174.96.78.81 match_user <anonymous> <anonymous>
Nov 3 03:18:35 twilightnode nnrpd[1002961]: 174.96.78.81 no_post anonymous-read
Nov 3 03:18:35 twilightnode nnrpd[1002962]: Reading access from /usr/local/news/etc/readers.conf
Nov 3 03:18:35 twilightnode nnrpd[1002962]: Auth strategy 'localhost' does not match client. Removing.
Nov 3 03:18:35 twilightnode nnrpd[1002962]: 174.96.78.81 res <anonymous>
Nov 3 03:18:35 twilightnode nnrpd[1002962]: 174.96.78.81 no_match_user <anonymous> <localhost>
Nov 3 03:18:35 twilightnode nnrpd[1002962]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Nov 3 03:18:35 twilightnode nnrpd[1002962]: 174.96.78.81 match_user <anonymous> <anonymous>
Nov 3 03:18:35 twilightnode nnrpd[1002962]: 174.96.78.81 no_post anonymous-read
Nov 3 03:35:49 twilightnode nnrpd[1003622]: Reading access from /usr/local/news/etc/readers.conf
Nov 3 03:35:49 twilightnode nnrpd[1003622]: Auth strategy 'localhost' does not match client. Removing.
Nov 3 03:35:49 twilightnode nnrpd[1003622]: 174.96.78.81 res <anonymous>
Nov 3 03:35:49 twilightnode nnrpd[1003622]: 174.96.78.81 no_match_user <anonymous> <localhost>
Nov 3 03:35:49 twilightnode nnrpd[1003622]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Nov 3 03:35:49 twilightnode nnrpd[1003622]: 174.96.78.81 match_user <anonymous> <anonymous>
Nov 3 03:35:49 twilightnode nnrpd[1003622]: 174.96.78.81 no_post anonymous-read
Nov 3 03:35:49 twilightnode nnrpd[1003623]: Reading access from /usr/local/news/etc/readers.conf
Nov 3 03:35:49 twilightnode nnrpd[1003623]: Auth strategy 'localhost' does not match client. Removing.
Nov 3 03:35:49 twilightnode nnrpd[1003623]: 174.96.78.81 res <anonymous>
Nov 3 03:35:49 twilightnode nnrpd[1003623]: 174.96.78.81 no_match_user <anonymous> <localhost>
Nov 3 03:35:49 twilightnode nnrpd[1003623]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Nov 3 03:35:49 twilightnode nnrpd[1003623]: 174.96.78.81 match_user <anonymous> <anonymous>
Nov 3 03:35:49 twilightnode nnrpd[1003623]: 174.96.78.81 no_post anonymous-read
Nov 3 03:55:35 twilightnode nnrpd[1004564]: Reading access from /usr/local/news/etc/readers.conf
Nov 3 03:55:35 twilightnode nnrpd[1004564]: Auth strategy 'localhost' does not match client. Removing.
Nov 3 03:55:35 twilightnode nnrpd[1004564]: 174.96.78.81 res <anonymous>
Nov 3 03:55:35 twilightnode nnrpd[1004564]: 174.96.78.81 no_match_user <anonymous> <localhost>
Nov 3 03:55:35 twilightnode nnrpd[1004564]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Nov 3 03:55:35 twilightnode nnrpd[1004564]: 174.96.78.81 match_user <anonymous> <anonymous>
Nov 3 03:55:35 twilightnode nnrpd[1004564]: 174.96.78.81 no_post anonymous-read
Nov 3 03:55:36 twilightnode nnrpd[1004565]: Reading access from /usr/local/news/etc/readers.conf
Nov 3 03:55:36 twilightnode nnrpd[1004565]: Auth strategy 'localhost' does not match client. Removing.
Nov 3 03:55:36 twilightnode nnrpd[1004565]: 174.96.78.81 res <anonymous>
Nov 3 03:55:36 twilightnode nnrpd[1004565]: 174.96.78.81 no_match_user <anonymous> <localhost>
Nov 3 03:55:36 twilightnode nnrpd[1004565]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Nov 3 03:55:36 twilightnode nnrpd[1004565]: 174.96.78.81 match_user <anonymous> <anonymous>
Nov 3 03:55:36 twilightnode nnrpd[1004565]: 174.96.78.81 no_post anonymous-read
Nov 3 03:56:02 twilightnode nnrpd[1004575]: Reading access from /usr/local/news/etc/readers.conf
Nov 3 03:56:02 twilightnode nnrpd[1004575]: Auth strategy 'localhost' does not match client. Removing.
Nov 3 03:56:02 twilightnode nnrpd[1004575]: 174.96.78.81 res <anonymous>
Nov 3 03:56:02 twilightnode nnrpd[1004575]: 174.96.78.81 no_match_user <anonymous> <localhost>
Nov 3 03:56:02 twilightnode nnrpd[1004575]: 174.96.78.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Nov 3 03:56:02 twilightnode nnrpd[1004575]: 174.96.78.81 match_user <anonymous> <anonymous>
Nov 3 03:56:02 twilightnode nnrpd[1004575]: 174.96.78.81 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 45997 80.7%3.9 MB 68.7%
inn 7453 13.1%1.3 MB 22.6%
innd 2291 4.0%292.4 KB 5.1%
innfeed 1275 2.2%211.0 KB 3.6%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 57017 100.0%5.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19049 51.0%
Negative hits 10898 29.2%
Do not exist 7393 19.8%
Cache misses 6 0.0%
TOTAL: 4 37346 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.035 0.0%26 0.500 1.346 4.000
article cleanup00:00:00.063 0.0%7428 0.000 0.008 0.095
article logging00:00:00.310 0.0%7470 0.000 0.041 0.269
article parse00:00:00.153 0.0%11375 0.000 0.013 0.100
article write00:00:08.280 0.0%5983 0.767 1.384 3.774
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.406 0.0%41708 0.000 0.010 0.107
hisgrep/artcncl00:00:00.015 0.0%26 0.000 0.577 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.821 0.0%37517 0.079 0.155 0.525
history sync00:00:00.104 0.0%337 0.000 0.309 2.000
history write00:00:04.241 0.0%7428 0.250 0.571 1.211
idle23:59:47.356 99.9%41673 868.817 2072.981 14291.476
nntp read00:00:01.054 0.0%40147 0.003 0.026 0.091
overview write00:00:06.867 0.0%5983 0.313 1.148 5.677
perl filter00:00:14.247 0.0%7428 0.843 1.918 28.200
python filter00:00:01.209 0.0%7428 0.018 0.163 5.400
site send00:00:00.398 0.0%11912 0.000 0.033 0.194
TOTAL: 1824:00:30.559100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.043 0.0%6090 0.000 0.007 0.750
article prepare00:00:00.004 0.0%26304 0.000 0.000 0.011
article read00:00:01.502 0.0%4376 0.071 0.343 2.000
backlog stats00:00:00.054 0.0%79937 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.666 0.0%44739 0.000 0.015 0.052
data write00:00:01.261 0.0%38785 0.000 0.033 0.084
idle23:57:16.075100.0%79937 353.357 1078.800 5940.515
status file00:00:00.266 0.0%172 0.000 1.547 8.000
TOTAL: 923:57:19.871100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle02:28:47.566100.0%44302 25.500 201.516 17000.645
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.142 0.0%98947 0.000 0.042 0.667
readart00:00:00.166 0.0%436 0.000 0.381 5.000
TOTAL: 402:28:51.874100.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 7 10102 4272 5763 67 42%47:36:45
2usenet.blueworldhosting.com 72 7409 1056 5078 1275 14%24:06:30
3newsfeed.endofthelinebbs.com 1 4471 413 3970 88 9%20:05:56
4news.tebibyte.org 2 3764 152 3612 0 4%23:58:34
5news.sklaffkom.se 43 116 108 8 0 93%11:56:27
6localhost 25 0 0 0 0 0%00:00:01
TOTAL: 6 150 25862 6001 18431 1430 23%127:44:13
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.4 MB0.0 KB188.6 KB14.6 MB 98%3.4 KB
2usenet.blueworldhosting.com7.9 MB0.0 KB2.5 MB10.4 MB 75%4.6 KB
3newsfeed.endofthelinebbs.com5.1 MB0.0 KB141.3 KB5.2 MB 97%10.7 KB
4news.tebibyte.org437.0 KB0.0 KB0.0 KB437.0 KB100%2.9 KB
5news.sklaffkom.se336.7 KB0.0 KB0.0 KB336.7 KB100%3.1 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 628.2 MB0.0 KB2.8 MB31.0 MB 90%4.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 3 03:00:04 - 03:59:59 133 2.2% 0.041.1 MB 3.8% 0.32
Nov 3 04:00:00 - 04:59:59 94 1.6% 0.03396.7 KB 1.3% 0.11
Nov 3 05:00:00 - 05:59:59 61 1.0% 0.02540.6 KB 1.8% 0.15
Nov 3 06:00:00 - 06:59:59 150 2.5% 0.04805.0 KB 2.7% 0.22
Nov 3 07:00:00 - 07:59:59 124 2.1% 0.03576.8 KB 1.9% 0.16
Nov 3 08:00:00 - 08:59:59 207 3.5% 0.06809.2 KB 2.7% 0.22
Nov 3 09:00:00 - 09:59:59 249 4.2% 0.07967.9 KB 3.3% 0.27
Nov 3 10:00:00 - 10:59:59 417 7.0% 0.121.5 MB 5.0% 0.41
Nov 3 11:00:00 - 11:59:59 346 5.8% 0.101.6 MB 5.5% 0.45
Nov 3 12:00:00 - 12:59:59 358 6.0% 0.101.8 MB 6.1% 0.50
Nov 3 13:00:00 - 13:59:59 275 4.6% 0.081.4 MB 4.9% 0.40
Nov 3 14:00:00 - 14:59:59 272 4.6% 0.081.7 MB 5.8% 0.48
Nov 3 15:00:00 - 15:59:59 339 5.7% 0.092.4 MB 8.3% 0.69
Nov 3 16:00:00 - 16:59:59 378 6.3% 0.101.6 MB 5.6% 0.46
Nov 3 17:00:00 - 17:59:59 290 4.9% 0.081.2 MB 4.0% 0.33
Nov 3 18:00:00 - 18:59:59 324 5.4% 0.091.3 MB 4.5% 0.37
Nov 3 19:00:00 - 19:59:59 364 6.1% 0.101.6 MB 5.5% 0.45
Nov 3 20:00:00 - 20:59:59 378 6.3% 0.101.5 MB 5.3% 0.44
Nov 3 21:00:00 - 21:59:59 259 4.3% 0.071.1 MB 3.9% 0.33
Nov 3 22:00:00 - 22:59:59 266 4.5% 0.071.3 MB 4.3% 0.36
Nov 3 23:00:00 - 23:59:59 267 4.5% 0.071.9 MB 6.4% 0.53
Nov 4 00:00:00 - 00:59:59 175 2.9% 0.05856.3 KB 2.9% 0.24
Nov 4 01:00:00 - 01:59:59 112 1.9% 0.03752.3 KB 2.5% 0.21
Nov 4 02:00:00 - 03:00:04 128 2.1% 0.04551.7 KB 1.9% 0.15
TOTAL: 24:00:00 5966 100.0% 0.0729.0 MB 100.0% 0.34
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1274 361 0 0 0 0 0 0 913
2twilightnode-out.news.weretis.net 113 18 0 0 0 0 0 0 95
3newsfeed.endofthelinebbs.com 99 5 0 0 0 0 0 0 94
4news.tebibyte.org 1 0 0 0 0 0 0 0 1
TOTAL: 4 1487 384 0 0 0 0 0 0 1103

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 48
alt.politics.communism 14
hispagatos.mastodon.tag.hacking 10
local.general 7
newsgrouper.support 3
alt.autos.performance-cars 2
alt.autos.mitsubishi 1
alt.bbs.files-announce 1
alt.religion.christian.home-church 1
alt.religion.islam.arabic 1
alt.sports.basketball.nba.la-lakers 1
chmurka.statystyki 1
de.soc.recht.datennetze 1
dk.kultur.musik 1
fido.ru.space 1
free.comp 1
hispagatos.mastodon.tag.hackers 1
hispagatos.test 1
TOTAL: 19 384

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 907
Too many newsgroups 141
HTML Multipart 5
User-issued cancel 5
EMP (phn path) 1
HTML post 1
Too many newsgroups (meow) 1
TOTAL: 7 1061

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7707 2088 3715 109 0 1 27%23:59:55
2sklaffkom.se 5974 941 1653 3318 0 0 15%23:59:56
3newsfeed.endofthelinebbs.com 9833 75 5344 8 0 160 0%23:59:55
4usenet.blueworldhosting.com 7849 4 4473 33 0 17 0%23:59:55
5weretis.net 1777 3 1690 0 0 4 0%23:45:25
TOTAL: 5 33140 3111 16875 3468 0 182 9%119:45:06
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org5.7 MB1.9 MB7.6 MB0.1 KB/s3.5 KB23:59:55
2sklaffkom.se1.6 MB21.0 MB22.6 MB0.3 KB/s5.4 KB23:59:56
3newsfeed.endofthelinebbs.com246.4 KB19.5 KB265.9 KB0.0 KB/s3.2 KB23:59:55
4usenet.blueworldhosting.com12.6 KB109.8 KB122.4 KB0.0 KB/s3.3 KB23:59:55
5weretis.net3.6 KB0.0 KB3.6 KB0.0 KB/s1.2 KB23:45:25
TOTAL: 57.6 MB23.0 MB30.6 MB0.1 KB/s4.8 KB119:45:06
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1174.96.78.81 592 4361.7 MB 173 0 002:28:09
TOTAL: 1 592 4361.7 MB 173 0 002:28:09

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 596 4361.7 MB 173 0 002:28:30
2*.66.censys-scanner.com 6 00.0 KB 0 0 000:00:06
3*.infrawat.ch 2 00.0 KB 0 0 000:00:00
4*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
5*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
6*.stretchoid.com 1 00.0 KB 0 0 000:00:09
TOTAL: 6 608 4361.7 MB 173 0 002:28:51

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
174.96.78.81 6.024 17.830 0.00002:28:09
80.94.92.19 0.005 0.011 0.00000:00:10
azpdssf4zm3q.stretchoid.com 0.004 0.014 0.00000:00:09
20.83.44.180 0.005 0.015 0.00000:00:09
71.159.132.66.censys-scanner.com 0.046 0.031 0.00000:00:03
annalise.probe.onyphe.net 0.006 0.006 0.00000:00:02
189.186.132.66.censys-scanner.com 0.058 0.025 0.00000:00:02
neave.probe.onyphe.net 0.005 0.012 0.00000:00:00
5-226-140-65.infrawat.ch 0.006 0.008 0.00000:00:00
205.210.31.71 0.005 0.011 0.00000:00:00
69-5-169-216.infrawat.ch 0.005 0.006 0.00000:00:00
147.185.132.109 0.008 0.006 0.00000:00:00
o330.scanner.modat.io 0.006 0.013 0.00000:00:00
TOTAL: 13 6.183 17.988 0.00002:28:51

Curious NNRP explorers [Top 20]:

SystemConn
189.186.132.66.censys-scanner.com 3
71.159.132.66.censys-scanner.com 3
147.185.132.109 1
20.83.44.180 1
205.210.31.71 1
5-226-140-65.infrawat.ch 1
69-5-169-216.infrawat.ch 1
80.94.92.19 1
annalise.probe.onyphe.net 1
azpdssf4zm3q.stretchoid.com 1
neave.probe.onyphe.net 1
o330.scanner.modat.io 1
TOTAL: 12 16

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

SystemConn
neave.probe.onyphe.net 3
189.186.132.66.censys-scanner.com 1
71.159.132.66.censys-scanner.com 1
TOTAL: 3 5

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

CommandCount
EHLO www.censys.io 2
#026#003#003#002c#001 1
;tB?d?K7?M? 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
TOTAL: 4 5

NNRP client timeouts [Top 20]:

SystemConnPeer
174.96.78.81 1 0
189.186.132.66.censys-scanner.com 1 2
5-226-140-65.infrawat.ch 1 1
71.159.132.66.censys-scanner.com 1 2
TOTAL: 4 4 8

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

HierarchyCountPct
1free 195 44.7%
2linux 74 17.0%
3alt 39 8.9%
4fr 29 6.7%
5it 22 5.0%
6rec 10 2.3%
7pl 9 2.1%
8de 6 1.4%
9junk 6 1.4%
10microsoft 6 1.4%
11news 6 1.4%
12uk 6 1.4%
13aus 5 1.1%
14talk 5 1.1%
15dk 3 0.7%
16sci 3 0.7%
17hispagatos 2 0.5%
18it-alt 2 0.5%
19soc 2 0.5%
20comp 1 0.2%
21edm 1 0.2%
22lada 1 0.2%
23misc 1 0.2%
24nl 1 0.2%
25sac 1 0.2%
TOTAL: 25 436100.0%

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

NewsgroupCount
1free.usenet 146
2free.pt 49
3linux.kernel.netdev 38
4fr.soc.politique 22
5linux.debian.changes.devel 13
6rec.arts.drwho 10
7alt.fan.rush-limbaugh 7
8alt.food.fast-food 6
9junk 6
10linux.debian.bugs.dist 6
11linux.debian.maint.ocaml.maint 6
12news.lists.filters 6
13alt.buddha.short.fat.guy 4
14alt.usage.english 4
15it.hobby.cucina 4
16it.test 4
17it.tlc.telefonia.voip 4
18pl.misc.elektronika 4
19alt.anonymous.messages 3
20aus.cars 3
21dk.politik 3
22fr.bio.medecine 3
23it.sport.calcio.milan 3
24linux.debian.bugs.rc 3
25linux.kernel.git 3
26microsoft.public.windowsxp.general 3
27microsoft.public.windowsxp.help_and_support 3
28talk.politics.guns 3
29alt.bestjobsusa.computer.jobs 2
30alt.computer.workshop 2
31aus.politics 2
32hispagatos.hacking.news 2
33it-alt.sport.calcio.juventus 2
34it.hobby.fai-da-te 2
35it.sport.calcio.inter-fc 2
36it.tlc.cellulari.android 2
37linux.debian.devel 2
38linux.debian.user 2
39pl.misc.telefonia.gsm 2
40pl.soc.prawo 2
41soc.culture.jewish 2
42talk.politics.misc 2
43uk.current-events.terrorism 2
44uk.telecom 2
45alt.bbs.synchronet 1
46alt.checkmate 1
47alt.comp.software.firefox 1
48alt.home.repair 1
49alt.os.linux 1
50alt.politics.republicans 1
51alt.privacy.anon-server 1
52alt.prophecies.nostradamus 1
53alt.russian.z1 1
54alt.suicide.holiday 1
55alt.windows7.general 1
56comp.lang.c 1
57de.alt.folklore.ddr 1
58de.etc.finanz.banken+broker 1
59de.sci.medizin.psychiatrie 1
60de.soc.politik.misc 1
61de.soc.recht.strassenverkehr 1
62de.talk.tagesgeschehen 1
63edm.general 1
64fr.lettres.langue.francaise 1
65fr.rec.bricolage 1
66fr.rec.photo 1
67fr.sci.physique 1
68it.discussioni.commercialisti 1
69lada.talk 1
70linux.debian.maint.boot 1
71misc.jobs.contract 1
72nl.religie 1
73pl.soc.polityka 1
74sac.politics 1
75sci.electronics.design 1
76sci.logic 1
77sci.physics 1
78uk.legal.moderated 1
79uk.railway 1
TOTAL: 79 436