Daily Usenet report

Oct 3 03:00:04 -- Oct 4 03:00:04

Unknown entries from news log file:

First 50 / 377 lines (13.3%)

Oct 3 03:01:18 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 3 03:19:31 twilightnode nnrpd[581383]: Reading access from /usr/local/news/etc/readers.conf
Oct 3 03:19:31 twilightnode nnrpd[581383]: Auth strategy 'localhost' does not match client. Removing.
Oct 3 03:19:31 twilightnode nnrpd[581383]: 66.61.98.95 res <anonymous>
Oct 3 03:19:31 twilightnode nnrpd[581383]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 3 03:19:31 twilightnode nnrpd[581383]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 3 03:19:31 twilightnode nnrpd[581383]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 3 03:19:31 twilightnode nnrpd[581383]: 66.61.98.95 no_post anonymous-read
Oct 3 03:19:31 twilightnode nnrpd[581384]: Reading access from /usr/local/news/etc/readers.conf
Oct 3 03:19:31 twilightnode nnrpd[581384]: Auth strategy 'localhost' does not match client. Removing.
Oct 3 03:19:31 twilightnode nnrpd[581384]: 66.61.98.95 res <anonymous>
Oct 3 03:19:31 twilightnode nnrpd[581384]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 3 03:19:31 twilightnode nnrpd[581384]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 3 03:19:31 twilightnode nnrpd[581384]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 3 03:19:31 twilightnode nnrpd[581384]: 66.61.98.95 no_post anonymous-read
Oct 3 04:02:01 twilightnode nnrpd[583279]: Reading access from /usr/local/news/etc/readers.conf
Oct 3 04:02:01 twilightnode nnrpd[583279]: Auth strategy 'localhost' does not match client. Removing.
Oct 3 04:02:01 twilightnode nnrpd[583279]: 66.61.98.95 res <anonymous>
Oct 3 04:02:01 twilightnode nnrpd[583279]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 3 04:02:01 twilightnode nnrpd[583279]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 3 04:02:01 twilightnode nnrpd[583279]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 3 04:02:01 twilightnode nnrpd[583279]: 66.61.98.95 no_post anonymous-read
Oct 3 04:02:01 twilightnode nnrpd[583283]: Reading access from /usr/local/news/etc/readers.conf
Oct 3 04:02:01 twilightnode nnrpd[583283]: Auth strategy 'localhost' does not match client. Removing.
Oct 3 04:02:01 twilightnode nnrpd[583283]: 66.61.98.95 res <anonymous>
Oct 3 04:02:01 twilightnode nnrpd[583283]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 3 04:02:01 twilightnode nnrpd[583283]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 3 04:02:01 twilightnode nnrpd[583283]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 3 04:02:01 twilightnode nnrpd[583283]: 66.61.98.95 no_post anonymous-read
Oct 3 05:22:43 twilightnode nnrpd[587046]: Reading access from /usr/local/news/etc/readers.conf
Oct 3 05:22:43 twilightnode nnrpd[587046]: Auth strategy 'localhost' does not match client. Removing.
Oct 3 05:22:43 twilightnode nnrpd[587046]: 134.168.246.33 res <anonymous>
Oct 3 05:22:43 twilightnode nnrpd[587046]: 134.168.246.33 no_match_user <anonymous> <localhost>
Oct 3 05:22:43 twilightnode nnrpd[587046]: 134.168.246.33 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 3 05:22:43 twilightnode nnrpd[587046]: 134.168.246.33 match_user <anonymous> <anonymous>
Oct 3 05:22:43 twilightnode nnrpd[587046]: 134.168.246.33 no_post anonymous-read
Oct 3 05:22:44 twilightnode nnrpd[587047]: Reading access from /usr/local/news/etc/readers.conf
Oct 3 05:22:44 twilightnode nnrpd[587047]: Auth strategy 'localhost' does not match client. Removing.
Oct 3 05:22:44 twilightnode nnrpd[587047]: 134.168.246.33 res <anonymous>
Oct 3 05:22:44 twilightnode nnrpd[587047]: 134.168.246.33 no_match_user <anonymous> <localhost>
Oct 3 05:22:44 twilightnode nnrpd[587047]: 134.168.246.33 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 3 05:22:44 twilightnode nnrpd[587047]: 134.168.246.33 match_user <anonymous> <anonymous>
Oct 3 05:22:44 twilightnode nnrpd[587047]: 134.168.246.33 no_post anonymous-read
Oct 3 05:22:54 twilightnode nnrpd[587048]: Reading access from /usr/local/news/etc/readers.conf
Oct 3 05:22:54 twilightnode nnrpd[587048]: Auth strategy 'localhost' does not match client. Removing.
Oct 3 05:22:54 twilightnode nnrpd[587048]: 134.168.246.33 res <anonymous>
Oct 3 05:22:54 twilightnode nnrpd[587048]: 134.168.246.33 no_match_user <anonymous> <localhost>
Oct 3 05:22:54 twilightnode nnrpd[587048]: 134.168.246.33 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 3 05:22:54 twilightnode nnrpd[587048]: 134.168.246.33 match_user <anonymous> <anonymous>
Oct 3 05:22:54 twilightnode nnrpd[587048]: 134.168.246.33 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 8791 36.6%765.4 KB 24.4%
innd 7227 30.1%1009.9 KB 32.2%
inn 6678 27.8%1.2 MB 37.6%
innfeed 1344 5.6%181.1 KB 5.8%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 24041 100.0%3.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 18402 53.9%
Negative hits 9098 26.6%
Do not exist 6622 19.4%
Cache misses 41 0.1%
TOTAL: 4 34163 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.145 0.0%27 0.000 5.370 31.000
article cleanup00:00:00.076 0.0%6637 0.000 0.011 0.115
article logging00:00:00.263 0.0%6679 0.000 0.039 0.246
article parse00:00:00.142 0.0%8943 0.000 0.016 0.094
article write00:00:06.208 0.0%5340 0.675 1.163 2.346
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.310 0.0%36678 0.000 0.008 0.098
hisgrep/artcncl00:00:00.030 0.0%27 0.000 1.111 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.369 0.0%34240 0.056 0.128 0.452
history sync00:00:00.099 0.0%409 0.000 0.242 6.333
history write00:00:03.330 0.0%6637 0.163 0.502 1.093
idle24:00:26.089 99.9%39027 1145.719 2214.520 7499.000
nntp read00:00:00.896 0.0%37275 0.000 0.024 0.079
overview write00:00:04.778 0.0%5340 0.349 0.895 3.619
perl filter00:00:14.956 0.0%6637 1.104 2.253 13.565
python filter00:00:01.221 0.0%6637 0.024 0.184 1.217
site send00:00:00.244 0.0%10616 0.000 0.023 0.158
TOTAL: 1824:01:03.156100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.025 0.0%5539 0.000 0.005 0.083
article prepare00:00:00.003 0.0%21680 0.000 0.000 0.009
article read00:00:01.043 0.0%3480 0.000 0.300 2.250
backlog stats00:00:00.052 0.0%71133 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.539 0.0%39368 0.000 0.014 0.057
data write00:00:00.891 0.0%34291 0.005 0.026 0.068
idle23:50:08.664100.0%71133 312.279 1206.313 4350.870
status file00:00:00.233 0.0%207 0.000 1.126 8.000
TOTAL: 923:50:11.450100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:41:22.342 99.9%10805 55.000 229.740 41805.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.656 0.1%47283 0.000 0.056 0.333
readart00:00:00.197 0.0%425 0.000 0.464 0.818
TOTAL: 400:41:25.195100.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 10803 4451 6238 114 41%52:09:40
2usenet.blueworldhosting.com 1354 6571 592 4912 1067 9%23:58:50
3newsfeed.endofthelinebbs.com 1 5045 207 4736 102 4%26:00:41
4news.tebibyte.org 1 3373 128 3245 0 3%23:59:57
5news.sklaffkom.se 32 58 47 11 0 81%07:17:45
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 1417 25850 5425 19142 1283 20%133:26:53
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.2 MB0.0 KB439.5 KB15.6 MB 97%3.5 KB
2newsfeed.endofthelinebbs.com6.4 MB0.0 KB139.4 KB6.6 MB 97%21.7 KB
3usenet.blueworldhosting.com2.6 MB0.0 KB1.3 MB3.9 MB 67%2.4 KB
4news.tebibyte.org478.8 KB0.0 KB0.0 KB478.8 KB100%3.7 KB
5news.sklaffkom.se195.4 KB0.0 KB0.0 KB195.4 KB100%4.2 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 624.8 MB0.0 KB1.8 MB26.7 MB 93%4.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 3 03:00:04 - 03:59:59 103 1.9% 0.03504.9 KB 2.4% 0.14
Oct 3 04:00:00 - 04:59:59 94 1.8% 0.03335.1 KB 1.6% 0.09
Oct 3 05:00:00 - 05:59:59 91 1.7% 0.03345.3 KB 1.7% 0.10
Oct 3 06:00:00 - 06:59:59 86 1.6% 0.02389.8 KB 1.9% 0.11
Oct 3 07:00:00 - 07:59:59 162 3.0% 0.04489.6 KB 2.4% 0.14
Oct 3 08:00:00 - 08:59:59 205 3.8% 0.06694.9 KB 3.3% 0.19
Oct 3 09:00:00 - 09:59:59 216 4.0% 0.06638.0 KB 3.1% 0.18
Oct 3 10:00:00 - 10:59:59 322 6.0% 0.091009.5 KB 4.9% 0.28
Oct 3 11:00:00 - 11:59:59 301 5.6% 0.08943.4 KB 4.5% 0.26
Oct 3 12:00:00 - 12:59:59 230 4.3% 0.06935.5 KB 4.5% 0.26
Oct 3 13:00:00 - 13:59:59 237 4.4% 0.07868.9 KB 4.2% 0.24
Oct 3 14:00:00 - 14:59:59 200 3.7% 0.06691.3 KB 3.3% 0.19
Oct 3 15:00:00 - 15:59:59 280 5.2% 0.081.0 MB 5.0% 0.29
Oct 3 16:00:00 - 16:59:59 321 6.0% 0.091.2 MB 6.1% 0.35
Oct 3 17:00:00 - 17:59:59 286 5.4% 0.081.4 MB 6.7% 0.39
Oct 3 18:00:00 - 18:59:59 373 7.0% 0.101.8 MB 8.6% 0.50
Oct 3 19:00:00 - 19:59:59 278 5.2% 0.081.1 MB 5.4% 0.31
Oct 3 20:00:00 - 20:59:59 260 4.9% 0.07860.3 KB 4.1% 0.24
Oct 3 21:00:00 - 21:59:59 294 5.5% 0.081.1 MB 5.2% 0.30
Oct 3 22:00:00 - 22:59:59 247 4.6% 0.07903.0 KB 4.4% 0.25
Oct 3 23:00:00 - 23:59:59 233 4.4% 0.061.2 MB 6.0% 0.35
Oct 4 00:00:00 - 00:59:59 199 3.7% 0.06942.1 KB 4.5% 0.26
Oct 4 01:00:00 - 01:59:59 160 3.0% 0.04661.4 KB 3.2% 0.18
Oct 4 02:00:00 - 02:59:59 149 2.8% 0.04586.2 KB 2.8% 0.16
Oct 4 03:00:00 - 03:00:04 12 0.2% 3.0021.6 KB 0.1% 5.41
TOTAL: 24:00:00 5339 100.0% 0.0620.3 MB 100.0% 0.24
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1065 318 0 0 0 0 0 0 747
2twilightnode-out.news.weretis.net 157 7 0 0 0 0 0 0 150
3newsfeed.endofthelinebbs.com 115 3 0 0 0 0 0 0 112
4news.tebibyte.org 2 1 0 0 0 0 0 0 1
TOTAL: 4 1339 329 0 0 0 0 0 0 1010

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 18
hispagatos.mastodon.tag.hacking 3
fido.ru.home 2
free.biz 2
local.general 2
newsgrouper.support 2
pl.listserv.chomor-l 2
3dfx.game 1
Tedoke 1
alt.bbs.files-announce 1
alt.pl.radio.radiomaryja 1
chmurka.statystyki 1
dk.snak.seksualitet 1
free.it.scienza.paleontologia 1
hispagatos.mastodon.tag.hackers 1
linux.debian.isp 1
sfnet.test 1
TOTAL: 18 329

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 790
Too many newsgroups 75
EMP (phn path) 70
User-issued cancel 33
TOTAL: 4 968

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
usenet.blueworldhosting.com 2
TOTAL: 1 2
Including strange strings
twilightnode-out.news.weretis.net 41
newsfeed.endofthelinebbs.com 1
TOTAL: 2 42
TOTAL: 2 44

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7116 1976 3258 37 0 46 27%23:58:05
2sklaffkom.se 5409 890 1902 2484 0 0 16%23:58:40
3newsfeed.endofthelinebbs.com 9664 33 5045 14 0 229 0%23:59:54
4weretis.net 966 1 929 0 0 0 0%23:08:47
5usenet.blueworldhosting.com 6945 0 4279 0 0 10 0%23:59:44
TOTAL: 5 30100 2900 15413 2535 0 285 9%119:05:10
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org5.1 MB364.5 KB5.5 MB0.1 KB/s2.8 KB23:58:05
2sklaffkom.se1.4 MB10.5 MB11.9 MB0.1 KB/s3.6 KB23:58:40
3newsfeed.endofthelinebbs.com98.0 KB175.9 KB273.9 KB0.0 KB/s5.8 KB23:59:54
4weretis.net1.2 KB0.0 KB1.2 KB0.0 KB/s1.2 KB23:08:47
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
TOTAL: 56.6 MB11.1 MB17.7 MB0.0 KB/s3.3 KB119:05:10
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 34 4048.5 MB 94 0 000:34:36
22603:6010:6802:4ca5:1a03:73ff:fe28:be0f 6 2158.9 KB 10 0 000:06:17
TOTAL: 2 40 4258.5 MB 104 0 000:40:54

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 45 4258.5 MB 104 0 000:41:14
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:05
5*.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
6*.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
7*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 53 4258.5 MB 104 0 000:41:25

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 3.364 11.090 0.00000:34:36
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f 0.491 1.770 0.00000:06:17
134.168.246.33 0.011 0.027 0.00000:00:10
52.165.91.103 0.006 0.017 0.00000:00:10
74.195.132.66.censys-scanner.com 0.026 0.017 0.00000:00:05
ellen.probe.onyphe.net 0.001 0.008 0.00000:00:02
charlee.probe.onyphe.net 0.012 0.009 0.00000:00:00
o006.scanner.modat.io 0.002 0.018 0.00000:00:00
ec2-52-53-180-34.us-west-1.compute.amazonaws.com 0.003 0.015 0.00000:00:00
89-21-67-166.infrawat.ch 0.002 0.007 0.00000:00:00
172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 0.004 0.009 0.00000:00:00
185-223-235-50.infrawat.ch 0.006 0.008 0.00000:00:00
TOTAL: 12 3.928 12.995 0.00000:41:25

