Daily Usenet report

Sep 11 03:00:03 -- Sep 12 03:00:03

Unknown entries from news log file:

First 50 / 18211 lines (0.3%)

Sep 11 03:01:03 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 11 04:39:08 twilightnode nnrpd[3222576]: Reading access from /usr/local/news/etc/readers.conf
Sep 11 04:39:08 twilightnode nnrpd[3222576]: Auth strategy 'localhost' does not match client. Removing.
Sep 11 04:39:08 twilightnode nnrpd[3222576]: r4-245-f5.monitoring.internet-measurement.com res <anonymous>
Sep 11 04:39:08 twilightnode nnrpd[3222576]: r4-245-f5.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Sep 11 04:39:08 twilightnode nnrpd[3222576]: r4-245-f5.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 11 04:39:08 twilightnode nnrpd[3222576]: r4-245-f5.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Sep 11 04:39:08 twilightnode nnrpd[3222576]: r4-245-f5.monitoring.internet-measurement.com no_post anonymous-read
Sep 11 06:21:52 twilightnode nnrpd[3227314]: Reading access from /usr/local/news/etc/readers.conf
Sep 11 06:21:52 twilightnode nnrpd[3227314]: Auth strategy 'localhost' does not match client. Removing.
Sep 11 06:21:52 twilightnode nnrpd[3227314]: o007.scanner.modat.io res <anonymous>
Sep 11 06:21:52 twilightnode nnrpd[3227314]: o007.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 11 06:21:52 twilightnode nnrpd[3227314]: o007.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 11 06:21:52 twilightnode nnrpd[3227314]: o007.scanner.modat.io match_user <anonymous> <anonymous>
Sep 11 06:21:52 twilightnode nnrpd[3227314]: o007.scanner.modat.io no_post anonymous-read
Sep 11 06:36:24 twilightnode nnrpd[3227932]: Reading access from /usr/local/news/etc/readers.conf
Sep 11 06:36:24 twilightnode nnrpd[3227932]: Auth strategy 'localhost' does not match client. Removing.
Sep 11 06:36:24 twilightnode nnrpd[3227932]: o030.scanner.modat.io res <anonymous>
Sep 11 06:36:24 twilightnode nnrpd[3227932]: o030.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 11 06:36:24 twilightnode nnrpd[3227932]: o030.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 11 06:36:24 twilightnode nnrpd[3227932]: o030.scanner.modat.io match_user <anonymous> <anonymous>
Sep 11 06:36:24 twilightnode nnrpd[3227932]: o030.scanner.modat.io no_post anonymous-read
Sep 11 07:25:21 twilightnode nnrpd[3238384]: Reading access from /usr/local/news/etc/readers.conf
Sep 11 07:25:21 twilightnode nnrpd[3238384]: Auth strategy 'localhost' does not match client. Removing.
Sep 11 07:25:21 twilightnode nnrpd[3238384]: 81.2.118.99 res <anonymous>
Sep 11 07:25:21 twilightnode nnrpd[3238384]: 81.2.118.99 no_match_user <anonymous> <localhost>
Sep 11 07:25:21 twilightnode nnrpd[3238384]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 11 07:25:21 twilightnode nnrpd[3238384]: 81.2.118.99 match_user <anonymous> <anonymous>
Sep 11 07:25:21 twilightnode nnrpd[3238384]: 81.2.118.99 no_post anonymous-read
Sep 11 07:25:21 twilightnode nnrpd[3238384]: 81.2.118.99 noperm ihave without permission
Sep 11 07:25:52 twilightnode nnrpd[3238386]: Reading access from /usr/local/news/etc/readers.conf
Sep 11 07:25:52 twilightnode nnrpd[3238386]: Auth strategy 'localhost' does not match client. Removing.
Sep 11 07:25:52 twilightnode nnrpd[3238386]: 81.2.118.99 res <anonymous>
Sep 11 07:25:52 twilightnode nnrpd[3238386]: 81.2.118.99 no_match_user <anonymous> <localhost>
Sep 11 07:25:52 twilightnode nnrpd[3238386]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 11 07:25:52 twilightnode nnrpd[3238386]: 81.2.118.99 match_user <anonymous> <anonymous>
Sep 11 07:25:52 twilightnode nnrpd[3238386]: 81.2.118.99 no_post anonymous-read
Sep 11 07:25:59 twilightnode nnrpd[3238386]: 81.2.118.99 noperm ihave without permission
Sep 11 07:26:25 twilightnode nnrpd[3238397]: Reading access from /usr/local/news/etc/readers.conf
Sep 11 07:26:25 twilightnode nnrpd[3238397]: Auth strategy 'localhost' does not match client. Removing.
Sep 11 07:26:25 twilightnode nnrpd[3238397]: o055.scanner.modat.io res <anonymous>
Sep 11 07:26:25 twilightnode nnrpd[3238397]: o055.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 11 07:26:25 twilightnode nnrpd[3238397]: o055.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 11 07:26:25 twilightnode nnrpd[3238397]: o055.scanner.modat.io match_user <anonymous> <anonymous>
Sep 11 07:26:25 twilightnode nnrpd[3238397]: o055.scanner.modat.io no_post anonymous-read
Sep 11 07:26:32 twilightnode nnrpd[3238398]: Reading access from /usr/local/news/etc/readers.conf
Sep 11 07:26:32 twilightnode nnrpd[3238398]: Auth strategy 'localhost' does not match client. Removing.
Sep 11 07:26:32 twilightnode nnrpd[3238398]: 81.2.118.99 res <anonymous>
Sep 11 07:26:32 twilightnode nnrpd[3238398]: 81.2.118.99 no_match_user <anonymous> <localhost>
Sep 11 07:26:32 twilightnode nnrpd[3238398]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>

