Daily Usenet report

Sep 25 03:00:04 -- Sep 26 03:00:04

Unknown entries from news log file:

First 50 / 104 lines (48.1%)

Sep 25 03:01:15 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 25 08:32:35 twilightnode nnrpd[22645]: Reading access from /usr/local/news/etc/readers.conf
Sep 25 08:32:35 twilightnode nnrpd[22645]: Auth strategy 'localhost' does not match client. Removing.
Sep 25 08:32:35 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com res <anonymous>
Sep 25 08:32:35 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 25 08:32:35 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 25 08:32:35 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 25 08:32:35 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com no_post anonymous-read
Sep 25 08:32:36 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com res <anonymous>
Sep 25 08:32:36 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 25 08:32:36 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 25 08:32:36 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 25 08:32:36 twilightnode nnrpd[22645]: 198.186.132.66.censys-scanner.com no_post anonymous-read
Sep 25 10:02:48 twilightnode nnrpd[27861]: Reading access from /usr/local/news/etc/readers.conf
Sep 25 10:02:48 twilightnode nnrpd[27861]: Auth strategy 'localhost' does not match client. Removing.
Sep 25 10:02:48 twilightnode nnrpd[27861]: 185-223-235-38.infrawat.ch res <anonymous>
Sep 25 10:02:48 twilightnode nnrpd[27861]: 185-223-235-38.infrawat.ch no_match_user <anonymous> <localhost>
Sep 25 10:02:48 twilightnode nnrpd[27861]: 185-223-235-38.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 25 10:02:48 twilightnode nnrpd[27861]: 185-223-235-38.infrawat.ch match_user <anonymous> <anonymous>
Sep 25 10:02:48 twilightnode nnrpd[27861]: 185-223-235-38.infrawat.ch no_post anonymous-read
Sep 25 10:02:54 twilightnode nnrpd[27863]: Reading access from /usr/local/news/etc/readers.conf
Sep 25 10:02:54 twilightnode nnrpd[27863]: Auth strategy 'localhost' does not match client. Removing.
Sep 25 10:02:54 twilightnode nnrpd[27863]: 194-88-98-110.infrawat.ch res <anonymous>
Sep 25 10:02:54 twilightnode nnrpd[27863]: 194-88-98-110.infrawat.ch no_match_user <anonymous> <localhost>
Sep 25 10:02:54 twilightnode nnrpd[27863]: 194-88-98-110.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 25 10:02:54 twilightnode nnrpd[27863]: 194-88-98-110.infrawat.ch match_user <anonymous> <anonymous>
Sep 25 10:02:54 twilightnode nnrpd[27863]: 194-88-98-110.infrawat.ch no_post anonymous-read
Sep 25 14:14:45 twilightnode nnrpd[42744]: Reading access from /usr/local/news/etc/readers.conf
Sep 25 14:14:45 twilightnode nnrpd[42744]: Auth strategy 'localhost' does not match client. Removing.
Sep 25 14:14:45 twilightnode nnrpd[42744]: 205.210.31.219 res <anonymous>
Sep 25 14:14:45 twilightnode nnrpd[42744]: 205.210.31.219 no_match_user <anonymous> <localhost>
Sep 25 14:14:45 twilightnode nnrpd[42744]: 205.210.31.219 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 25 14:14:45 twilightnode nnrpd[42744]: 205.210.31.219 match_user <anonymous> <anonymous>
Sep 25 14:14:45 twilightnode nnrpd[42744]: 205.210.31.219 no_post anonymous-read
Sep 25 16:00:14 twilightnode nnrpd[48615]: Reading access from /usr/local/news/etc/readers.conf
Sep 25 16:00:14 twilightnode nnrpd[48615]: Auth strategy 'localhost' does not match client. Removing.
Sep 25 16:00:14 twilightnode nnrpd[48615]: azpdcsv8n033.stretchoid.com res <anonymous>
Sep 25 16:00:14 twilightnode nnrpd[48615]: azpdcsv8n033.stretchoid.com no_match_user <anonymous> <localhost>
Sep 25 16:00:14 twilightnode nnrpd[48615]: azpdcsv8n033.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 25 16:00:14 twilightnode nnrpd[48615]: azpdcsv8n033.stretchoid.com match_user <anonymous> <anonymous>
Sep 25 16:00:14 twilightnode nnrpd[48615]: azpdcsv8n033.stretchoid.com no_post anonymous-read
Sep 25 16:00:23 twilightnode nnrpd[48617]: Reading access from /usr/local/news/etc/readers.conf
Sep 25 16:00:23 twilightnode nnrpd[48617]: Auth strategy 'localhost' does not match client. Removing.
Sep 25 16:00:23 twilightnode nnrpd[48617]: azpdcsv8n033.stretchoid.com res <anonymous>
Sep 25 16:00:23 twilightnode nnrpd[48617]: azpdcsv8n033.stretchoid.com no_match_user <anonymous> <localhost>
Sep 25 16:00:23 twilightnode nnrpd[48617]: azpdcsv8n033.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 25 16:00:23 twilightnode nnrpd[48617]: azpdcsv8n033.stretchoid.com match_user <anonymous> <anonymous>
Sep 25 16:00:23 twilightnode nnrpd[48617]: azpdcsv8n033.stretchoid.com no_post anonymous-read
Sep 26 02:14:14 twilightnode nnrpd[80312]: Reading access from /usr/local/news/etc/readers.conf
Sep 26 02:14:14 twilightnode nnrpd[80312]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 6834 63.8%1.2 MB 69.1%
innd 2045 19.1%302.9 KB 17.1%
innfeed 1260 11.8%195.3 KB 11.0%
nnrpd 570 5.3%50.5 KB 2.8%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 10710 100.0%1.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 17662 50.0%
Negative hits 10869 30.8%
Do not exist 6765 19.2%
Cache misses 27 0.1%
TOTAL: 4 35323 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.042 0.0%13 0.000 3.231 9.000
article cleanup00:00:00.110 0.0%6778 0.000 0.016 0.133
article logging00:00:00.286 0.0%6833 0.000 0.042 0.318
article parse00:00:00.117 0.0%9341 0.000 0.013 0.138
article write00:00:06.188 0.0%5769 0.667 1.073 2.591
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.222 0.0%36162 0.000 0.006 0.033
hisgrep/artcncl00:00:00.008 0.0%13 0.000 0.615 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.179 0.0%35390 0.050 0.118 0.248
history sync00:00:00.081 0.0%324 0.000 0.250 1.000
history write00:00:03.175 0.0%6778 0.133 0.468 1.083
idle23:59:49.786 99.9%36309 1138.165 2379.294 10171.559
nntp read00:00:00.804 0.0%35934 0.000 0.022 0.067
overview write00:00:04.330 0.0%5769 0.209 0.751 2.556
perl filter00:00:13.428 0.0%6778 0.722 1.981 12.500
python filter00:00:01.024 0.0%6778 0.000 0.151 0.714
site send00:00:00.323 0.0%11503 0.000 0.028 0.432
TOTAL: 1824:00:24.103100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.035 0.0%6132 0.000 0.006 0.083
article prepare00:00:00.002 0.0%28216 0.000 0.000 0.006
article read00:00:01.086 0.0%4556 0.000 0.238 2.778
backlog stats00:00:00.040 0.0%76813 0.000 0.001 0.015
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.530 0.0%43162 0.000 0.012 0.036
data write00:00:00.965 0.0%37631 0.000 0.026 0.072
idle23:59:43.501100.0%76813 315.433 1124.595 3687.104
status file00:00:00.202 0.0%161 0.000 1.255 4.000
TOTAL: 923:59:46.361100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:46.777100.0%523 79.000 204.163 9824.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.029 0.0%1304 0.000 0.022 0.500
readart00:00:00.001 0.0%4 0.000 0.250 0.500
TOTAL: 400:01:46.807100.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 4 9220 3591 5608 21 38%42:08:29
2usenet.blueworldhosting.com 144 6718 1855 3912 951 27%23:49:23
3news.tebibyte.org 2 3423 137 3285 1 4%23:59:28
4newsfeed.endofthelinebbs.com 3 7384 125 7198 61 1%33:55:22
5news.sklaffkom.se 19 26 24 2 0 92%04:47:03
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 197 26771 5732 20005 1034 21%128:39:45
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.9 MB0.0 KB56.6 KB12.0 MB 99%3.4 KB
2usenet.blueworldhosting.com9.7 MB0.0 KB1.2 MB10.9 MB 89%4.0 KB
3newsfeed.endofthelinebbs.com2.0 MB0.0 KB114.9 KB2.1 MB 94%11.7 KB
4news.tebibyte.org601.2 KB0.0 KB3.0 KB604.2 KB 99%4.4 KB
5news.sklaffkom.se70.8 KB0.0 KB0.0 KB70.8 KB100%2.9 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 624.3 MB0.0 KB1.4 MB25.7 MB 94%3.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 25 03:00:04 - 03:59:59 116 2.0% 0.03461.3 KB 1.9% 0.13
Sep 25 04:00:00 - 04:59:59 152 2.6% 0.04820.1 KB 3.4% 0.23
Sep 25 05:00:00 - 05:59:59 95 1.6% 0.03267.0 KB 1.1% 0.07
Sep 25 06:00:00 - 06:59:59 100 1.7% 0.03319.5 KB 1.3% 0.09
Sep 25 07:00:00 - 07:59:59 159 2.8% 0.04574.1 KB 2.4% 0.16
Sep 25 08:00:00 - 08:59:59 254 4.4% 0.071008.8 KB 4.2% 0.28
Sep 25 09:00:00 - 09:59:59 320 5.5% 0.091.3 MB 5.3% 0.36
Sep 25 10:00:00 - 10:59:59 330 5.7% 0.09902.2 KB 3.8% 0.25
Sep 25 11:00:00 - 11:59:59 389 6.7% 0.111.4 MB 6.0% 0.40
Sep 25 12:00:00 - 12:59:59 415 7.2% 0.121.5 MB 6.2% 0.42
Sep 25 13:00:00 - 13:59:59 296 5.1% 0.081.1 MB 4.7% 0.31
Sep 25 14:00:00 - 14:59:59 296 5.1% 0.081.1 MB 4.8% 0.32
Sep 25 15:00:00 - 15:59:59 321 5.6% 0.091.3 MB 5.5% 0.37
Sep 25 16:00:00 - 16:59:59 385 6.7% 0.111.7 MB 7.3% 0.49
Sep 25 17:00:00 - 17:59:59 263 4.6% 0.071.0 MB 4.4% 0.29
Sep 25 18:00:00 - 18:59:59 249 4.3% 0.071003.7 KB 4.2% 0.28
Sep 25 19:00:00 - 19:59:59 235 4.1% 0.071.1 MB 4.8% 0.32
Sep 25 20:00:00 - 20:59:59 247 4.3% 0.071.8 MB 7.5% 0.50
Sep 25 21:00:00 - 21:59:59 242 4.2% 0.071.1 MB 4.6% 0.31
Sep 25 22:00:00 - 22:59:59 218 3.8% 0.061.1 MB 4.8% 0.32
Sep 25 23:00:00 - 23:59:59 233 4.0% 0.06998.1 KB 4.2% 0.28
Sep 26 00:00:00 - 00:59:59 180 3.1% 0.05775.2 KB 3.2% 0.22
Sep 26 01:00:00 - 01:59:59 136 2.4% 0.04537.6 KB 2.2% 0.15
Sep 26 02:00:00 - 02:59:59 128 2.2% 0.04545.4 KB 2.3% 0.15
Sep 26 03:00:00 - 03:00:04 11 0.2% 2.7515.2 KB 0.1% 3.79
TOTAL: 24:00:00 5770 100.0% 0.0723.5 MB 100.0% 0.28
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 951 323 0 0 0 0 0 0 628
2twilightnode-out.news.weretis.net 80 11 0 0 0 0 0 0 69
3newsfeed.endofthelinebbs.com 33 2 0 0 0 0 0 0 31
TOTAL: 3 1064 336 0 0 0 0 0 0 728

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 20
de.comm.software.mailserver 6
hispagatos.mastodon.tag.hacking 4
pl.listserv.chomor-l 2
realms.sysops 2
realms.testing 2
alt.autos.performance-cars 1
alt.bbs.files-announce 1
alt.drwho 1
alt.strange.days 1
biz.mlm 1
can.test 1
chmurka.statystyki 1
fido.ru.fidonet.today 1
hispagatos.mastodon.tag.hackers 1
iijnet.music 1
realms.general 1
vt.4sale 1
TOTAL: 19 336

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 553
Too many newsgroups 104
User-issued cancel 7
EMP (phn path) 4
HTML Multipart 3
Too many newsgroups (meow) 1
hierarchy violation - crosspost outside netscape 1
TOTAL: 7 673

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7932 2492 3158 84 0 47 31%23:59:51
2sklaffkom.se 5847 991 1288 3456 0 35 16%23:59:55
3newsfeed.endofthelinebbs.com 10040 22 5629 15 0 323 0%23:58:30
4weretis.net 2040 2 1996 0 0 0 0%23:59:55
5usenet.blueworldhosting.com 6155 0 3624 0 0 5 0%23:59:05
TOTAL: 5 32014 3507 15695 3555 0 410 10%119:57:16
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.5 MB842.6 KB7.3 MB0.1 KB/s2.9 KB23:59:51
2sklaffkom.se1.7 MB16.7 MB18.4 MB0.2 KB/s4.2 KB23:59:55
3newsfeed.endofthelinebbs.com73.0 KB43.3 KB116.4 KB0.0 KB/s3.1 KB23:58:30
4weretis.net59.8 KB0.0 KB59.8 KB0.0 KB/s29.9 KB23:59:55
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:05
TOTAL: 58.3 MB17.6 MB26.0 MB0.1 KB/s3.8 KB119:57:16
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 8 411.4 KB 2 0 000:01:33
TOTAL: 1 8 411.4 KB 2 0 000:01:33

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 9 411.4 KB 2 0 000:01:34
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
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:01
TOTAL: 4 14 411.4 KB 2 0 000:01:46

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 0.098 0.380 0.00000:01:33
azpdcsv8n033.stretchoid.com 0.014 0.015 0.00000:00:09
198.186.132.66.censys-scanner.com 0.031 0.020 0.00000:00:01
205.210.31.219 0.004 0.012 0.00000:00:00
194-88-98-110.infrawat.ch 0.005 0.006 0.00000:00:00
185-223-235-38.infrawat.ch 0.007 0.012 0.00000:00:00
TOTAL: 6 0.159 0.445 0.00000:01:46

Curious NNRP explorers [Top 20]:

SystemConn
azpdcsv8n033.stretchoid.com 2
185-223-235-38.infrawat.ch 1
194-88-98-110.infrawat.ch 1
198.186.132.66.censys-scanner.com 1
205.210.31.219 1
TOTAL: 5 6

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

SystemConn
198.186.132.66.censys-scanner.com 1
azpdcsv8n033.stretchoid.com 1
TOTAL: 2 2

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
194-88-98-110.infrawat.ch 1 1
TOTAL: 1 1 3

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

HierarchyCountPct
1alt 2 50.0%
2de 2 50.0%
TOTAL: 2 4100.0%

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

NewsgroupCount
1alt.os.linux 2
2de.comp.hardware.netzwerke.misc 2
TOTAL: 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