Curious NNRP explorers [Top 20]:

SystemConn
134.168.246.33 3
52.165.91.103 2
172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 1
185-223-235-50.infrawat.ch 1
74.195.132.66.censys-scanner.com 1
89-21-67-166.infrawat.ch 1
charlee.probe.onyphe.net 1
ec2-52-53-180-34.us-west-1.compute.amazonaws.com 1
ellen.probe.onyphe.net 1
o006.scanner.modat.io 1
TOTAL: 10 13

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

SystemConn
134.168.246.33 2
charlee.probe.onyphe.net 2
52.165.91.103 1
74.195.132.66.censys-scanner.com 1
TOTAL: 4 6

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
89-21-67-166.infrawat.ch 1 1
TOTAL: 1 1 2

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

HierarchyCountPct
1microsoft 104 24.5%
2linux 84 19.8%
3free 64 15.1%
4alt 58 13.6%
5junk 39 9.2%
6rec 16 3.8%
7talk 14 3.3%
8uk 14 3.3%
9misc 9 2.1%
10de 6 1.4%
11nl 4 0.9%
12it 2 0.5%
13lada 2 0.5%
14muc 2 0.5%
15soc 2 0.5%
16cn 1 0.2%
17comp 1 0.2%
18dk 1 0.2%
19it-alt 1 0.2%
20sac 1 0.2%
TOTAL: 20 425100.0%

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

