Daily Usenet report

Sep 18 03:00:03 -- Sep 19 03:00:03

Unknown entries from news log file:

First 50 / 209 lines (23.9%)

Sep 18 03:01:10 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 18 11:32:42 twilightnode nnrpd[3727266]: Reading access from /usr/local/news/etc/readers.conf
Sep 18 11:32:42 twilightnode nnrpd[3727266]: Auth strategy 'localhost' does not match client. Removing.
Sep 18 11:32:42 twilightnode nnrpd[3727266]: 205.210.31.107 res <anonymous>
Sep 18 11:32:42 twilightnode nnrpd[3727266]: 205.210.31.107 no_match_user <anonymous> <localhost>
Sep 18 11:32:42 twilightnode nnrpd[3727266]: 205.210.31.107 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 18 11:32:42 twilightnode nnrpd[3727266]: 205.210.31.107 match_user <anonymous> <anonymous>
Sep 18 11:32:42 twilightnode nnrpd[3727266]: 205.210.31.107 no_post anonymous-read
Sep 18 12:43:54 twilightnode nnrpd[3730516]: Reading access from /usr/local/news/etc/readers.conf
Sep 18 12:43:54 twilightnode nnrpd[3730516]: Auth strategy 'localhost' does not match client. Removing.
Sep 18 12:43:54 twilightnode nnrpd[3730516]: 20.80.93.101 res <anonymous>
Sep 18 12:43:54 twilightnode nnrpd[3730516]: 20.80.93.101 no_match_user <anonymous> <localhost>
Sep 18 12:43:54 twilightnode nnrpd[3730516]: 20.80.93.101 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 18 12:43:54 twilightnode nnrpd[3730516]: 20.80.93.101 match_user <anonymous> <anonymous>
Sep 18 12:43:54 twilightnode nnrpd[3730516]: 20.80.93.101 no_post anonymous-read
Sep 18 12:44:05 twilightnode nnrpd[3730526]: Reading access from /usr/local/news/etc/readers.conf
Sep 18 12:44:05 twilightnode nnrpd[3730526]: Auth strategy 'localhost' does not match client. Removing.
Sep 18 12:44:05 twilightnode nnrpd[3730526]: 20.80.93.101 res <anonymous>
Sep 18 12:44:05 twilightnode nnrpd[3730526]: 20.80.93.101 no_match_user <anonymous> <localhost>
Sep 18 12:44:05 twilightnode nnrpd[3730526]: 20.80.93.101 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 18 12:44:05 twilightnode nnrpd[3730526]: 20.80.93.101 match_user <anonymous> <anonymous>
Sep 18 12:44:05 twilightnode nnrpd[3730526]: 20.80.93.101 no_post anonymous-read
Sep 18 13:30:58 twilightnode nnrpd[3732778]: Reading access from /usr/local/news/etc/readers.conf
Sep 18 13:30:58 twilightnode nnrpd[3732778]: Auth strategy 'localhost' does not match client. Removing.
Sep 18 13:30:58 twilightnode nnrpd[3732778]: 47.250.83.248 res <anonymous>
Sep 18 13:30:58 twilightnode nnrpd[3732778]: 47.250.83.248 no_match_user <anonymous> <localhost>
Sep 18 13:30:58 twilightnode nnrpd[3732778]: 47.250.83.248 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 18 13:30:58 twilightnode nnrpd[3732778]: 47.250.83.248 match_user <anonymous> <anonymous>
Sep 18 13:30:58 twilightnode nnrpd[3732778]: 47.250.83.248 no_post anonymous-read
Sep 18 15:09:37 twilightnode nnrpd[3737563]: Reading access from /usr/local/news/etc/readers.conf
Sep 18 15:09:37 twilightnode nnrpd[3737563]: Auth strategy 'localhost' does not match client. Removing.
Sep 18 15:09:37 twilightnode nnrpd[3737563]: 138.172.132.66.censys-scanner.com res <anonymous>
Sep 18 15:09:37 twilightnode nnrpd[3737563]: 138.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 18 15:09:37 twilightnode nnrpd[3737563]: 138.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 18 15:09:37 twilightnode nnrpd[3737563]: 138.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 18 15:09:37 twilightnode nnrpd[3737563]: 138.172.132.66.censys-scanner.com no_post anonymous-read
Sep 18 15:09:42 twilightnode nnrpd[3737565]: Reading access from /usr/local/news/etc/readers.conf
Sep 18 15:09:42 twilightnode nnrpd[3737565]: Auth strategy 'localhost' does not match client. Removing.
Sep 18 15:09:42 twilightnode nnrpd[3737565]: 138.172.132.66.censys-scanner.com res <anonymous>
Sep 18 15:09:42 twilightnode nnrpd[3737565]: 138.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 18 15:09:42 twilightnode nnrpd[3737565]: 138.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 18 15:09:42 twilightnode nnrpd[3737565]: 138.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 18 15:09:42 twilightnode nnrpd[3737565]: 138.172.132.66.censys-scanner.com no_post anonymous-read
Sep 18 15:09:46 twilightnode nnrpd[3737566]: Reading access from /usr/local/news/etc/readers.conf
Sep 18 15:09:46 twilightnode nnrpd[3737566]: Auth strategy 'localhost' does not match client. Removing.
Sep 18 15:09:46 twilightnode nnrpd[3737566]: 138.172.132.66.censys-scanner.com res <anonymous>
Sep 18 15:09:46 twilightnode nnrpd[3737566]: 138.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 18 15:09:46 twilightnode nnrpd[3737566]: 138.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 18 15:09:46 twilightnode nnrpd[3737566]: 138.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 18 15:09:46 twilightnode nnrpd[3737566]: 138.172.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 19131 72.9%3.7 MB 79.7%
innd 4595 17.5%652.7 KB 13.7%
innfeed 1265 4.8%198.1 KB 4.2%
nnrpd 1258 4.8%113.2 KB 2.4%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 26250 100.0%4.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 46750 44.3%
Negative hits 39585 37.5%
Do not exist 19063 18.1%
Cache misses 34 0.0%
TOTAL: 4 105432 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.031 0.0%18 0.000 1.722 9.000
article cleanup00:00:00.139 0.0%19089 0.000 0.007 0.105
article logging00:00:00.484 0.0%19136 0.000 0.025 0.143
article parse00:00:00.357 0.0%40259 0.000 0.009 0.056
article write00:00:12.283 0.0%17909 0.454 0.686 2.917
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.357 0.0%113397 0.000 0.003 0.102
hisgrep/artcncl00:00:00.009 0.0%18 0.000 0.500 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:07.987 0.0%105559 0.046 0.076 0.193
history sync00:00:00.031 0.0%315 0.000 0.098 1.000
history write00:00:06.950 0.0%19089 0.118 0.364 0.923
idle23:59:18.614 99.9%107981 335.559 799.757 13640.114
nntp read00:00:01.781 0.0%107106 0.000 0.017 0.071
overview write00:00:04.941 0.0%17909 0.087 0.276 2.625
perl filter00:00:22.052 0.0%19089 0.857 1.155 11.000
python filter00:00:01.346 0.0%19089 0.014 0.071 0.667
site send00:00:00.712 0.0%35784 0.000 0.020 0.143
TOTAL: 1824:00:18.074100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.034 0.0%19634 0.000 0.002 0.174
article prepare00:00:00.009 0.0%105216 0.000 0.000 0.005
article read00:00:01.700 0.0%14952 0.042 0.114 1.000
backlog stats00:00:00.098 0.0%191100 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.241 0.0%120821 0.000 0.010 0.055
data write00:00:02.350 0.0%105792 0.000 0.022 0.101
idle23:58:52.632100.0%191100 134.433 451.767 4323.590
status file00:00:00.152 0.0%169 0.000 0.899 3.000
TOTAL: 923:58:58.216100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:04:02.741100.0%1184 72.500 205.018 10209.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.062 0.0%3000 0.000 0.021 0.500
readart00:00:00.016 0.0%43 0.000 0.372 0.600
TOTAL: 400:04:02.819100.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 6 36239 11264 24943 32 31%54:51:14
2usenet.blueworldhosting.com 716 19027 6338 11643 1046 33%23:46:02
3newsfeed.endofthelinebbs.com 2 5239 276 4886 77 5%22:15:10
4news.tebibyte.org 2 3590 118 3472 0 3%23:59:08
5news.sklaffkom.se 44 107 92 15 0 85%10:59:14
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 795 64202 18088 44959 1155 28%135:50:48
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net82.2 MB0.0 KB136.0 KB82.3 MB 99%7.5 KB
2usenet.blueworldhosting.com57.6 MB0.0 KB1.6 MB59.2 MB 97%8.2 KB
3newsfeed.endofthelinebbs.com2.8 MB0.0 KB161.6 KB2.9 MB 94%8.5 KB
4news.tebibyte.org462.0 KB0.0 KB0.0 KB462.0 KB100%3.9 KB
5news.sklaffkom.se304.7 KB0.0 KB0.0 KB304.7 KB100%3.3 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 6143.3 MB0.0 KB1.9 MB145.2 MB 98%7.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 18 03:00:03 - 03:59:59 141 0.8% 0.04715.6 KB 0.5% 0.20
Sep 18 04:00:00 - 04:59:59 159 0.9% 0.04779.2 KB 0.5% 0.22
Sep 18 05:00:00 - 05:59:59 85 0.5% 0.02465.4 KB 0.3% 0.13
Sep 18 06:00:00 - 06:59:59 107 0.6% 0.03418.7 KB 0.3% 0.12
Sep 18 07:00:00 - 07:59:59 178 1.0% 0.05615.7 KB 0.4% 0.17
Sep 18 08:00:00 - 08:59:59 248 1.4% 0.07818.4 KB 0.6% 0.23
Sep 18 09:00:00 - 09:59:59 337 1.9% 0.091.2 MB 0.9% 0.35
Sep 18 10:00:00 - 10:59:59 354 2.0% 0.101.1 MB 0.8% 0.31
Sep 18 11:00:00 - 11:59:59 1416 7.9% 0.3912.0 MB 8.4% 3.41
Sep 18 12:00:00 - 12:59:59 1587 8.9% 0.4414.2 MB 10.0% 4.04
Sep 18 13:00:00 - 13:59:59 1515 8.5% 0.4213.9 MB 9.8% 3.96
Sep 18 14:00:00 - 14:59:59 1465 8.2% 0.4113.2 MB 9.2% 3.75
Sep 18 15:00:00 - 15:59:59 1318 7.4% 0.3711.2 MB 7.8% 3.18
Sep 18 16:00:00 - 16:59:59 1559 8.7% 0.4313.8 MB 9.7% 3.94
Sep 18 17:00:00 - 17:59:59 1589 8.9% 0.4414.1 MB 9.9% 4.02
Sep 18 18:00:00 - 18:59:59 1683 9.4% 0.4714.7 MB 10.3% 4.17
Sep 18 19:00:00 - 19:59:59 1510 8.4% 0.4213.6 MB 9.6% 3.88
Sep 18 20:00:00 - 20:59:59 1185 6.6% 0.3310.0 MB 7.0% 2.85
Sep 18 21:00:00 - 21:59:59 297 1.7% 0.081.0 MB 0.7% 0.29
Sep 18 22:00:00 - 22:59:59 279 1.6% 0.08964.5 KB 0.7% 0.27
Sep 18 23:00:00 - 23:59:59 247 1.4% 0.07984.7 KB 0.7% 0.27
Sep 19 00:00:00 - 00:59:59 295 1.6% 0.081.1 MB 0.7% 0.30
Sep 19 01:00:00 - 01:59:59 176 1.0% 0.05718.8 KB 0.5% 0.20
Sep 19 02:00:00 - 02:59:59 168 0.9% 0.051.0 MB 0.7% 0.28
Sep 19 03:00:00 - 03:00:03 7 0.0% 2.336.4 KB 0.0% 2.14
TOTAL: 24:00:00 17905 100.0% 0.21142.5 MB 100.0% 1.69
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1046 355 0 0 0 0 0 0 691
2newsfeed.endofthelinebbs.com 102 13 0 0 0 0 0 0 89
3twilightnode-out.news.weretis.net 77 9 0 0 0 0 0 0 68
4news.tebibyte.org 1 0 0 0 0 0 0 0 1
TOTAL: 4 1226 377 0 0 0 0 0 0 849

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 38
fido.ru.qico 7
hispagatos.mastodon.tag.hacking 7
pl.listserv.chomor-l 7
fido.ru.golded 6
fido.ru.home 6
alt.test.9 3
newsgrouper.support 3
fido.enet.sysop 2
fido.ru.ftn.develop 2
alt.bbs.files-announce 1
alt.sports.football.pro.sf-49ers 1
chmurka.statystyki 1
dk.kultur.tegneserier 1
fido.8912.local 1
fido.ru.fidonet.today 1
fido7.ru.talk.english 1
free.java.prishtine 1
TOTAL: 19 377

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 718
Too many newsgroups 69
User-issued cancel 8
HTML Multipart 6
Too many newsgroups (meow) 1
TOTAL: 5 802

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 46
newsfeed.endofthelinebbs.com 1
TOTAL: 2 47
TOTAL: 1 47

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 21256 11381 6389 107 0 48 53%23:59:56
2sklaffkom.se 18062 1035 3097 13740 0 0 5%23:59:56
3newsfeed.endofthelinebbs.com 24440 22 17556 29 0 1678 0%23:59:46
4weretis.net 6978 7 6769 0 0 0 0%23:56:41
5usenet.blueworldhosting.com 11959 0 8658 0 0 31 0%23:59:50
TOTAL: 5 82695 12445 42469 13876 0 1757 15%119:56:09
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org103.8 MB932.2 KB104.7 MB1.2 KB/s9.3 KB23:59:56
2sklaffkom.se1.7 MB128.5 MB130.2 MB1.5 KB/s9.0 KB23:59:56
3newsfeed.endofthelinebbs.com69.4 KB93.7 KB163.1 KB0.0 KB/s3.2 KB23:59:46
4weretis.net21.4 KB0.0 KB21.4 KB0.0 KB/s3.1 KB23:56:41
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
TOTAL: 5105.6 MB129.5 MB235.1 MB0.6 KB/s9.1 KB119:56:09
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 18 43202.0 KB 17 0 000:03:41
TOTAL: 1 18 43202.0 KB 17 0 000:03:41

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 22 43202.0 KB 17 0 000:03:55
2*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3*.infrawat.ch 2 00.0 KB 0 0 000:00:00
4*.prod.binaryedge.ninja 1 00.0 KB 0 0 000:00:02
5*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 5 29 43202.0 KB 17 0 000:04:02

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 0.219 0.644 0.00000:03:41
20.80.93.101 0.011 0.021 0.00000:00:10
138.172.132.66.censys-scanner.com 0.036 0.024 0.00000:00:04
47.250.83.248 0.005 0.009 0.00000:00:03
us-central-45.barium.li.prod.binaryedge.ninja 0.005 0.010 0.00000:00:02
o037.scanner.modat.io 0.001 0.016 0.00000:00:00
205.210.31.107 0.005 0.010 0.00000:00:00
69-5-169-11.infrawat.ch 0.003 0.006 0.00000:00:00
37-10-113-214.infrawat.ch 0.004 0.011 0.00000:00:00
TOTAL: 9 0.289 0.751 0.00000:04:02

