Daily Usenet report

Sep 13 03:00:04 -- Sep 14 03:00:04

Unknown entries from news log file:

First 50 / 76 lines (65.8%)

Sep 13 03:01:08 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 13 04:29:06 twilightnode nnrpd[3366069]: Reading access from /usr/local/news/etc/readers.conf
Sep 13 04:29:06 twilightnode nnrpd[3366069]: Auth strategy 'localhost' does not match client. Removing.
Sep 13 04:29:06 twilightnode nnrpd[3366069]: 164.186.132.66.censys-scanner.com res <anonymous>
Sep 13 04:29:06 twilightnode nnrpd[3366069]: 164.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 13 04:29:06 twilightnode nnrpd[3366069]: 164.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 13 04:29:06 twilightnode nnrpd[3366069]: 164.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 13 04:29:06 twilightnode nnrpd[3366069]: 164.186.132.66.censys-scanner.com no_post anonymous-read
Sep 13 04:29:14 twilightnode nnrpd[3366070]: Reading access from /usr/local/news/etc/readers.conf
Sep 13 04:29:14 twilightnode nnrpd[3366070]: Auth strategy 'localhost' does not match client. Removing.
Sep 13 04:29:14 twilightnode nnrpd[3366070]: 164.186.132.66.censys-scanner.com res <anonymous>
Sep 13 04:29:14 twilightnode nnrpd[3366070]: 164.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 13 04:29:14 twilightnode nnrpd[3366070]: 164.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 13 04:29:14 twilightnode nnrpd[3366070]: 164.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 13 04:29:14 twilightnode nnrpd[3366070]: 164.186.132.66.censys-scanner.com no_post anonymous-read
Sep 13 04:29:18 twilightnode nnrpd[3366071]: Reading access from /usr/local/news/etc/readers.conf
Sep 13 04:29:18 twilightnode nnrpd[3366071]: Auth strategy 'localhost' does not match client. Removing.
Sep 13 04:29:18 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com res <anonymous>
Sep 13 04:29:18 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 13 04:29:18 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 13 04:29:18 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 13 04:29:18 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com no_post anonymous-read
Sep 13 04:29:19 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com res <anonymous>
Sep 13 04:29:19 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 13 04:29:19 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 13 04:29:19 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 13 04:29:19 twilightnode nnrpd[3366071]: 164.186.132.66.censys-scanner.com no_post anonymous-read
Sep 13 05:44:20 twilightnode nnrpd[3369617]: Reading access from /usr/local/news/etc/readers.conf
Sep 13 05:44:20 twilightnode nnrpd[3369617]: Auth strategy 'localhost' does not match client. Removing.
Sep 13 05:44:20 twilightnode nnrpd[3369617]: o050.scanner.modat.io res <anonymous>
Sep 13 05:44:20 twilightnode nnrpd[3369617]: o050.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 13 05:44:20 twilightnode nnrpd[3369617]: o050.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 13 05:44:20 twilightnode nnrpd[3369617]: o050.scanner.modat.io match_user <anonymous> <anonymous>
Sep 13 05:44:20 twilightnode nnrpd[3369617]: o050.scanner.modat.io no_post anonymous-read
Sep 13 05:56:33 twilightnode nnrpd[3370175]: Reading access from /usr/local/news/etc/readers.conf
Sep 13 05:56:33 twilightnode nnrpd[3370175]: Auth strategy 'localhost' does not match client. Removing.
Sep 13 05:56:33 twilightnode nnrpd[3370175]: o053.scanner.modat.io res <anonymous>
Sep 13 05:56:33 twilightnode nnrpd[3370175]: o053.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 13 05:56:33 twilightnode nnrpd[3370175]: o053.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 13 05:56:33 twilightnode nnrpd[3370175]: o053.scanner.modat.io match_user <anonymous> <anonymous>
Sep 13 05:56:33 twilightnode nnrpd[3370175]: o053.scanner.modat.io no_post anonymous-read
Sep 13 11:28:44 twilightnode nnrpd[3385732]: Reading access from /usr/local/news/etc/readers.conf
Sep 13 11:28:44 twilightnode nnrpd[3385732]: Auth strategy 'localhost' does not match client. Removing.
Sep 13 11:28:44 twilightnode nnrpd[3385732]: 37-10-113-218.infrawat.ch res <anonymous>
Sep 13 11:28:44 twilightnode nnrpd[3385732]: 37-10-113-218.infrawat.ch no_match_user <anonymous> <localhost>
Sep 13 11:28:44 twilightnode nnrpd[3385732]: 37-10-113-218.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 13 11:28:44 twilightnode nnrpd[3385732]: 37-10-113-218.infrawat.ch match_user <anonymous> <anonymous>
Sep 13 11:28:44 twilightnode nnrpd[3385732]: 37-10-113-218.infrawat.ch no_post anonymous-read
Sep 13 11:28:47 twilightnode nnrpd[3385734]: Reading access from /usr/local/news/etc/readers.conf
Sep 13 11:28:47 twilightnode nnrpd[3385734]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 6987 53.9%1.2 MB 59.5%
innd 4544 35.1%643.1 KB 30.5%
innfeed 1305 10.1%198.8 KB 9.4%
nnrpd 118 0.9%12.8 KB 0.6%
TOTAL: 4 12954 100.0%2.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19514 53.4%
Negative hits 10106 27.6%
Do not exist 6919 18.9%
Cache misses 14 0.0%
TOTAL: 4 36553 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.048 0.0%27 0.000 1.778 13.000
article cleanup00:00:00.053 0.0%6944 0.000 0.008 0.050
article logging00:00:00.205 0.0%6989 0.000 0.029 0.156
article parse00:00:00.131 0.0%10165 0.000 0.013 0.071
article write00:00:06.083 0.0%5838 0.652 1.042 1.733
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.170 0.0%39829 0.000 0.004 0.041
hisgrep/artcncl00:00:00.016 0.0%27 0.000 0.593 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.778 0.0%36681 0.059 0.103 0.277
history sync00:00:00.032 0.0%330 0.000 0.097 1.000
history write00:00:02.791 0.0%6944 0.125 0.402 0.789
idle23:59:58.198100.0%40834 385.848 2115.840 6317.989
nntp read00:00:00.761 0.0%39834 0.000 0.019 0.056
overview write00:00:04.262 0.0%5838 0.245 0.730 3.294
perl filter00:00:12.161 0.0%6944 0.986 1.751 7.500
python filter00:00:00.845 0.0%6944 0.000 0.122 0.636
site send00:00:00.279 0.0%11646 0.000 0.024 0.100
TOTAL: 1824:00:29.813100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.025 0.0%6475 0.000 0.004 0.095
article prepare00:00:00.004 0.0%24884 0.000 0.000 0.006
article read00:00:00.955 0.0%3959 0.000 0.241 1.800
backlog stats00:00:00.044 0.0%79608 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.531 0.0%44085 0.000 0.012 0.043
data write00:00:00.920 0.0%38263 0.000 0.024 0.064
idle23:58:30.217100.0%79608 326.271 1084.190 3111.332
status file00:00:00.160 0.0%184 0.000 0.870 3.000
TOTAL: 923:58:32.856100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:09.263100.0%15 67.000 617.533 1976.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%86 0.000 0.012 0.333
TOTAL: 300:00:09.264100.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 11610 4385 7139 86 37%48:38:58
2usenet.blueworldhosting.com 718 6802 839 5024 939 12%23:45:01
3newsfeed.endofthelinebbs.com 2 8491 249 8076 166 2%39:14:45
4news.tebibyte.org 2 2129 220 1909 0 10%15:16:34
5news.sklaffkom.se 42 89 64 25 0 71%09:36:10
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 795 29121 5757 22173 1191 19%136:31:28
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.9 MB0.0 KB205.9 KB15.1 MB 98%3.5 KB
2newsfeed.endofthelinebbs.com8.7 MB0.0 KB248.5 KB9.0 MB 97%22.1 KB
3usenet.blueworldhosting.com3.1 MB0.0 KB1.4 MB4.5 MB 68%2.6 KB
4news.tebibyte.org1014.4 KB0.0 KB0.0 KB1014.4 KB100%4.6 KB
5news.sklaffkom.se212.7 KB0.0 KB0.0 KB212.7 KB100%3.3 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 627.9 MB0.0 KB1.9 MB29.8 MB 93%4.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 13 03:00:04 - 03:59:59 111 1.9% 0.03486.4 KB 1.9% 0.14
Sep 13 04:00:00 - 04:59:59 129 2.2% 0.041.3 MB 5.1% 0.37
Sep 13 05:00:00 - 05:59:59 127 2.2% 0.04732.1 KB 2.8% 0.20
Sep 13 06:00:00 - 06:59:59 108 1.9% 0.03502.8 KB 1.9% 0.14
Sep 13 07:00:00 - 07:59:59 175 3.0% 0.05714.6 KB 2.8% 0.20
Sep 13 08:00:00 - 08:59:59 165 2.8% 0.05792.8 KB 3.1% 0.22
Sep 13 09:00:00 - 09:59:59 255 4.4% 0.07708.5 KB 2.7% 0.20
Sep 13 10:00:00 - 10:59:59 363 6.2% 0.105.8 MB 22.9% 1.64
Sep 13 11:00:00 - 11:59:59 352 6.0% 0.10865.0 KB 3.3% 0.24
Sep 13 12:00:00 - 12:59:59 358 6.1% 0.101017.6 KB 3.9% 0.28
Sep 13 13:00:00 - 13:59:59 298 5.1% 0.08871.6 KB 3.4% 0.24
Sep 13 14:00:00 - 14:59:59 297 5.1% 0.08747.9 KB 2.9% 0.21
Sep 13 15:00:00 - 15:59:59 297 5.1% 0.08878.2 KB 3.4% 0.24
Sep 13 16:00:00 - 16:59:59 330 5.7% 0.091.1 MB 4.2% 0.30
Sep 13 17:00:00 - 17:59:59 317 5.4% 0.091.0 MB 4.0% 0.29
Sep 13 18:00:00 - 18:59:59 287 4.9% 0.08977.1 KB 3.8% 0.27
Sep 13 19:00:00 - 19:59:59 289 5.0% 0.08841.6 KB 3.3% 0.23
Sep 13 20:00:00 - 20:59:59 365 6.3% 0.101.5 MB 5.8% 0.41
Sep 13 21:00:00 - 21:59:59 235 4.0% 0.07930.1 KB 3.6% 0.26
Sep 13 22:00:00 - 22:59:59 236 4.0% 0.07964.7 KB 3.7% 0.27
Sep 13 23:00:00 - 23:59:59 254 4.4% 0.071017.5 KB 3.9% 0.28
Sep 14 00:00:00 - 00:59:59 186 3.2% 0.05640.9 KB 2.5% 0.18
Sep 14 01:00:00 - 01:59:59 139 2.4% 0.04581.2 KB 2.2% 0.16
Sep 14 02:00:00 - 02:59:59 153 2.6% 0.04737.6 KB 2.8% 0.20
Sep 14 03:00:00 - 03:00:04 11 0.2% 2.7513.4 KB 0.1% 3.34
TOTAL: 24:00:00 5837 100.0% 0.0725.3 MB 100.0% 0.30
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 939 320 0 0 0 0 0 0 619
2twilightnode-out.news.weretis.net 125 6 0 0 0 0 0 0 119
3newsfeed.endofthelinebbs.com 85 10 0 0 0 0 0 0 75
4news.tebibyte.org 1 0 0 0 0 0 0 0 1
TOTAL: 4 1150 336 0 0 0 0 0 0 814

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
amiga.test 8
hispagatos.hacking.ransomware 7
hispagatos.mastodon.tag.hacking 6
fido.golded 3
local.general 3
alt.asshole.binky 2
alt.sex.pedophilia 2
fido.8912.local 2
fido.ru.golded 2
kraft.politics 2
pl.news.czytniki 2
alt.bbs.files-announce 1
alt.internet.wireless 1
alt.sports.football.pro.atl-falcons 1
chmurka.statystyki 1
fido.ru.fidonet.today 1
fido.su.tormoz 1
kraft.os.linux.android 1
pl.listserv.chomor-l 1
TOTAL: 21 336

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 658
Too many newsgroups 85
User-issued cancel 14
HTML Multipart 12
TOTAL: 4 769

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 44
news.tebibyte.org 1
TOTAL: 2 45
TOTAL: 1 45

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7522 2395 3333 79 0 12 31%23:59:56
2sklaffkom.se 5881 1024 2141 2645 0 483 17%23:59:56
3newsfeed.endofthelinebbs.com 10605 27 5544 52 0 192 0%23:59:50
4weretis.net 1558 7 1473 0 0 0 0%23:40:56
5usenet.blueworldhosting.com 7921 6 4587 0 0 4 0%23:58:37
TOTAL: 5 33487 3459 17078 2776 0 691 10%119:39:15
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.1 MB1.2 MB7.3 MB0.1 KB/s3.0 KB23:59:56
2sklaffkom.se1.6 MB12.4 MB14.0 MB0.2 KB/s3.9 KB23:59:56
3newsfeed.endofthelinebbs.com71.9 KB170.1 KB242.0 KB0.0 KB/s3.1 KB23:59:50
4weretis.net70.0 KB0.0 KB70.0 KB0.0 KB/s10.0 KB23:40:56
5usenet.blueworldhosting.com16.8 KB0.0 KB16.8 KB0.0 KB/s2.8 KB23:58:37
TOTAL: 57.9 MB13.7 MB21.6 MB0.1 KB/s3.6 KB119:39:15
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1164.186.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:03
2205.210.31.60 1 00.0 KB 0 0 000:00:00
337-10-113-218.infrawat.ch 1 00.0 KB 0 0 000:00:00
469-5-169-102.infrawat.ch 1 00.0 KB 0 0 000:00:00
5o050.scanner.modat.io 1 00.0 KB 0 0 000:00:00
6o053.scanner.modat.io 1 00.0 KB 0 0 000:00:00
7r3-158-9e.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
8r3-193-c1.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 8 10 00.0 KB 0 0 000:00:09

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:03
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
3*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
4*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
5unresolved 1 00.0 KB 0 0 000:00:00
TOTAL: 5 10 00.0 KB 0 0 000:00:09

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
164.186.132.66.censys-scanner.com 0.038 0.032 0.00000:00:03
r3-193-c1.monitoring.internet-measurement.com 0.006 0.012 0.00000:00:01
r3-158-9e.monitoring.internet-measurement.com 0.003 0.015 0.00000:00:01
o050.scanner.modat.io 0.005 0.025 0.00000:00:00
205.210.31.60 0.004 0.010 0.00000:00:00
69-5-169-102.infrawat.ch 0.005 0.006 0.00000:00:00
o053.scanner.modat.io 0.007 0.011 0.00000:00:00
37-10-113-218.infrawat.ch 0.006 0.011 0.00000:00:00
TOTAL: 8 0.074 0.122 0.00000:00:09

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

SystemConn
164.186.132.66.censys-scanner.com 1
TOTAL: 1 1

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

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
164.186.132.66.censys-scanner.com 1 2
69-5-169-102.infrawat.ch 1 1
TOTAL: 2 2 5