NewsgroupCount
1linux.kernel.netdev 64
2free.usenet 60
3microsoft.public.windowsxp.general 60
4microsoft.public.windowsxp.help_and_support 44
5junk 39
6alt.test 22
7linux.debian.changes.devel 11
8talk.politics.misc 10
9alt.russian.z1 7
10alt.usage.english 7
11linux.debian.bugs.dist 7
12misc.immigration.usa 7
13rec.food.cooking 6
14uk.rec.gardening 6
15alt.christnet.bible 5
16uk.d-i-y 5
17free.pt 4
18rec.arts.sf.tv 4
19talk.politics.guns 4
20alt.checkmate 3
21alt.fan.rush-limbaugh 3
22rec.arts.tv 3
23de.etc.bahn.misc 2
24lada.talk 2
25linux.debian.maint.hams 2
26nl.test 2
27rec.arts.comics.dc.universe 2
28soc.culture.china 2
29uk.railway 2
30alt.arts.poetry.comments 1
31alt.bbs 1
32alt.bbs.synchronet 1
33alt.buddha.short.fat.guy 1
34alt.computer.workshop 1
35alt.law-enforcement 1
36alt.os.linux.slackware 1
37alt.politics 1
38alt.privacy.anon-server 1
39alt.tv.big-brother 1
40alt.usenet.offline-reader.forte-agent 1
41cn.soc.history 1
42comp.sys.cbm 1
43de.alt.fan.pluesch 1
44de.sci.electronics 1
45de.sci.ing.elektrotechnik 1
46de.soc.weltanschauung.christentum 1
47dk.politik 1
48it-alt.sport.calcio.juventus 1
49it.cultura.filosofia 1
50it.hobby.fai-da-te 1
51misc.phone.mobile.iphone 1
52misc.transport.rail.americas 1
53muc.lists.freebsd.bugs 1
54muc.lists.freebsd.fs 1
55nl.politiek 1
56nl.scientology 1
57rec.music.rock-pop-r+b.1950s 1
58sac.politics 1
59uk.legal.moderated 1
TOTAL: 59 425
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