Daily Usenet report

Jul 20 03:00:04 -- Jul 21 03:00:05

Unknown entries from news log file:

First 50 / 60 lines (83.3%)

Jul 20 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 20 18:15:04 twilightnode nnrpd[323221]: Reading access from /usr/local/news/etc/readers.conf
Jul 20 18:15:04 twilightnode nnrpd[323221]: Auth strategy 'localhost' does not match client. Removing.
Jul 20 18:15:04 twilightnode nnrpd[323221]: 198.235.24.88 res <anonymous>
Jul 20 18:15:04 twilightnode nnrpd[323221]: 198.235.24.88 no_match_user <anonymous> <localhost>
Jul 20 18:15:04 twilightnode nnrpd[323221]: 198.235.24.88 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 20 18:15:04 twilightnode nnrpd[323221]: 198.235.24.88 match_user <anonymous> <anonymous>
Jul 20 18:15:04 twilightnode nnrpd[323221]: 198.235.24.88 no_post anonymous-read
Jul 20 19:36:52 twilightnode nnrpd[326719]: Reading access from /usr/local/news/etc/readers.conf
Jul 20 19:36:52 twilightnode nnrpd[326719]: Auth strategy 'localhost' does not match client. Removing.
Jul 20 19:36:52 twilightnode nnrpd[326719]: azpdwsrtejri.stretchoid.com res <anonymous>
Jul 20 19:36:52 twilightnode nnrpd[326719]: azpdwsrtejri.stretchoid.com no_match_user <anonymous> <localhost>
Jul 20 19:36:52 twilightnode nnrpd[326719]: azpdwsrtejri.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 20 19:36:52 twilightnode nnrpd[326719]: azpdwsrtejri.stretchoid.com match_user <anonymous> <anonymous>
Jul 20 19:36:52 twilightnode nnrpd[326719]: azpdwsrtejri.stretchoid.com no_post anonymous-read
Jul 20 19:37:02 twilightnode nnrpd[326724]: Reading access from /usr/local/news/etc/readers.conf
Jul 20 19:37:02 twilightnode nnrpd[326724]: Auth strategy 'localhost' does not match client. Removing.
Jul 20 19:37:02 twilightnode nnrpd[326724]: azpdwsrtejri.stretchoid.com res <anonymous>
Jul 20 19:37:02 twilightnode nnrpd[326724]: azpdwsrtejri.stretchoid.com no_match_user <anonymous> <localhost>
Jul 20 19:37:02 twilightnode nnrpd[326724]: azpdwsrtejri.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 20 19:37:02 twilightnode nnrpd[326724]: azpdwsrtejri.stretchoid.com match_user <anonymous> <anonymous>
Jul 20 19:37:02 twilightnode nnrpd[326724]: azpdwsrtejri.stretchoid.com no_post anonymous-read
Jul 20 20:36:43 twilightnode nnrpd[329372]: Reading access from /usr/local/news/etc/readers.conf
Jul 20 20:36:43 twilightnode nnrpd[329372]: Auth strategy 'localhost' does not match client. Removing.
Jul 20 20:36:43 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com res <anonymous>
Jul 20 20:36:43 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 20 20:36:43 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 20 20:36:43 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 20 20:36:43 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com no_post anonymous-read
Jul 20 20:36:46 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com res <anonymous>
Jul 20 20:36:46 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 20 20:36:46 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 20 20:36:46 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 20 20:36:46 twilightnode nnrpd[329372]: 88.195.132.66.censys-scanner.com no_post anonymous-read
Jul 20 22:58:48 twilightnode nnrpd[335705]: Reading access from /usr/local/news/etc/readers.conf
Jul 20 22:58:48 twilightnode nnrpd[335705]: Auth strategy 'localhost' does not match client. Removing.
Jul 20 22:58:48 twilightnode nnrpd[335705]: 124.154.45.199.censys-scanner.com res <anonymous>
Jul 20 22:58:48 twilightnode nnrpd[335705]: 124.154.45.199.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 20 22:58:48 twilightnode nnrpd[335705]: 124.154.45.199.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 20 22:58:48 twilightnode nnrpd[335705]: 124.154.45.199.censys-scanner.com match_user <anonymous> <anonymous>
Jul 20 22:58:48 twilightnode nnrpd[335705]: 124.154.45.199.censys-scanner.com no_post anonymous-read
Jul 20 22:58:53 twilightnode nnrpd[335708]: Reading access from /usr/local/news/etc/readers.conf
Jul 20 22:58:53 twilightnode nnrpd[335708]: Auth strategy 'localhost' does not match client. Removing.
Jul 20 22:58:53 twilightnode nnrpd[335708]: 124.154.45.199.censys-scanner.com res <anonymous>
Jul 20 22:58:53 twilightnode nnrpd[335708]: 124.154.45.199.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 20 22:58:53 twilightnode nnrpd[335708]: 124.154.45.199.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 20 22:58:53 twilightnode nnrpd[335708]: 124.154.45.199.censys-scanner.com match_user <anonymous> <anonymous>
Jul 20 22:58:53 twilightnode nnrpd[335708]: 124.154.45.199.censys-scanner.com no_post anonymous-read
Jul 20 22:59:00 twilightnode nnrpd[335710]: Reading access from /usr/local/news/etc/readers.conf
Jul 20 22:59:00 twilightnode nnrpd[335710]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 6414 71.1%970.3 KB 71.4%
innd 1263 14.0%192.9 KB 14.2%
innfeed 1249 13.9%185.4 KB 13.6%
nnrpd 91 1.0%9.6 KB 0.7%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 9018 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14484 48.2%
Negative hits 9141 30.4%
Do not exist 6385 21.2%
Cache misses 55 0.2%
TOTAL: 4 30065 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.009 0.0%12 0.333 0.750 2.000
article cleanup00:00:00.076 0.0%6415 0.000 0.012 0.128
article logging00:00:00.253 0.0%6422 0.000 0.039 0.333
article parse00:00:00.140 0.0%8770 0.000 0.016 0.909
article write00:00:04.452 0.0%4713 0.333 0.945 2.208
data move00:00:00.084 0.0%32623 0.000 0.003 0.024
hisgrep/artcncl00:00:00.003 0.0%12 0.000 0.250 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.862 0.0%30197 0.080 0.161 0.500
history sync00:00:00.048 0.0%418 0.000 0.115 1.000
history write00:00:03.446 0.0%6415 0.179 0.537 1.000
idle24:00:36.347100.0%31942 1450.353 2706.041 13638.614
nntp read00:00:00.739 0.0%31604 0.000 0.023 0.169
overview write00:00:02.670 0.0%4713 0.179 0.567 7.800
perl filter00:00:09.461 0.0%6415 0.828 1.475 25.333
python filter00:00:00.716 0.0%6415 0.000 0.112 2.083
site send00:00:00.143 0.0%4712 0.000 0.030 0.273
TOTAL: 1724:01:03.449100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.017 0.0%4917 0.000 0.003 0.059
article prepare00:00:00.002 0.0%12168 0.000 0.000 0.010
article read00:00:00.564 0.0%2392 0.000 0.236 2.000
backlog stats00:00:00.045 0.0%50842 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.455 0.0%27317 0.000 0.017 0.054
data write00:00:00.709 0.0%22558 0.000 0.031 0.089
idle23:50:30.916100.0%50842 439.876 1688.189 5129.111
status file00:00:00.129 0.0%237 0.000 0.544 3.000
TOTAL: 923:50:32.837100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:20.820100.0%11 92.500 1892.727 10527.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%68 0.000 0.015 0.200
TOTAL: 300:00:20.821100.0%----