Log entries by program:

Program nameLines%LinesSize%Size
inn 31824 49.6%6.4 MB 65.7%
nnrpd 26422 41.2%2.6 MB 26.5%
innd 4549 7.1%606.9 KB 6.1%
innfeed 1309 2.0%179.7 KB 1.8%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 64105 100.0%9.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 69717 36.6%
Negative hits 67231 35.3%
Do not exist 31750 16.7%
Cache misses 21987 11.5%
TOTAL: 4 190685 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.060 0.0%21 0.000 2.857 38.000
article cleanup00:00:00.236 0.0%31779 0.000 0.007 0.100
article logging00:00:00.765 0.0%31809 0.000 0.024 0.273
article parse00:00:00.979 0.0%114311 0.000 0.009 0.118
article write00:00:14.512 0.0%30716 0.236 0.472 1.852
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.653 0.0%239820 0.000 0.003 0.050
hisgrep/artcncl00:00:00.008 0.0%21 0.000 0.381 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.168 0.0%190746 0.007 0.053 0.293
history sync00:00:00.041 0.0%328 0.000 0.125 1.000
history write00:00:10.201 0.0%31757 0.178 0.321 0.899
idle23:48:35.196 99.9%206175 52.460 415.740 9680.081
nntp read00:00:03.306 0.0%201110 0.000 0.016 0.070
overview write00:00:05.665 0.0%30716 0.066 0.184 2.154
perl filter00:00:45.167 0.1%31757 0.907 1.422 7.321
python filter00:00:02.327 0.0%31757 0.000 0.073 0.520
site send00:00:01.445 0.0%61393 0.000 0.024 0.071
TOTAL: 1823:50:10.729100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.097 0.0%60529 0.000 0.002 0.073
article prepare00:00:00.020 0.0%131792 0.000 0.000 0.028
article read00:00:02.532 0.0%28543 0.000 0.089 1.200
backlog stats00:00:00.149 0.0%257817 0.000 0.001 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.800 0.0%167435 0.000 0.011 0.056
data write00:00:02.965 0.0%141891 0.000 0.021 0.094
idle23:57:58.867100.0%257817 33.208 334.652 5352.062
status file00:00:00.175 0.0%178 0.000 0.983 3.000
TOTAL: 923:58:06.605100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:10:57.313100.0%6819 31.000 96.394 5435.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.257 0.0%13669 0.000 0.019 0.333
TOTAL: 300:10:57.570100.0%----

Control commands to innd:

CommandNumber
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 146
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 167

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 6 60841 18751 42023 67 30%50:34:21
2usenet.blueworldhosting.com 717 31697 11768 19037 892 37%23:46:25
3newsfeed.endofthelinebbs.com 2 5427 276 5030 121 5%26:10:11
481.2.118.99 0 4263 77 4183 3 1%23:26:44
5news.sklaffkom.se 35 75 71 4 0 94%09:08:31
6localhost 25 0 0 0 0 0%00:00:00
7news.tebibyte.org 1 0 0 0 0 0%00:00:00
TOTAL: 7 786 102303 30943 70277 1083 30%133:06:12
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net269.8 MB49.4 KB142.8 KB270.0 MB 99%14.7 KB
2usenet.blueworldhosting.com221.5 MB251.9 KB1.1 MB222.9 MB 99%18.0 KB
3newsfeed.endofthelinebbs.com3.3 MB0.0 KB204.0 KB3.5 MB 94%9.0 KB
481.2.118.99286.3 KB0.0 KB4.6 KB290.8 KB 98%3.6 KB
5news.sklaffkom.se217.3 KB0.0 KB0.0 KB217.3 KB100%3.1 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
7news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 7495.1 MB301.2 KB1.4 MB496.8 MB 99%15.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 11 03:00:03 - 03:59:59 91 0.3% 0.03411.8 KB 0.1% 0.11
Sep 11 04:00:00 - 04:59:59 125 0.4% 0.03564.2 KB 0.1% 0.16
Sep 11 05:00:00 - 05:59:59 122 0.4% 0.03571.4 KB 0.1% 0.16
Sep 11 06:00:00 - 06:59:59 183 0.6% 0.05961.0 KB 0.2% 0.27
Sep 11 07:00:00 - 07:59:59 204 0.7% 0.06562.0 KB 0.1% 0.16
Sep 11 08:00:00 - 08:59:59 230 0.7% 0.06763.0 KB 0.2% 0.21
Sep 11 09:00:00 - 09:59:59 287 0.9% 0.081.2 MB 0.2% 0.33
Sep 11 10:00:00 - 10:59:59 312 1.0% 0.09880.5 KB 0.2% 0.24
Sep 11 11:00:00 - 11:59:59 452 1.5% 0.133.2 MB 0.6% 0.91
Sep 11 12:00:00 - 12:59:59 380 1.2% 0.113.1 MB 0.6% 0.89
Sep 11 13:00:00 - 13:59:59 884 2.9% 0.2513.4 MB 2.7% 3.82
Sep 11 14:00:00 - 14:59:59 1005 3.3% 0.2814.7 MB 3.0% 4.18
Sep 11 15:00:00 - 15:59:59 1540 5.0% 0.4325.7 MB 5.2% 7.31
Sep 11 16:00:00 - 16:59:59 6254 20.4% 1.74122.8 MB 24.8% 34.92
Sep 11 17:00:00 - 17:59:59 6561 21.4% 1.82128.9 MB 26.1% 36.66
Sep 11 18:00:00 - 18:59:59 5251 17.1% 1.46102.5 MB 20.7% 29.16
Sep 11 19:00:00 - 19:59:59 4979 16.2% 1.3864.7 MB 13.1% 18.39
Sep 11 20:00:00 - 20:59:59 293 1.0% 0.081.3 MB 0.3% 0.36
Sep 11 21:00:00 - 21:59:59 298 1.0% 0.081019.6 KB 0.2% 0.28
Sep 11 22:00:00 - 22:59:59 334 1.1% 0.091.3 MB 0.3% 0.38
Sep 11 23:00:00 - 23:59:59 321 1.0% 0.092.1 MB 0.4% 0.59
Sep 12 00:00:00 - 00:59:59 277 0.9% 0.082.3 MB 0.5% 0.67
Sep 12 01:00:00 - 01:59:59 174 0.6% 0.05778.8 KB 0.2% 0.22
Sep 12 02:00:00 - 02:59:59 164 0.5% 0.05919.1 KB 0.2% 0.26
Sep 12 03:00:00 - 03:00:03 8 0.0% 2.6712.0 KB 0.0% 4.00
TOTAL: 24:00:00 30729 100.0% 0.36494.4 MB 100.0% 5.86
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 892 322 0 18 2 0 0 0 550
2newsfeed.endofthelinebbs.com 108 4 0 0 0 0 0 0 104
3twilightnode-out.news.weretis.net 91 7 0 4 0 0 0 0 80
481.2.118.99 3 0 0 0 0 0 0 0 3
5news.sklaffkom.se 1 0 0 0 0 0 0 0 1
TOTAL: 5 1095 333 0 22 2 0 0 0 738

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.mastodon.tag.hacking 19
hispagatos.hacking.ransomware 7
fido.ru.fidonet.today 4
de.comm.abuse 3
alt.sex.pedophilia 2
alt.bbs.files-announce 1
alt.books.david-weber 1
alt.fun 1
alt.genealogy 1
alt.politics.communism 1
chmurka.statystyki 1
fido.golded 1
fido.pushkin.local 1
italia.como.discussioni 1
soc.culture.uk 1
TOTAL: 16 333

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

