Daily Usenet report

Oct 6 03:00:03 -- Oct 7 03:00:04

Unknown entries from news log file:

First 50 / 8257 lines (0.6%)

Oct 6 03:00:58 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 6 03:01:34 twilightnode nnrpd[809704]: Reading access from /usr/local/news/etc/readers.conf
Oct 6 03:01:34 twilightnode nnrpd[809704]: Auth strategy 'localhost' does not match client. Removing.
Oct 6 03:01:34 twilightnode nnrpd[809704]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f res <anonymous>
Oct 6 03:01:34 twilightnode nnrpd[809704]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> <localhost>
Oct 6 03:01:34 twilightnode nnrpd[809704]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 6 03:01:34 twilightnode nnrpd[809704]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f match_user <anonymous> <anonymous>
Oct 6 03:01:34 twilightnode nnrpd[809704]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_post anonymous-read
Oct 6 03:01:34 twilightnode nnrpd[809705]: Reading access from /usr/local/news/etc/readers.conf
Oct 6 03:01:34 twilightnode nnrpd[809705]: Auth strategy 'localhost' does not match client. Removing.
Oct 6 03:01:34 twilightnode nnrpd[809705]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f res <anonymous>
Oct 6 03:01:34 twilightnode nnrpd[809705]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> <localhost>
Oct 6 03:01:34 twilightnode nnrpd[809705]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 6 03:01:34 twilightnode nnrpd[809705]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f match_user <anonymous> <anonymous>
Oct 6 03:01:34 twilightnode nnrpd[809705]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_post anonymous-read
Oct 6 03:01:54 twilightnode nnrpd[809708]: Reading access from /usr/local/news/etc/readers.conf
Oct 6 03:01:54 twilightnode nnrpd[809708]: Auth strategy 'localhost' does not match client. Removing.
Oct 6 03:01:54 twilightnode nnrpd[809708]: 66.61.98.95 res <anonymous>
Oct 6 03:01:54 twilightnode nnrpd[809708]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 6 03:01:54 twilightnode nnrpd[809708]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 6 03:01:54 twilightnode nnrpd[809708]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 6 03:01:54 twilightnode nnrpd[809708]: 66.61.98.95 no_post anonymous-read
Oct 6 03:01:55 twilightnode nnrpd[809709]: Reading access from /usr/local/news/etc/readers.conf
Oct 6 03:01:55 twilightnode nnrpd[809709]: Auth strategy 'localhost' does not match client. Removing.
Oct 6 03:01:55 twilightnode nnrpd[809709]: 66.61.98.95 res <anonymous>
Oct 6 03:01:55 twilightnode nnrpd[809709]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 6 03:01:55 twilightnode nnrpd[809709]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 6 03:01:55 twilightnode nnrpd[809709]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 6 03:01:55 twilightnode nnrpd[809709]: 66.61.98.95 no_post anonymous-read
Oct 6 03:02:07 twilightnode nnrpd[809722]: Reading access from /usr/local/news/etc/readers.conf
Oct 6 03:02:07 twilightnode nnrpd[809722]: Auth strategy 'localhost' does not match client. Removing.
Oct 6 03:02:07 twilightnode nnrpd[809722]: 66.61.98.95 res <anonymous>
Oct 6 03:02:07 twilightnode nnrpd[809722]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 6 03:02:07 twilightnode nnrpd[809722]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 6 03:02:07 twilightnode nnrpd[809722]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 6 03:02:07 twilightnode nnrpd[809722]: 66.61.98.95 no_post anonymous-read
Oct 6 03:02:07 twilightnode nnrpd[809723]: Reading access from /usr/local/news/etc/readers.conf
Oct 6 03:02:07 twilightnode nnrpd[809723]: Auth strategy 'localhost' does not match client. Removing.
Oct 6 03:02:07 twilightnode nnrpd[809723]: 66.61.98.95 res <anonymous>
Oct 6 03:02:07 twilightnode nnrpd[809723]: 66.61.98.95 no_match_user <anonymous> <localhost>
Oct 6 03:02:07 twilightnode nnrpd[809723]: 66.61.98.95 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 6 03:02:07 twilightnode nnrpd[809723]: 66.61.98.95 match_user <anonymous> <anonymous>
Oct 6 03:02:07 twilightnode nnrpd[809723]: 66.61.98.95 no_post anonymous-read
Oct 6 03:02:58 twilightnode nnrpd[809730]: Reading access from /usr/local/news/etc/readers.conf
Oct 6 03:02:58 twilightnode nnrpd[809730]: Auth strategy 'localhost' does not match client. Removing.
Oct 6 03:02:58 twilightnode nnrpd[809730]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f res <anonymous>
Oct 6 03:02:58 twilightnode nnrpd[809730]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> <localhost>
Oct 6 03:02:58 twilightnode nnrpd[809730]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 6 03:02:58 twilightnode nnrpd[809730]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f match_user <anonymous> <anonymous>
Oct 6 03:02:58 twilightnode nnrpd[809730]: 2603:6010:6802:4ca5:1a03:73ff:fe28:be0f no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 20547 56.2%2.0 MB 45.4%
inn 7372 20.2%1.3 MB 28.4%
innd 7359 20.1%1007.9 KB 21.9%
innfeed 1268 3.5%197.1 KB 4.3%
controlchan 3 0.0%0.4 KB 0.0%
TOTAL: 5 36549 100.0%4.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20392 53.4%
Negative hits 10464 27.4%
Do not exist 7301 19.1%
Cache misses 17 0.0%
TOTAL: 4 38174 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.053 0.0%19 1.875 2.789 9.000
article cleanup00:00:00.058 0.0%7336 0.000 0.008 0.083
article logging00:00:00.224 0.0%7369 0.000 0.030 0.161
article parse00:00:00.145 0.0%10290 0.000 0.014 0.074
article write00:00:05.311 0.0%6067 0.528 0.875 1.632
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.196 0.0%41833 0.000 0.005 0.057
hisgrep/artcncl00:00:00.018 0.0%19 0.750 0.947 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:03.642 0.0%38353 0.045 0.095 0.233
history sync00:00:00.044 0.0%408 0.000 0.108 1.000
history write00:00:02.900 0.0%7336 0.143 0.395 0.757
idle24:00:28.251100.0%46276 897.930 1867.669 7325.000
nntp read00:00:00.840 0.0%42176 0.000 0.020 0.051
overview write00:00:03.217 0.0%6067 0.213 0.530 1.792
perl filter00:00:09.794 0.0%7336 0.789 1.335 6.222
python filter00:00:00.669 0.0%7336 0.000 0.091 0.778
site send00:00:00.305 0.0%12103 0.000 0.025 0.112
TOTAL: 2024:00:55.667100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.010 0.0%6513 0.000 0.002 0.053
article prepare00:00:00.002 0.0%25188 0.000 0.000 0.003
article read00:00:00.757 0.0%4069 0.000 0.186 0.667
backlog stats00:00:00.040 0.0%80930 0.000 0.000 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.610 0.0%45185 0.000 0.014 0.068
data write00:00:00.930 0.0%39176 0.000 0.024 0.055
idle23:50:23.230100.0%80930 248.478 1060.462 5660.519
status file00:00:00.102 0.0%170 0.000 0.600 15.000
TOTAL: 923:50:25.681100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:31:30.671100.0%9411 59.500 200.900 9880.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.395 0.0%22575 0.000 0.017 3.500
readart00:00:00.243 0.0%525 0.000 0.463 1.000
TOTAL: 400:31:31.309100.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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org020002
TOTAL020002

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 11853 4699 6991 163 39%49:25:38
2usenet.blueworldhosting.com 1391 7250 886 5402 962 12%23:48:44
3newsfeed.endofthelinebbs.com 1 5882 393 5325 164 6%26:55:39
4news.tebibyte.org 3 6611 217 6392 2 3%38:42:09
5news.sklaffkom.se 37 95 90 5 0 94%09:34:31
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 1462 31691 6285 24115 1291 19%148:26:41
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net16.2 MB0.0 KB560.3 KB16.7 MB 96%3.5 KB
2usenet.blueworldhosting.com4.6 MB0.0 KB1.4 MB6.0 MB 76%3.3 KB
3newsfeed.endofthelinebbs.com4.0 MB0.0 KB256.7 KB4.3 MB 94%7.9 KB
4news.tebibyte.org952.9 KB0.0 KB2.1 KB955.0 KB 99%4.4 KB
5news.sklaffkom.se291.6 KB0.0 KB0.0 KB291.6 KB100%3.2 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 626.0 MB0.0 KB2.2 MB28.2 MB 92%3.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 6 03:00:03 - 03:59:59 128 2.1% 0.04582.5 KB 2.3% 0.16
Oct 6 04:00:00 - 04:59:59 130 2.1% 0.04729.3 KB 2.9% 0.20
Oct 6 05:00:00 - 05:59:59 87 1.4% 0.02483.1 KB 1.9% 0.13
Oct 6 06:00:00 - 06:59:59 126 2.1% 0.04419.3 KB 1.6% 0.12
Oct 6 07:00:00 - 07:59:59 181 3.0% 0.05656.3 KB 2.6% 0.18
Oct 6 08:00:00 - 08:59:59 211 3.5% 0.06707.8 KB 2.8% 0.20
Oct 6 09:00:00 - 09:59:59 315 5.2% 0.091.3 MB 5.1% 0.36
Oct 6 10:00:00 - 10:59:59 280 4.6% 0.08925.0 KB 3.6% 0.26
Oct 6 11:00:00 - 11:59:59 315 5.2% 0.091.0 MB 4.2% 0.30
Oct 6 12:00:00 - 12:59:59 296 4.9% 0.081.1 MB 4.5% 0.32
Oct 6 13:00:00 - 13:59:59 241 4.0% 0.07929.0 KB 3.6% 0.26
Oct 6 14:00:00 - 14:59:59 312 5.1% 0.091.0 MB 4.0% 0.29
Oct 6 15:00:00 - 15:59:59 297 4.9% 0.081005.6 KB 3.9% 0.28
Oct 6 16:00:00 - 16:59:59 459 7.6% 0.131.6 MB 6.5% 0.46
Oct 6 17:00:00 - 17:59:59 415 6.8% 0.121.9 MB 7.7% 0.54
Oct 6 18:00:00 - 18:59:59 394 6.5% 0.111.9 MB 7.6% 0.54
Oct 6 19:00:00 - 19:59:59 368 6.1% 0.101.8 MB 7.4% 0.52
Oct 6 20:00:00 - 20:59:59 314 5.2% 0.091.4 MB 5.8% 0.41
Oct 6 21:00:00 - 21:59:59 253 4.2% 0.071.1 MB 4.5% 0.32
Oct 6 22:00:00 - 22:59:59 255 4.2% 0.071.0 MB 4.2% 0.30
Oct 6 23:00:00 - 23:59:59 173 2.9% 0.05864.9 KB 3.4% 0.24
Oct 7 00:00:00 - 00:59:59 166 2.7% 0.05734.1 KB 2.9% 0.20
Oct 7 01:00:00 - 01:59:59 177 2.9% 0.05873.9 KB 3.4% 0.24
Oct 7 02:00:00 - 02:59:59 164 2.7% 0.05919.1 KB 3.6% 0.26
Oct 7 03:00:00 - 03:00:04 11 0.2% 2.7515.3 KB 0.1% 3.84
TOTAL: 24:00:01 6068 100.0% 0.0725.0 MB 100.0% 0.30
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 963 318 0 0 0 0 0 0 645
2twilightnode-out.news.weretis.net 184 21 0 0 0 0 0 0 163
3newsfeed.endofthelinebbs.com 155 6 0 0 1 0 0 0 148
4news.tebibyte.org 2 1 0 0 0 0 0 0 1
TOTAL: 4 1304 346 0 0 1 0 0 0 957

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 20
pl.listserv.chomor-l 6
de.soc.recht.datennetze 5
fido.ru.home 4
fido.english_tutor 3
alt.sports.basketball.nba.la-lakers 2
alt.sports.football.pro.no-saints 2
fido.ru.qico 2
alt.2600.madrid 1
alt.bbs.files-announce 1
alt.politics.communism 1
alt.rock-n-roll 1
alt.sex.pedophilia 1
chmurka.statystyki 1
de.alt.astrologie 1
de.comp.office-pakete.misc 1
fido.golded 1
fido.ru.unix.bsd 1
fido.su.pol 1
TOTAL: 23 346

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