Curious NNRP explorers [Top 20]:

SystemConn
138.172.132.66.censys-scanner.com 3
20.80.93.101 2
205.210.31.107 1
37-10-113-214.infrawat.ch 1
47.250.83.248 1
69-5-169-11.infrawat.ch 1
o037.scanner.modat.io 1
us-central-45.barium.li.prod.binaryedge.ninja 1
TOTAL: 8 11

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

SystemConn
47.250.83.248 3
138.172.132.66.censys-scanner.com 1
20.80.93.101 1
TOTAL: 3 5

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

CommandCount
#023#001#023#002#023#003#001 1
#026#003#001 1
?#024 1
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 5 5

NNRP client timeouts [Top 20]:

SystemConnPeer
138.172.132.66.censys-scanner.com 1 2
47.250.83.248 1 1
69-5-169-11.infrawat.ch 1 1
TOTAL: 3 3 6

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

HierarchyCountPct
1it 13 30.2%
2cn 7 16.3%
3rec 7 16.3%
4alt 6 14.0%
5junk 5 11.6%
6talk 2 4.7%
7linux 1 2.3%
8sci 1 2.3%
9soc 1 2.3%
TOTAL: 9 43100.0%

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

NewsgroupCount
1it.tlc.cellulari.android 11
2cn.comp 7
3junk 5
4rec.food.cooking 4
5alt.sports.football.pro.sd-chargers 2
6it.hobby.elettronica 2
7talk.politics.misc 2
8alt.home.repair 1
9alt.obituaries 1
10alt.religion.druid 1
11alt.russian.z1 1
12linux.debian.ports.x86-64 1
13rec.arts.drwho 1
14rec.arts.sf.tv 1
15rec.sport.tennis 1
16sci.geo.meteorology 1
17soc.culture.israel 1
TOTAL: 17 43