GroupsCount
uk.religion.christian 2
TOTAL: 1 2

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 574
Too many newsgroups 76
EMP (phn path) 37
User-issued cancel 16
Too many newsgroups (meow) 4
HTML Multipart 1
TOTAL: 6 708

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 29
news.sklaffkom.se 1
TOTAL: 2 30
TOTAL: 1 30

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 31783 4329 26255 106 0 24495 13%23:59:58
2sklaffkom.se 30851 901 2242 27547 0 0 2%23:59:52
3newsfeed.endofthelinebbs.com 41580 23 30395 41 0 5347 0%23:59:54
4weretis.net 12377 14 12050 0 0 0 0%23:56:05
5usenet.blueworldhosting.com 17062 0 13870 0 0 6 0%23:59:54
TOTAL: 5 133653 5267 84812 27694 0 29848 3%119:55:43
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org49.5 MB1.0 MB50.6 MB0.6 KB/s11.7 KB23:59:58
2sklaffkom.se1.5 MB481.7 MB483.2 MB5.7 KB/s17.4 KB23:59:52
3weretis.net317.1 KB0.0 KB317.1 KB0.0 KB/s22.7 KB23:56:05
4newsfeed.endofthelinebbs.com74.0 KB320.4 KB394.4 KB0.0 KB/s6.2 KB23:59:54
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
TOTAL: 551.4 MB483.1 MB534.5 MB1.3 KB/s16.6 KB119:55:43
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
181.2.118.991369 00.0 KB 0 0 000:08:39
2mailgate.tebibyte.org 897 00.0 KB 0 0 000:01:52
3186.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:05
4205.210.31.16 1 00.0 KB 0 0 000:00:00
594.154.43.21 1 00.0 KB 0 0 000:00:00
6cole.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
7o007.scanner.modat.io 1 00.0 KB 0 0 000:00:00
8o030.scanner.modat.io 1 00.0 KB 0 0 000:00:00
9o052.scanner.modat.io 1 00.0 KB 0 0 000:00:00
10o055.scanner.modat.io 1 00.0 KB 0 0 000:00:00
11r4-245-f5.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
12sh-chi-us-gp1-wk101d.internet-census.org 1 00.0 KB 0 0 000:00:10
13velasquez.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
TOTAL: 132277 00.0 KB 0 0 000:10:57

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved1371 00.0 KB 0 0 000:08:40
2*.tebibyte.org 897 00.0 KB 0 0 000:01:52
3*.scanner.modat.io 4 00.0 KB 0 0 000:00:02
4*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
5*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:05
6*.internet-census.org 1 00.0 KB 0 0 000:00:10
7*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 72277 00.0 KB 0 0 000:10:57

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
81.2.118.99 7.248 10.199 0.00000:08:39
mailgate.tebibyte.org 4.648 6.372 0.00000:01:52
sh-chi-us-gp1-wk101d.internet-census.org 0.005 0.009 0.00000:00:10
186.172.132.66.censys-scanner.com 0.023 0.014 0.00000:00:05
velasquez.probe.onyphe.net 0.005 0.007 0.00000:00:02
r4-245-f5.monitoring.internet-measurement.com 0.006 0.010 0.00000:00:01
cole.probe.onyphe.net 0.004 0.006 0.00000:00:00
o055.scanner.modat.io 0.004 0.012 0.00000:00:00
o030.scanner.modat.io 0.005 0.008 0.00000:00:00
o007.scanner.modat.io 0.003 0.013 0.00000:00:00
94.154.43.21 0.003 0.007 0.00000:00:00
o052.scanner.modat.io 0.005 0.006 0.00000:00:00
205.210.31.16 0.004 0.004 0.00000:00:00
TOTAL: 13 11.963 16.667 0.00000:10:57

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

SystemConn
cole.probe.onyphe.net 2
o007.scanner.modat.io 2
o030.scanner.modat.io 2
186.172.132.66.censys-scanner.com 1
TOTAL: 4 7

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

CommandCount
FEAT 2
SYST 2
#026#003#003#002c#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
EHLO www.censys.io 1
TOTAL: 5 7
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