Control commands to innd:

CommandNumber
flush 2
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 166

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 10965 4217 5665 1083 38%50:12:53
2newsfeed.endofthelinebbs.com 2 5475 470 4397 608 8%26:21:33
3news.tebibyte.org 1 4430 117 4276 37 2%23:59:52
4news.sklaffkom.se 34 85 29 24 32 34%08:45:39
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 66 20955 4833 14362 1760 23%109:19:57
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.7 MB0.0 KB3.2 MB16.0 MB 79%3.1 KB
2newsfeed.endofthelinebbs.com5.9 MB0.0 KB1.3 MB7.1 MB 81%6.8 KB
3news.tebibyte.org499.6 KB0.0 KB205.8 KB705.4 KB 70%4.6 KB
4news.sklaffkom.se63.8 KB0.0 KB55.2 KB119.0 KB 53%2.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 519.1 MB0.0 KB4.8 MB23.9 MB 79%3.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 20 03:00:04 - 03:59:59 102 2.2% 0.03482.9 KB 2.6% 0.13
Jul 20 04:00:00 - 04:59:59 117 2.5% 0.03620.2 KB 3.4% 0.17
Jul 20 05:00:00 - 05:59:59 109 2.3% 0.03339.3 KB 1.8% 0.09
Jul 20 06:00:00 - 06:59:59 179 3.8% 0.05601.2 KB 3.3% 0.17
Jul 20 07:00:00 - 07:59:59 115 2.4% 0.03299.9 KB 1.6% 0.08
Jul 20 08:00:00 - 08:59:59 187 4.0% 0.05694.7 KB 3.8% 0.19
Jul 20 09:00:00 - 09:59:59 201 4.3% 0.06612.8 KB 3.3% 0.17
Jul 20 10:00:00 - 10:59:59 251 5.3% 0.07841.3 KB 4.6% 0.23
Jul 20 11:00:00 - 11:59:59 171 3.6% 0.05830.3 KB 4.5% 0.23
Jul 20 12:00:00 - 12:59:59 237 5.0% 0.071.0 MB 5.7% 0.29
Jul 20 13:00:00 - 13:59:59 281 6.0% 0.08815.3 KB 4.4% 0.23
Jul 20 14:00:00 - 14:59:59 176 3.7% 0.05643.1 KB 3.5% 0.18
Jul 20 15:00:00 - 15:59:59 221 4.7% 0.06618.4 KB 3.4% 0.17
Jul 20 16:00:00 - 16:59:59 252 5.4% 0.071.4 MB 7.6% 0.39
Jul 20 17:00:00 - 17:59:59 247 5.2% 0.071.1 MB 6.3% 0.32
Jul 20 18:00:00 - 18:59:59 299 6.3% 0.081.1 MB 5.9% 0.30
Jul 20 19:00:00 - 19:59:59 242 5.1% 0.07719.7 KB 3.9% 0.20
Jul 20 20:00:00 - 20:59:59 230 4.9% 0.06734.0 KB 4.0% 0.20
Jul 20 21:00:00 - 21:59:59 236 5.0% 0.07808.4 KB 4.4% 0.22
Jul 20 22:00:00 - 22:59:59 218 4.6% 0.06611.0 KB 3.3% 0.17
Jul 20 23:00:00 - 23:59:59 189 4.0% 0.05580.2 KB 3.2% 0.16
Jul 21 00:00:00 - 00:59:59 199 4.2% 0.061.5 MB 8.3% 0.42
Jul 21 01:00:00 - 01:59:59 128 2.7% 0.04511.9 KB 2.8% 0.14
Jul 21 02:00:00 - 02:59:59 111 2.4% 0.03747.2 KB 4.1% 0.21
Jul 21 03:00:00 - 03:00:05 11 0.2% 2.2019.2 KB 0.1% 3.84
TOTAL: 24:00:01 4709 100.0% 0.0517.9 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1054 1012 0 0 0 0 0 0 42
2newsfeed.endofthelinebbs.com 571 88 0 0 0 0 0 0 483
3news.tebibyte.org 48 47 0 0 0 0 0 0 1
4news.sklaffkom.se 32 32 0 0 0 0 0 0 0
TOTAL: 4 1705 1179 0 0 0 0 0 0 526

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.checkmate 60
alt.usage.english 45
fido7.ukrnews 35
it.hobby.cucina 32
lada.talk 30
pl.misc.samochody 30
fido7.ru.news 29
aus.politics 27
it-alt.sport.calcio.juventus 27
alt.slack 24
uk.legal.moderated 24
alt.recovery.aa 22
cn.comp 20
fr.misc.engueulades 20
uk.media.radio.archers 20
pl.soc.polityka 19
eternal-september.talk 18
linux.debian.maint.ocaml.maint 18
de.rec.heimwerken 17
alt.bestjobsusa.computer.jobs 16
TOTAL: 211 1179

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 501
HTML Multipart 8
User-issued cancel 8
Too many newsgroups 1
Too many newsgroups (low_xpost) 1
TOTAL: 5 519

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 5141 876 2381 1452 0 1 17%23:59:54
2news.tebibyte.org 6851 683 4020 10 0 37 9%23:59:54
3newsfeed.endofthelinebbs.com 7841 25 4180 5 0 190 0%23:59:54
4weretis.net 594 0 580 0 0 0 0%21:31:39
TOTAL: 4 20427 1584 11161 1467 0 228 7%93:31:21
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.2 MB1.0 MB3.2 MB0.0 KB/s4.7 KB23:59:54
2sklaffkom.se1.4 MB9.7 MB11.0 MB0.1 KB/s4.9 KB23:59:54
3newsfeed.endofthelinebbs.com82.8 KB64.9 KB147.7 KB0.0 KB/s4.9 KB23:59:54
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s021:31:39
TOTAL: 43.6 MB10.8 MB14.4 MB0.0 KB/s4.8 KB93:31:21
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1124.154.45.199.censys-scanner.com 3 00.0 KB 0 0 000:00:05
2azpdwsrtejri.stretchoid.com 2 00.0 KB 0 0 000:00:10
3198.235.24.88 1 00.0 KB 0 0 000:00:00
488.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:04
TOTAL: 4 7 00.0 KB 0 0 000:00:20

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.199.censys-scanner.com 3 00.0 KB 0 0 000:00:05
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:04
4unresolved 1 00.0 KB 0 0 000:00:00
TOTAL: 4 7 00.0 KB 0 0 000:00:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdwsrtejri.stretchoid.com 0.007 0.020 0.00000:00:10
124.154.45.199.censys-scanner.com 0.035 0.024 0.00000:00:05
88.195.132.66.censys-scanner.com 0.023 0.017 0.00000:00:04
198.235.24.88 0.007 0.016 0.00000:00:00
TOTAL: 4 0.072 0.077 0.00000:00:20

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

SystemConn
124.154.45.199.censys-scanner.com 1
88.195.132.66.censys-scanner.com 1
azpdwsrtejri.stretchoid.com 1
TOTAL: 3 3

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
124.154.45.199.censys-scanner.com 1 2
TOTAL: 1 1 3
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