GroupsCount
uk.religion.christian 1
TOTAL: 1 1

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 749
EMP (phn path) 80
Too many newsgroups 48
User-issued cancel 27
EMP (phn nph) 14
Too many newsgroups (meow) 6
TOTAL: 6 924

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 32
usenet.blueworldhosting.com 1
TOTAL: 2 33
TOTAL: 1 33

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8172 2204 3728 97 0 22 26%23:59:56
2sklaffkom.se 6174 919 2096 3020 0 0 14%23:59:55
3newsfeed.endofthelinebbs.com 10966 61 5638 25 0 483 0%23:59:56
4weretis.net 1462 2 1376 0 0 0 0%23:53:17
5usenet.blueworldhosting.com 7797 0 4602 0 0 11 0%23:59:41
TOTAL: 5 34571 3186 17440 3142 0 516 9%119:52:45
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.3 MB1.5 MB7.8 MB0.1 KB/s3.5 KB23:59:56
2sklaffkom.se1.7 MB14.8 MB16.5 MB0.2 KB/s4.3 KB23:59:55
3newsfeed.endofthelinebbs.com230.4 KB58.8 KB289.2 KB0.0 KB/s3.4 KB23:59:56
4weretis.net6.1 KB0.0 KB6.1 KB0.0 KB/s3.0 KB23:53:17
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:41
TOTAL: 58.2 MB16.4 MB24.6 MB0.1 KB/s4.0 KB119:52:45
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 630 3416.4 MB 33 0 000:22:51
22603:6010:6802:4ca5:1a03:73ff:fe28:be0f 540 1841.9 MB 17 0 000:08:23
TOTAL: 21170 5258.2 MB 50 0 000:31:15

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved1171 5258.2 MB 50 0 000:31:15
2*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:01
3*.stretchoid.com 2 00.0 KB 0 0 000:00:09
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
5*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
TOTAL: 51178 5258.2 MB 50 0 000:31:30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 3.548 6.017 0.00000:22:51
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f 2.845 4.069 0.00000:08:23
azpdescxnsia.stretchoid.com 0.010 0.013 0.00000:00:09
37.195.132.66.censys-scanner.com 0.036 0.006 0.00000:00:02
r3-60-3c.monitoring.internet-measurement.com 0.004 0.005 0.00000:00:02
62.146.94.167.censys-scanner.com 0.043 0.027 0.00000:00:01
205.210.31.88 0.006 0.006 0.00000:00:00
TOTAL: 7 6.492 10.143 0.00000:31:30

