Daily Usenet report

Jun 22 03:00:05 -- Jun 23 03:00:04

Unknown entries from news log file:

First 50 / 55 lines (90.9%)

Jun 22 03:01:18 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 22 03:34:05 twilightnode nnrpd[2411302]: Reading access from /usr/local/news/etc/readers.conf
Jun 22 03:34:05 twilightnode nnrpd[2411302]: Auth strategy 'localhost' does not match client. Removing.
Jun 22 03:34:05 twilightnode nnrpd[2411302]: azpdwsgo7uru.stretchoid.com res <anonymous>
Jun 22 03:34:05 twilightnode nnrpd[2411302]: azpdwsgo7uru.stretchoid.com no_match_user <anonymous> <localhost>
Jun 22 03:34:05 twilightnode nnrpd[2411302]: azpdwsgo7uru.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 22 03:34:05 twilightnode nnrpd[2411302]: azpdwsgo7uru.stretchoid.com match_user <anonymous> <anonymous>
Jun 22 03:34:05 twilightnode nnrpd[2411302]: azpdwsgo7uru.stretchoid.com no_post anonymous-read
Jun 22 03:34:16 twilightnode nnrpd[2411303]: Reading access from /usr/local/news/etc/readers.conf
Jun 22 03:34:16 twilightnode nnrpd[2411303]: Auth strategy 'localhost' does not match client. Removing.
Jun 22 03:34:16 twilightnode nnrpd[2411303]: azpdwsgo7uru.stretchoid.com res <anonymous>
Jun 22 03:34:16 twilightnode nnrpd[2411303]: azpdwsgo7uru.stretchoid.com no_match_user <anonymous> <localhost>
Jun 22 03:34:16 twilightnode nnrpd[2411303]: azpdwsgo7uru.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 22 03:34:16 twilightnode nnrpd[2411303]: azpdwsgo7uru.stretchoid.com match_user <anonymous> <anonymous>
Jun 22 03:34:16 twilightnode nnrpd[2411303]: azpdwsgo7uru.stretchoid.com no_post anonymous-read
Jun 22 06:40:45 twilightnode nnrpd[2419164]: Reading access from /usr/local/news/etc/readers.conf
Jun 22 06:40:45 twilightnode nnrpd[2419164]: Auth strategy 'localhost' does not match client. Removing.
Jun 22 06:40:45 twilightnode nnrpd[2419164]: 89.21.67.171 res <anonymous>
Jun 22 06:40:45 twilightnode nnrpd[2419164]: 89.21.67.171 no_match_user <anonymous> <localhost>
Jun 22 06:40:45 twilightnode nnrpd[2419164]: 89.21.67.171 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 22 06:40:45 twilightnode nnrpd[2419164]: 89.21.67.171 match_user <anonymous> <anonymous>
Jun 22 06:40:45 twilightnode nnrpd[2419164]: 89.21.67.171 no_post anonymous-read
Jun 22 06:40:55 twilightnode nnrpd[2419165]: Reading access from /usr/local/news/etc/readers.conf
Jun 22 06:40:55 twilightnode nnrpd[2419165]: Auth strategy 'localhost' does not match client. Removing.
Jun 22 06:40:55 twilightnode nnrpd[2419165]: 69.5.169.37 res <anonymous>
Jun 22 06:40:55 twilightnode nnrpd[2419165]: 69.5.169.37 no_match_user <anonymous> <localhost>
Jun 22 06:40:55 twilightnode nnrpd[2419165]: 69.5.169.37 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 22 06:40:55 twilightnode nnrpd[2419165]: 69.5.169.37 match_user <anonymous> <anonymous>
Jun 22 06:40:55 twilightnode nnrpd[2419165]: 69.5.169.37 no_post anonymous-read
Jun 22 12:31:06 twilightnode nnrpd[2434717]: Reading access from /usr/local/news/etc/readers.conf
Jun 22 12:31:06 twilightnode nnrpd[2434717]: Auth strategy 'localhost' does not match client. Removing.
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com res <anonymous>
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com no_post anonymous-read
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com res <anonymous>
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 22 12:31:06 twilightnode nnrpd[2434717]: 130.172.132.66.censys-scanner.com no_post anonymous-read
Jun 22 16:41:38 twilightnode nnrpd[2445774]: Reading access from /usr/local/news/etc/readers.conf
Jun 22 16:41:38 twilightnode nnrpd[2445774]: Auth strategy 'localhost' does not match client. Removing.
Jun 22 16:41:38 twilightnode nnrpd[2445774]: 83-175-185-147.dynamic.play.pl res <anonymous>
Jun 22 16:41:38 twilightnode nnrpd[2445774]: 83-175-185-147.dynamic.play.pl no_match_user <anonymous> <localhost>
Jun 22 16:41:38 twilightnode nnrpd[2445774]: 83-175-185-147.dynamic.play.pl no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 22 16:41:38 twilightnode nnrpd[2445774]: 83-175-185-147.dynamic.play.pl match_user <anonymous> <anonymous>
Jun 22 16:41:38 twilightnode nnrpd[2445774]: 83-175-185-147.dynamic.play.pl no_post anonymous-read
Jun 22 16:42:19 twilightnode nnrpd[2445784]: Reading access from /usr/local/news/etc/readers.conf
Jun 22 16:42:19 twilightnode nnrpd[2445784]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 21348 70.1%3.0 MB 66.1%
inn 7432 24.4%1.3 MB 27.7%
innd 1569 5.2%277.1 KB 6.0%
nnrpd 85 0.3%9.3 KB 0.2%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 30436 100.0%4.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 11605 42.7%
Negative hits 8163 30.0%
Do not exist 7395 27.2%
Cache misses 15 0.1%
TOTAL: 4 27178 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.020 0.0%4 2.000 5.000 6.000
article cleanup00:00:00.091 0.0%7410 0.000 0.012 0.091
article logging00:00:00.252 0.0%7434 0.000 0.034 0.125
article parse00:00:00.093 0.0%9769 0.000 0.010 0.065
article write00:00:04.958 0.0%4467 0.500 1.110 8.311
data move00:00:00.077 0.0%28369 0.000 0.003 0.032
hisgrep/artcncl00:00:00.006 0.0%4 0.000 1.500 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.781 0.0%27228 0.110 0.249 8.240
history sync00:00:00.052 0.0%409 0.000 0.127 0.667
history write00:00:04.943 0.0%7410 0.111 0.667 18.889
idle24:00:30.063 99.9%28314 1262.695 3052.556 22249.148
nntp read00:00:00.820 0.0%27962 0.000 0.029 0.115
overview write00:00:02.304 0.0%4467 0.167 0.516 2.667
perl filter00:00:12.104 0.0%7410 0.846 1.633 17.500
python filter00:00:00.971 0.0%7410 0.000 0.131 1.286
site send00:00:00.105 0.0%4467 0.000 0.024 0.294
TOTAL: 1724:01:03.640100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.022 0.0%7212 0.000 0.003 0.067
article prepare00:00:00.001 0.0%25856 0.000 0.000 0.005
article read00:00:00.665 0.0%4441 0.000 0.150 1.000
backlog stats00:00:00.058 0.0%48686 0.000 0.001 0.018
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.388 0.0%24611 0.000 0.016 0.073
data write00:00:00.636 0.0%20224 0.000 0.031 0.184
idle23:50:00.297100.0%48686 1007.161 1762.320 3151.835
status file00:00:05.875 0.0%8588 0.468 0.684 2.517
TOTAL: 923:50:07.942100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:34.205 99.9%14 58.000 2443.214 10496.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.047 0.1%3152 0.000 0.015 0.200
TOTAL: 300:00:34.252100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 14524 4479 7862 2183 30%54:25:50
2news.sklaffkom.se 36 126 60 41 25 47%12:32:18
3newsfeed.endofthelinebbs.com 1 5496 54 4697 745 0%23:38:46
4localhost 25 0 0 0 0 0%00:00:02
5news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 5 68 20146 4593 12600 2953 22%90:46:56
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.0 MB0.0 KB6.8 MB19.8 MB 65%3.0 KB
2newsfeed.endofthelinebbs.com296.6 KB0.0 KB4.1 MB4.4 MB 6%5.7 KB
3news.sklaffkom.se152.4 KB0.0 KB52.0 KB204.5 KB 74%2.4 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 513.4 MB0.0 KB11.0 MB24.4 MB 55%3.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 22 03:00:05 - 03:59:59 63 1.4% 0.02195.6 KB 1.5% 0.05
Jun 22 04:00:00 - 04:59:59 77 1.7% 0.02317.5 KB 2.4% 0.09
Jun 22 05:00:00 - 05:59:59 87 1.9% 0.02377.7 KB 2.8% 0.10
Jun 22 06:00:00 - 06:59:59 112 2.5% 0.03300.6 KB 2.3% 0.08
Jun 22 07:00:00 - 07:59:59 144 3.2% 0.04343.3 KB 2.6% 0.10
Jun 22 08:00:00 - 08:59:59 184 4.1% 0.05476.8 KB 3.6% 0.13
Jun 22 09:00:00 - 09:59:59 238 5.3% 0.07651.5 KB 4.9% 0.18
Jun 22 10:00:00 - 10:59:59 201 4.5% 0.06504.4 KB 3.8% 0.14
Jun 22 11:00:00 - 11:59:59 230 5.2% 0.06579.6 KB 4.4% 0.16
Jun 22 12:00:00 - 12:59:59 202 4.5% 0.06540.5 KB 4.1% 0.15
Jun 22 13:00:00 - 13:59:59 251 5.6% 0.07747.9 KB 5.6% 0.21
Jun 22 14:00:00 - 14:59:59 231 5.2% 0.06765.6 KB 5.7% 0.21
Jun 22 15:00:00 - 15:59:59 277 6.2% 0.08786.7 KB 5.9% 0.22
Jun 22 16:00:00 - 16:59:59 235 5.3% 0.07574.9 KB 4.3% 0.16
Jun 22 17:00:00 - 17:59:59 283 6.3% 0.081.1 MB 8.4% 0.31
Jun 22 18:00:00 - 18:59:59 238 5.3% 0.07781.9 KB 5.9% 0.22
Jun 22 19:00:00 - 19:59:59 248 5.6% 0.07608.5 KB 4.6% 0.17
Jun 22 20:00:00 - 20:59:59 226 5.1% 0.06753.3 KB 5.7% 0.21
Jun 22 21:00:00 - 21:59:59 239 5.4% 0.07717.0 KB 5.4% 0.20
Jun 22 22:00:00 - 22:59:59 200 4.5% 0.06620.8 KB 4.7% 0.17
Jun 22 23:00:00 - 23:59:59 155 3.5% 0.04453.7 KB 3.4% 0.13
Jun 23 00:00:00 - 00:59:59 147 3.3% 0.04516.4 KB 3.9% 0.14
Jun 23 01:00:00 - 01:59:59 104 2.3% 0.03355.4 KB 2.7% 0.10
Jun 23 02:00:00 - 02:59:59 83 1.9% 0.02230.6 KB 1.7% 0.06
Jun 23 03:00:00 - 03:00:04 9 0.2% 2.257.4 KB 0.1% 1.84
TOTAL: 23:59:59 4464 100.0% 0.0513.0 MB 100.0% 0.15
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 2209 1726 0 0 0 0 0 0 483
2newsfeed.endofthelinebbs.com 734 384 0 0 0 0 0 0 350
3news.sklaffkom.se 25 25 0 0 0 0 0 0 0
TOTAL: 3 2968 2135 0 0 0 0 0 0 833

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 216
linux.kernel.git 65
it.sport.calcio.milan 62
pl.soc.prawo 57
de.rec.tv.technik 45
nl.politiek 45
de.rec.fahrrad 44
uk.d-i-y 42
de.soc.recht.misc 41
fido7.ru.fidonet.today 39
de.comp.hardware.netzwerke.misc 36
de.alt.folklore.ddr 35
fido7.ukrnews 32
it.hobby.cucina 32
alt.bestjobsusa.computer.jobs 31
de.alt.fan.pluesch 31
pl.soc.polityka 31
uk.tech.digital-tv 31
de.comm.provider.usenet 30
it.discussioni.misteri 30
TOTAL: 254 2135

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 386
EMP (phn path) 375
User-issued cancel 46
HTML Multipart 1
Too many newsgroups 1
TOTAL: 5 809

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4466 4464 0 0 0 0 99%23:59:51
2sklaffkom.se 4847 799 2423 1235 0 4 16%23:58:47
3newsfeed.endofthelinebbs.com 2785 0 0 0 0 7192 0%23:59:53
4weretis.net 124 0 108 0 0 0 0%11:38:48
5sklaff.dev 0 0 0 0 0 4456 0%23:59:03
6thebunker.nu 0 0 0 0 0 4464 0%23:59:53
7usenet.blueworldhosting.com 0 0 0 0 0 4189 0%23:59:53
TOTAL: 7 12222 5263 2531 1235 0 20305 43%155:36:08
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se13.0 MB0.0 KB13.0 MB0.2 KB/s3.0 KB23:59:51
2sklaffkom.se1.3 MB4.0 MB5.3 MB0.1 KB/s2.7 KB23:58:47
3newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
4sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:03
5thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
6usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
7weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s011:38:48
TOTAL: 714.3 MB4.0 MB18.3 MB0.0 KB/s2.9 KB155:36:08
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
183-175-185-147.dynamic.play.pl 2 00.0 KB 0 0 100:00:21
2azpdwsgo7uru.stretchoid.com 2 00.0 KB 0 0 000:00:10
3130.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
469.5.169.37 1 00.0 KB 0 0 000:00:00
589.21.67.171 1 00.0 KB 0 0 000:00:00
TOTAL: 5 7 00.0 KB 0 0 100:00:34

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.dynamic.play.pl 2 00.0 KB 0 0 100:00:21
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3unresolved 2 00.0 KB 0 0 000:00:00
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
TOTAL: 4 7 00.0 KB 0 0 100:00:34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
83-175-185-147.dynamic.play.pl 0.015 0.027 0.00000:00:21
azpdwsgo7uru.stretchoid.com 0.008 0.022 0.00000:00:10
130.172.132.66.censys-scanner.com 0.032 0.014 0.00000:00:01
69.5.169.37 0.004 0.005 0.00000:00:00
89.21.67.171 0.004 0.013 0.00000:00:00
TOTAL: 5 0.063 0.081 0.00000:00:34

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

SystemConn
130.172.132.66.censys-scanner.com 1
azpdwsgo7uru.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
69.5.169.37 1 1
TOTAL: 1 1 2
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