Daily Usenet report

Sep 2 03:00:04 -- Sep 3 03:00:03

Unknown entries from news log file:

First 50 / 95 lines (52.6%)

Sep 2 03:01:01 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 2 04:08:09 twilightnode nnrpd[2604438]: Reading access from /usr/local/news/etc/readers.conf
Sep 2 04:08:09 twilightnode nnrpd[2604438]: Auth strategy 'localhost' does not match client. Removing.
Sep 2 04:08:09 twilightnode nnrpd[2604438]: r5-15-f.monitoring.internet-measurement.com res <anonymous>
Sep 2 04:08:09 twilightnode nnrpd[2604438]: r5-15-f.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Sep 2 04:08:09 twilightnode nnrpd[2604438]: r5-15-f.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 2 04:08:09 twilightnode nnrpd[2604438]: r5-15-f.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Sep 2 04:08:09 twilightnode nnrpd[2604438]: r5-15-f.monitoring.internet-measurement.com no_post anonymous-read
Sep 2 06:01:13 twilightnode nnrpd[2609194]: Reading access from /usr/local/news/etc/readers.conf
Sep 2 06:01:13 twilightnode nnrpd[2609194]: Auth strategy 'localhost' does not match client. Removing.
Sep 2 06:01:13 twilightnode nnrpd[2609194]: r5-144-90.monitoring.internet-measurement.com res <anonymous>
Sep 2 06:01:13 twilightnode nnrpd[2609194]: r5-144-90.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Sep 2 06:01:13 twilightnode nnrpd[2609194]: r5-144-90.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 2 06:01:13 twilightnode nnrpd[2609194]: r5-144-90.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Sep 2 06:01:13 twilightnode nnrpd[2609194]: r5-144-90.monitoring.internet-measurement.com no_post anonymous-read
Sep 2 11:21:05 twilightnode nnrpd[2624776]: Reading access from /usr/local/news/etc/readers.conf
Sep 2 11:21:05 twilightnode nnrpd[2624776]: Auth strategy 'localhost' does not match client. Removing.
Sep 2 11:21:05 twilightnode nnrpd[2624776]: maisy.probe.onyphe.net res <anonymous>
Sep 2 11:21:05 twilightnode nnrpd[2624776]: maisy.probe.onyphe.net no_match_user <anonymous> <localhost>
Sep 2 11:21:05 twilightnode nnrpd[2624776]: maisy.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 2 11:21:05 twilightnode nnrpd[2624776]: maisy.probe.onyphe.net match_user <anonymous> <anonymous>
Sep 2 11:21:05 twilightnode nnrpd[2624776]: maisy.probe.onyphe.net no_post anonymous-read
Sep 2 11:21:07 twilightnode nnrpd[2624777]: Reading access from /usr/local/news/etc/readers.conf
Sep 2 11:21:07 twilightnode nnrpd[2624777]: Auth strategy 'localhost' does not match client. Removing.
Sep 2 11:21:07 twilightnode nnrpd[2624777]: giovanni.probe.onyphe.net res <anonymous>
Sep 2 11:21:07 twilightnode nnrpd[2624777]: giovanni.probe.onyphe.net no_match_user <anonymous> <localhost>
Sep 2 11:21:07 twilightnode nnrpd[2624777]: giovanni.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 2 11:21:07 twilightnode nnrpd[2624777]: giovanni.probe.onyphe.net match_user <anonymous> <anonymous>
Sep 2 11:21:07 twilightnode nnrpd[2624777]: giovanni.probe.onyphe.net no_post anonymous-read
Sep 2 11:49:21 twilightnode nnrpd[2625867]: Reading access from /usr/local/news/etc/readers.conf
Sep 2 11:49:21 twilightnode nnrpd[2625867]: Auth strategy 'localhost' does not match client. Removing.
Sep 2 11:49:21 twilightnode nnrpd[2625867]: 40.90.253.212 res <anonymous>
Sep 2 11:49:21 twilightnode nnrpd[2625867]: 40.90.253.212 no_match_user <anonymous> <localhost>
Sep 2 11:49:21 twilightnode nnrpd[2625867]: 40.90.253.212 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 2 11:49:21 twilightnode nnrpd[2625867]: 40.90.253.212 match_user <anonymous> <anonymous>
Sep 2 11:49:21 twilightnode nnrpd[2625867]: 40.90.253.212 no_post anonymous-read
Sep 2 11:49:22 twilightnode nnrpd[2625868]: Reading access from /usr/local/news/etc/readers.conf
Sep 2 11:49:22 twilightnode nnrpd[2625868]: Auth strategy 'localhost' does not match client. Removing.
Sep 2 11:49:22 twilightnode nnrpd[2625868]: 40.90.253.212 res <anonymous>
Sep 2 11:49:22 twilightnode nnrpd[2625868]: 40.90.253.212 no_match_user <anonymous> <localhost>
Sep 2 11:49:22 twilightnode nnrpd[2625868]: 40.90.253.212 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 2 11:49:22 twilightnode nnrpd[2625868]: 40.90.253.212 match_user <anonymous> <anonymous>
Sep 2 11:49:22 twilightnode nnrpd[2625868]: 40.90.253.212 no_post anonymous-read
Sep 2 13:58:47 twilightnode nnrpd[2631269]: Reading access from /usr/local/news/etc/readers.conf
Sep 2 13:58:47 twilightnode nnrpd[2631269]: Auth strategy 'localhost' does not match client. Removing.
Sep 2 13:58:47 twilightnode nnrpd[2631269]: 154-16-44-30.infrawat.ch res <anonymous>
Sep 2 13:58:47 twilightnode nnrpd[2631269]: 154-16-44-30.infrawat.ch no_match_user <anonymous> <localhost>
Sep 2 13:58:47 twilightnode nnrpd[2631269]: 154-16-44-30.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 2 13:58:47 twilightnode nnrpd[2631269]: 154-16-44-30.infrawat.ch match_user <anonymous> <anonymous>
Sep 2 13:58:47 twilightnode nnrpd[2631269]: 154-16-44-30.infrawat.ch no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 7921 56.0%1.4 MB 61.5%
innd 4804 34.0%680.6 KB 29.9%
innfeed 1271 9.0%180.4 KB 7.9%
nnrpd 146 1.0%15.6 KB 0.7%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 14144 100.0%2.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 21530 52.1%
Negative hits 11476 27.8%
Do not exist 7853 19.0%
Cache misses 433 1.0%
TOTAL: 4 41292 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.056 0.0%28 0.000 2.000 11.000
article cleanup00:00:00.060 0.0%7880 0.000 0.008 0.118
article logging00:00:00.249 0.0%7918 0.000 0.031 0.179
article parse00:00:00.152 0.0%10622 0.000 0.014 0.067
article write00:00:06.656 0.0%6495 0.545 1.025 3.583
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.206 0.0%42531 0.000 0.005 0.046
hisgrep/artcncl00:00:00.014 0.0%28 0.000 0.500 1.091
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.281 0.0%41434 0.043 0.103 0.211
history sync00:00:00.057 0.0%410 0.000 0.139 2.000
history write00:00:03.431 0.0%7880 0.083 0.435 1.000
idle24:00:25.386 99.9%43345 818.332 1993.895 9231.508
nntp read00:00:00.829 0.0%42304 0.003 0.020 0.067
overview write00:00:04.197 0.0%6495 0.303 0.646 2.929
perl filter00:00:12.652 0.0%7880 0.746 1.606 8.176
python filter00:00:00.780 0.0%7880 0.000 0.099 0.464
site send00:00:00.294 0.0%12938 0.000 0.023 0.093
TOTAL: 1824:00:59.300100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.017 0.0%7480 0.000 0.002 0.051
article prepare00:00:00.007 0.0%26708 0.000 0.000 0.018
article read00:00:00.930 0.0%4427 0.000 0.210 1.300
backlog stats00:00:00.039 0.0%84958 0.000 0.000 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.641 0.0%47446 0.000 0.014 0.074
data write00:00:01.045 0.0%41146 0.000 0.025 0.054
idle23:49:54.631100.0%84958 360.544 1009.848 3734.404
status file00:00:00.112 0.0%168 0.000 0.667 5.000
TOTAL: 923:49:57.422100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:18.602100.0%19 2.500 979.053 3709.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%100 0.000 0.010 0.200
TOTAL: 300:00:18.603100.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 3 10008 4915 5029 64 49%41:04:14
2usenet.blueworldhosting.com 715 7604 1019 5568 1017 13%23:48:05
3newsfeed.endofthelinebbs.com 1 6154 288 5738 128 4%26:13:55
4news.tebibyte.org 4 6515 196 6172 147 3%34:24:37
5news.sklaffkom.se 30 46 43 3 0 93%06:16:45
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 778 30327 6461 22510 1356 21%131:47:36
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.5 MB0.0 KB205.5 KB15.7 MB 98%3.2 KB
2usenet.blueworldhosting.com5.4 MB0.0 KB1.3 MB6.8 MB 80%3.4 KB
3newsfeed.endofthelinebbs.com3.0 MB0.0 KB227.3 KB3.3 MB 93%8.1 KB
4news.tebibyte.org681.2 KB0.0 KB459.9 KB1.1 MB 59%3.3 KB
5news.sklaffkom.se103.9 KB0.0 KB0.0 KB103.9 KB100%2.4 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 624.8 MB0.0 KB2.2 MB26.9 MB 91%3.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 2 03:00:04 - 03:59:59 99 1.5% 0.03467.6 KB 1.9% 0.13
Sep 2 04:00:00 - 04:59:59 109 1.7% 0.03513.9 KB 2.1% 0.14
Sep 2 05:00:00 - 05:59:59 96 1.5% 0.03460.0 KB 1.8% 0.13
Sep 2 06:00:00 - 06:59:59 150 2.3% 0.04619.4 KB 2.5% 0.17
Sep 2 07:00:00 - 07:59:59 174 2.7% 0.05672.7 KB 2.7% 0.19
Sep 2 08:00:00 - 08:59:59 225 3.5% 0.06648.1 KB 2.6% 0.18
Sep 2 09:00:00 - 09:59:59 295 4.5% 0.08969.3 KB 3.9% 0.27
Sep 2 10:00:00 - 10:59:59 353 5.4% 0.101.1 MB 4.5% 0.32
Sep 2 11:00:00 - 11:59:59 424 6.5% 0.121.4 MB 5.7% 0.40
Sep 2 12:00:00 - 12:59:59 324 5.0% 0.09962.7 KB 3.8% 0.27
Sep 2 13:00:00 - 13:59:59 330 5.1% 0.091.2 MB 5.1% 0.35
Sep 2 14:00:00 - 14:59:59 360 5.5% 0.101.2 MB 5.0% 0.35
Sep 2 15:00:00 - 15:59:59 347 5.3% 0.101.3 MB 5.2% 0.36
Sep 2 16:00:00 - 16:59:59 330 5.1% 0.091001.1 KB 4.0% 0.28
Sep 2 17:00:00 - 17:59:59 352 5.4% 0.101.4 MB 5.8% 0.40
Sep 2 18:00:00 - 18:59:59 281 4.3% 0.081.1 MB 4.6% 0.32
Sep 2 19:00:00 - 19:59:59 361 5.6% 0.101.3 MB 5.3% 0.37
Sep 2 20:00:00 - 20:59:59 355 5.5% 0.101.3 MB 5.5% 0.38
Sep 2 21:00:00 - 21:59:59 321 4.9% 0.091.2 MB 5.0% 0.35
Sep 2 22:00:00 - 22:59:59 297 4.6% 0.081.3 MB 5.2% 0.36
Sep 2 23:00:00 - 23:59:59 250 3.8% 0.071.0 MB 4.2% 0.29
Sep 3 00:00:00 - 00:59:59 246 3.8% 0.071.6 MB 6.4% 0.45
Sep 3 01:00:00 - 01:59:59 229 3.5% 0.061000.6 KB 4.0% 0.28
Sep 3 02:00:00 - 02:59:59 179 2.8% 0.05791.7 KB 3.2% 0.22
Sep 3 03:00:00 - 03:00:03 12 0.2% 4.0019.3 KB 0.1% 6.42
TOTAL: 23:59:59 6499 100.0% 0.0824.4 MB 100.0% 0.29
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1017 324 0 0 0 0 0 0 693
2news.tebibyte.org 145 0 0 0 0 0 0 0 145
3twilightnode-out.news.weretis.net 138 3 0 0 0 0 0 0 135
4newsfeed.endofthelinebbs.com 122 7 0 0 0 0 0 0 115
TOTAL: 4 1422 334 0 0 0 0 0 0 1088

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 15
hispagatos.mastodon.tag.hacking 5
kraft.politics 5
local.general 5
linux.altlinux.sisyphus 3
alt.sex.pedophilia 2
de.alt.technik.misc 2
alt.2600.madrid 1
alt.bbs.files-announce 1
chmurka.misc 1
chmurka.statystyki 1
de.comm.software.mailserver 1
fido.ru.space 1
fido.su.pol 1
fidonet.fn_sysop 1
hispagatos.mastodon.tag.hackers 1
TOTAL: 17 334

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 714
EMP (phn path) 220
Too many newsgroups 94
User-issued cancel 15
HTML Multipart 7
TOTAL: 5 1050

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 38
TOTAL: 1 38
TOTAL: 1 38

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8175 2237 4115 77 18 856 27%23:59:56
2sklaffkom.se 6589 1133 2141 3150 23 1 17%23:59:56
3newsfeed.endofthelinebbs.com 11650 59 6110 15 3 206 0%23:59:56
4usenet.blueworldhosting.com 8622 8 4965 0 0 9 0%23:59:56
5weretis.net 1504 7 1466 1 0 0 0%23:56:15
TOTAL: 5 36540 3444 18797 3243 44 1072 9%119:55:59
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.2 MB654.6 KB6.8 MB0.1 KB/s3.0 KB23:59:56
2sklaffkom.se1.9 MB14.9 MB16.7 MB0.2 KB/s4.0 KB23:59:56
3newsfeed.endofthelinebbs.com158.2 KB37.9 KB196.2 KB0.0 KB/s2.7 KB23:59:56
4usenet.blueworldhosting.com19.8 KB0.0 KB19.8 KB0.0 KB/s2.5 KB23:59:56
5weretis.net19.4 KB3.7 KB23.1 KB0.0 KB/s2.9 KB23:56:15
TOTAL: 58.2 MB15.6 MB23.8 MB0.1 KB/s3.6 KB119:55:59
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1198.172.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
240.90.253.212 2 00.0 KB 0 0 000:00:00
3154-16-44-30.infrawat.ch 1 00.0 KB 0 0 000:00:00
4193-176-29-16.infrawat.ch 1 00.0 KB 0 0 000:00:00
566.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
6giovanni.probe.onyphe.net 1 00.0 KB 0 0 000:00:03
7maisy.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
8r5-144-90.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
9r5-15-f.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
TOTAL: 9 12 00.0 KB 0 0 000:00:18

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:07
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:04
4*.probe.onyphe.net 2 00.0 KB 0 0 000:00:05
5unresolved 2 00.0 KB 0 0 000:00:00
TOTAL: 5 12 00.0 KB 0 0 000:00:18

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
198.172.132.66.censys-scanner.com 0.033 0.032 0.00000:00:04
giovanni.probe.onyphe.net 0.005 0.003 0.00000:00:03
66.195.132.66.censys-scanner.com 0.026 0.021 0.00000:00:02
r5-15-f.monitoring.internet-measurement.com 0.003 0.012 0.00000:00:02
maisy.probe.onyphe.net 0.002 0.012 0.00000:00:02
r5-144-90.monitoring.internet-measurement.com 0.003 0.012 0.00000:00:01
40.90.253.212 0.004 0.020 0.00000:00:00
193-176-29-16.infrawat.ch 0.006 0.005 0.00000:00:00
154-16-44-30.infrawat.ch 0.002 0.015 0.00000:00:00
TOTAL: 9 0.084 0.132 0.00000:00:18

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

SystemConn
maisy.probe.onyphe.net 2
198.172.132.66.censys-scanner.com 1
40.90.253.212 1
66.195.132.66.censys-scanner.com 1
TOTAL: 4 5

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
193-176-29-16.infrawat.ch 1 1
198.172.132.66.censys-scanner.com 1 2
TOTAL: 2 2 4
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