Curious NNRP explorers [Top 20]:

SystemConn
62.146.94.167.censys-scanner.com 3
azpdescxnsia.stretchoid.com 2
205.210.31.88 1
37.195.132.66.censys-scanner.com 1
r3-60-3c.monitoring.internet-measurement.com 1
TOTAL: 5 8

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

SystemConn
37.195.132.66.censys-scanner.com 1
62.146.94.167.censys-scanner.com 1
azpdescxnsia.stretchoid.com 1
TOTAL: 3 3

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

CommandCount
EHLO www.censys.io 2
MGLNDD_172.234.115.63_119 1
TOTAL: 2 3

NNRP client timeouts [Top 20]:

SystemConnPeer
62.146.94.167.censys-scanner.com 1 2
TOTAL: 1 1 3

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

HierarchyCountPct
1microsoft 228 43.4%
2alt 118 22.5%
3free 65 12.4%
4aus 37 7.0%
5linux 25 4.8%
6hfx 14 2.7%
7de 7 1.3%
8uk 6 1.1%
9comp 5 1.0%
10hispagatos 5 1.0%
11talk 5 1.0%
12it 2 0.4%
13rec 2 0.4%
14eternal-september 1 0.2%
15fr 1 0.2%
16junk 1 0.2%
17misc 1 0.2%
18sac 1 0.2%
19seattle 1 0.2%
TOTAL: 19 525100.0%

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

NewsgroupCount
1microsoft.public.windowsxp.general 114
2microsoft.public.windowsxp.help_and_support 114
3free.usenet 65
4alt.anonymous.messages 55
5alt.fan.rush-limbaugh 44
6aus.cars 35
7linux.kernel.netdev 15
8hfx.general 14
9alt.comp.freeware 9
10linux.debian.kernel 8
11de.comm.provider.usenet 7
12hispagatos.hacking.news 5
13alt.politics.democrats.d 4
14comp.lang.c++ 4
15uk.tech.broadcast 4
16alt.politics.republicans 3
17aus.politics 2
18it.news.net-abuse 2
19linux.debian.changes.devel 2
20talk.politics.guns 2
21talk.politics.misc 2
22uk.comp.sys.mac 2
23alt.anagrams 1
24alt.arts.poetry.comments 1
25alt.tv.survivor 1
26comp.sys.cbm 1
27eternal-september.talk 1
28fr.rec.bricolage 1
29junk 1
30misc.survivalism 1
31rec.arts.drwho 1
32rec.food.cooking 1
33sac.politics 1
34seattle.politics 1
35talk.origins 1
TOTAL: 35 525
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