Daily Usenet report

Jun 23 03:00:05 -- Jun 24 03:00:05

Unknown entries from news log file:

First 50 / 1556 lines (3.2%)

Jun 23 03:01:18 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 23 06:25:11 twilightnode nnrpd[2481382]: Reading access from /usr/local/news/etc/readers.conf
Jun 23 06:25:11 twilightnode nnrpd[2481382]: Auth strategy 'localhost' does not match client. Removing.
Jun 23 06:25:11 twilightnode nnrpd[2481382]: 69.5.169.220 res <anonymous>
Jun 23 06:25:11 twilightnode nnrpd[2481382]: 69.5.169.220 no_match_user <anonymous> <localhost>
Jun 23 06:25:11 twilightnode nnrpd[2481382]: 69.5.169.220 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 23 06:25:11 twilightnode nnrpd[2481382]: 69.5.169.220 match_user <anonymous> <anonymous>
Jun 23 06:25:11 twilightnode nnrpd[2481382]: 69.5.169.220 no_post anonymous-read
Jun 23 06:25:19 twilightnode nnrpd[2481383]: Reading access from /usr/local/news/etc/readers.conf
Jun 23 06:25:19 twilightnode nnrpd[2481383]: Auth strategy 'localhost' does not match client. Removing.
Jun 23 06:25:19 twilightnode nnrpd[2481383]: 69.5.169.116 res <anonymous>
Jun 23 06:25:19 twilightnode nnrpd[2481383]: 69.5.169.116 no_match_user <anonymous> <localhost>
Jun 23 06:25:19 twilightnode nnrpd[2481383]: 69.5.169.116 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 23 06:25:19 twilightnode nnrpd[2481383]: 69.5.169.116 match_user <anonymous> <anonymous>
Jun 23 06:25:19 twilightnode nnrpd[2481383]: 69.5.169.116 no_post anonymous-read
Jun 23 06:43:47 twilightnode nnrpd[2482148]: Reading access from /usr/local/news/etc/readers.conf
Jun 23 06:43:47 twilightnode nnrpd[2482148]: Auth strategy 'localhost' does not match client. Removing.
Jun 23 06:43:47 twilightnode nnrpd[2482148]: 45-79-211-97.ip.linodeusercontent.com res <anonymous>
Jun 23 06:43:47 twilightnode nnrpd[2482148]: 45-79-211-97.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 23 06:43:47 twilightnode nnrpd[2482148]: 45-79-211-97.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 23 06:43:47 twilightnode nnrpd[2482148]: 45-79-211-97.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 23 06:43:47 twilightnode nnrpd[2482148]: 45-79-211-97.ip.linodeusercontent.com no_post anonymous-read
Jun 23 07:38:15 twilightnode nnrpd[2484372]: Reading access from /usr/local/news/etc/readers.conf
Jun 23 07:38:15 twilightnode nnrpd[2484372]: Auth strategy 'localhost' does not match client. Removing.
Jun 23 07:38:15 twilightnode nnrpd[2484372]: 45-33-14-197.ip.linodeusercontent.com res <anonymous>
Jun 23 07:38:15 twilightnode nnrpd[2484372]: 45-33-14-197.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 23 07:38:15 twilightnode nnrpd[2484372]: 45-33-14-197.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 23 07:38:15 twilightnode nnrpd[2484372]: 45-33-14-197.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 23 07:38:15 twilightnode nnrpd[2484372]: 45-33-14-197.ip.linodeusercontent.com no_post anonymous-read
Jun 23 08:23:19 twilightnode nnrpd[2486408]: Reading access from /usr/local/news/etc/readers.conf
Jun 23 08:23:19 twilightnode nnrpd[2486408]: Auth strategy 'localhost' does not match client. Removing.
Jun 23 08:23:19 twilightnode nnrpd[2486408]: 66-228-53-4.ip.linodeusercontent.com res <anonymous>
Jun 23 08:23:19 twilightnode nnrpd[2486408]: 66-228-53-4.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 23 08:23:19 twilightnode nnrpd[2486408]: 66-228-53-4.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 23 08:23:19 twilightnode nnrpd[2486408]: 66-228-53-4.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 23 08:23:19 twilightnode nnrpd[2486408]: 66-228-53-4.ip.linodeusercontent.com no_post anonymous-read
Jun 23 08:23:20 twilightnode nnrpd[2486409]: Reading access from /usr/local/news/etc/readers.conf
Jun 23 08:23:20 twilightnode nnrpd[2486409]: Auth strategy 'localhost' does not match client. Removing.
Jun 23 08:23:20 twilightnode nnrpd[2486409]: 66-228-53-4.ip.linodeusercontent.com res <anonymous>
Jun 23 08:23:20 twilightnode nnrpd[2486409]: 66-228-53-4.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 23 08:23:20 twilightnode nnrpd[2486409]: 66-228-53-4.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 23 08:23:20 twilightnode nnrpd[2486409]: 66-228-53-4.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 23 08:23:20 twilightnode nnrpd[2486409]: 66-228-53-4.ip.linodeusercontent.com no_post anonymous-read
Jun 23 08:38:02 twilightnode nnrpd[2486960]: Reading access from /usr/local/news/etc/readers.conf
Jun 23 08:38:02 twilightnode nnrpd[2486960]: Auth strategy 'localhost' does not match client. Removing.
Jun 23 08:38:02 twilightnode nnrpd[2486960]: 173-255-221-189.ip.linodeusercontent.com res <anonymous>
Jun 23 08:38:02 twilightnode nnrpd[2486960]: 173-255-221-189.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 23 08:38:02 twilightnode nnrpd[2486960]: 173-255-221-189.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 23 08:38:02 twilightnode nnrpd[2486960]: 173-255-221-189.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 23 08:38:02 twilightnode nnrpd[2486960]: 173-255-221-189.ip.linodeusercontent.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 34799 77.6%4.8 MB 74.3%
inn 6506 14.5%1.2 MB 18.1%
nnrpd 2175 4.8%239.8 KB 3.6%
innd 1365 3.0%260.4 KB 4.0%
controlchan 4 0.0%0.2 KB 0.0%
TOTAL: 5 44849 100.0%6.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14524 48.7%
Negative hits 8354 28.0%
Do not exist 6426 21.5%
Cache misses 546 1.8%
TOTAL: 4 29850 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.010 0.0%2 3.000 5.000 7.000
article cleanup00:00:00.063 0.0%6438 0.000 0.010 0.105
article logging00:00:00.316 0.0%6449 0.000 0.049 0.617
article parse00:00:00.106 0.0%8617 0.000 0.012 0.087
article write00:00:04.729 0.0%4061 0.404 1.164 15.000
data move00:00:00.102 0.0%30482 0.000 0.003 0.042
hisgrep/artcncl00:00:00.001 0.0%2 0.000 0.500 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.472 0.0%29888 0.065 0.183 1.396
history sync00:00:00.046 0.0%405 0.000 0.114 1.000
history write00:00:03.632 0.0%6438 0.136 0.564 1.644
idle23:50:23.938100.0%30847 1335.938 2782.246 13653.023
nntp read00:00:00.663 0.0%30137 0.000 0.022 0.143
overview write00:00:02.079 0.0%4061 0.143 0.512 2.429
perl filter00:00:10.254 0.0%6438 0.804 1.593 14.316
python filter00:00:00.790 0.0%6439 0.000 0.123 1.053
site send00:00:00.103 0.0%4061 0.000 0.025 0.250
TOTAL: 1723:50:52.304100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.044 0.0%8719 0.000 0.005 0.062
article prepare00:00:00.002 0.0%22708 0.000 0.000 0.010
article read00:00:00.678 0.0%4049 0.000 0.167 1.545
backlog stats00:00:00.045 0.0%59493 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.454 0.0%28742 0.000 0.016 0.067
data write00:00:00.753 0.0%24767 0.005 0.030 0.107
idle23:50:15.272100.0%59493 962.687 1442.443 2923.517
status file00:00:09.687 0.0%14269 0.460 0.679 3.700
TOTAL: 923:50:26.935100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:07.904100.0%570 19.333 119.130 10544.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.028 0.0%1170 0.000 0.024 0.500
TOTAL: 300:01:07.932100.0%----

Control commands to innd:

CommandNumber
flush 3
flushlogs 2
go 2
logmode 2
lowmark 1
mode 154
name 3
pause 2
paused 2
reload 2
reserve 2
xexec 1
TOTAL: 12 176

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 11250 3867 5759 1624 34%45:56:10
2newsfeed.endofthelinebbs.com 3 5226 75 4529 622 1%25:01:53
3news.sklaffkom.se 39 102 47 28 27 46%09:57:59
4localhost 26 1 1 0 0 100%00:00:00
5news.chimia.se 1 0 0 0 0 0%00:10:00
6news.tebibyte.org 2 0 0 0 0 0%00:03:00
7news.twilightnode.org 1 0 0 0 0 0%00:00:00
TOTAL: 7 77 16579 3990 10316 2273 24%81:09:02
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.4 MB0.0 KB4.7 MB16.0 MB 70%3.0 KB
2newsfeed.endofthelinebbs.com182.0 KB0.0 KB4.4 MB4.6 MB 3%6.7 KB
3news.sklaffkom.se160.9 KB0.0 KB57.2 KB218.2 KB 73%2.9 KB
4localhost0.5 KB0.0 KB0.0 KB0.5 KB100%0.5 KB
5news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
6news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
7news.twilightnode.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 711.7 MB0.0 KB9.1 MB20.8 MB 56%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 23 03:00:05 - 03:59:59 60 1.5% 0.02192.0 KB 1.5% 0.05
Jun 23 04:00:00 - 04:59:59 148 3.6% 0.04469.4 KB 3.8% 0.13
Jun 23 05:00:00 - 05:59:59 90 2.2% 0.03259.3 KB 2.1% 0.07
Jun 23 06:00:00 - 06:59:59 80 1.9% 0.02241.2 KB 1.9% 0.07
Jun 23 07:00:00 - 07:59:59 103 2.5% 0.03278.5 KB 2.2% 0.08
Jun 23 08:00:00 - 08:59:59 154 3.7% 0.04479.4 KB 3.9% 0.13
Jun 23 09:00:00 - 09:59:59 207 5.0% 0.06640.1 KB 5.1% 0.18
Jun 23 10:00:00 - 10:59:59 298 7.2% 0.08945.3 KB 7.6% 0.26
Jun 23 11:00:00 - 11:59:59 175 4.3% 0.05458.0 KB 3.7% 0.13
Jun 23 12:00:00 - 12:59:59 186 4.5% 0.05465.4 KB 3.7% 0.13
Jun 23 13:00:00 - 13:59:59 162 3.9% 0.04444.7 KB 3.6% 0.12
Jun 23 14:00:00 - 14:59:59 221 5.4% 0.06606.0 KB 4.9% 0.17
Jun 23 15:00:00 - 15:59:59 186 4.5% 0.05478.0 KB 3.8% 0.13
Jun 23 16:00:00 - 16:59:59 213 5.2% 0.06714.4 KB 5.7% 0.20
Jun 23 17:00:00 - 17:59:59 210 5.1% 0.06654.1 KB 5.3% 0.18
Jun 23 18:00:00 - 18:59:59 235 5.7% 0.07617.6 KB 5.0% 0.17
Jun 23 19:00:00 - 19:59:59 242 5.9% 0.07696.7 KB 5.6% 0.19
Jun 23 20:00:00 - 20:59:59 213 5.2% 0.06663.2 KB 5.3% 0.18
Jun 23 21:00:00 - 21:59:59 193 4.7% 0.05713.3 KB 5.7% 0.20
Jun 23 22:00:00 - 22:59:59 202 4.9% 0.06614.1 KB 4.9% 0.17
Jun 23 23:00:00 - 23:59:59 195 4.7% 0.05665.3 KB 5.3% 0.18
Jun 24 00:00:00 - 00:59:59 124 3.0% 0.03381.7 KB 3.1% 0.11
Jun 24 01:00:00 - 01:59:59 124 3.0% 0.03439.3 KB 3.5% 0.12
Jun 24 02:00:00 - 02:59:59 82 2.0% 0.02325.3 KB 2.6% 0.09
Jun 24 03:00:00 - 03:00:05 9 0.2% 1.807.2 KB 0.1% 1.45
TOTAL: 24:00:00 4112 100.0% 0.0512.2 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1685 1429 0 0 0 0 0 0 256
2newsfeed.endofthelinebbs.com 634 349 0 0 0 0 0 0 285
3news.tebibyte.org 48 34 0 0 0 0 0 0 14
4news.sklaffkom.se 27 27 0 0 0 0 0 0 0
TOTAL: 4 2394 1839 0 0 0 0 0 0 555

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 233
fido7.ru.news 150
uk.d-i-y 51
nl.politiek 39
fido7.ru.fidonet.today 37
de.rec.tv.technik 35
uk.legal.moderated 35
lada.talk 33
fido7.ukrnews 32
it.discussioni.misteri 31
it.sport.calcio.milan 31
it.hobby.cucina 29
linux.kernel.git 28
fido7.ru.home 27
alt.usage.english 26
uk.tech.digital-tv 26
de.alt.folklore.ddr 25
pl.soc.polityka 23
alt.checkmate 20
aus.politics 20
TOTAL: 230 1839

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 271
EMP (phl) 194
EMP (phn path) 62
HTML Multipart 6
Too many newsgroups 5
User-issued cancel 4
Binary: misplaced binary 1
EMP (phr path) 1
TOTAL: 8 544

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4114 4108 0 4 0 0 99%23:59:51
2sklaffkom.se 4454 671 2459 975 0 15 15%23:59:51
3weretis.net 211 1 188 0 0 0 0%13:36:51
4newsfeed.endofthelinebbs.com 2784 0 0 0 0 6804 0%23:59:49
5news.tebibyte.org 1906 0 0 0 0 4894 0%16:08:16
6sklaff.dev 0 0 0 0 0 4105 0%23:56:35
7thebunker.nu 0 0 0 0 0 4112 0%23:59:51
8usenet.blueworldhosting.com 0 0 0 0 0 3879 0%23:59:50
TOTAL: 8 13469 4780 2647 979 0 23809 35%173:40:54
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se12.2 MB5.7 KB12.2 MB0.1 KB/s3.0 KB23:59:51
2sklaffkom.se1.0 MB3.1 MB4.1 MB0.0 KB/s2.6 KB23:59:51
3weretis.net0.5 KB0.0 KB0.5 KB0.0 KB/s0.5 KB13:36:51
4news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s016:08:16
5newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
6sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:35
7thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:51
8usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
TOTAL: 813.2 MB3.1 MB16.3 MB0.0 KB/s2.9 KB173:40:54
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1mailgate.tebibyte.org 182 00.0 KB 0 0 000:00:52
266-228-53-4.ip.linodeusercontent.com 2 00.0 KB 0 0 000:00:00
3azpdcs3yt5er.stretchoid.com 2 00.0 KB 0 0 000:00:10
4119.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
5173-255-221-189.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
6198.235.24.167 1 00.0 KB 0 0 000:00:00
7203.55.131.5 1 00.0 KB 0 0 000:00:00
845-33-14-197.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:00
945-79-211-97.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:00
1069.5.169.116 1 00.0 KB 0 0 000:00:00
1169.5.169.220 1 00.0 KB 0 0 000:00:00
12o336.scanner.modat.io 1 00.0 KB 0 0 000:00:00
13twilightnode.org 1 00.0 KB 0 1 000:00:00
TOTAL: 13 196 00.0 KB 0 1 000:01:07

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.tebibyte.org 182 00.0 KB 0 0 000:00:52
2*.ip.linodeusercontent.com 5 00.0 KB 0 0 000:00:02
3unresolved 4 00.0 KB 0 0 000:00:01
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
5*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
6*.org 1 00.0 KB 0 1 000:00:00
7*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 196 00.0 KB 0 1 000:01:07

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mailgate.tebibyte.org 0.763 1.665 0.00000:00:52
azpdcs3yt5er.stretchoid.com 0.006 0.023 0.00000:00:10
173-255-221-189.ip.linodeusercontent.com 0.014 0.023 0.00000:00:01
119.195.132.66.censys-scanner.com 0.030 0.015 0.00000:00:01
66-228-53-4.ip.linodeusercontent.com 0.006 0.022 0.00000:00:00
45-33-14-197.ip.linodeusercontent.com 0.003 0.020 0.00000:00:00
198.235.24.167 0.006 0.013 0.00000:00:00
69.5.169.220 0.006 0.017 0.00000:00:00
203.55.131.5 0.006 0.007 0.00000:00:00
45-79-211-97.ip.linodeusercontent.com 0.009 0.011 0.00000:00:00
69.5.169.116 0.003 0.010 0.00000:00:00
twilightnode.org 0.003 0.033 0.00000:00:00
o336.scanner.modat.io 0.003 0.013 0.00000:00:00
TOTAL: 13 0.858 1.872 0.00000:01:07

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

SystemConn
66-228-53-4.ip.linodeusercontent.com 8
173-255-221-189.ip.linodeusercontent.com 2
119.195.132.66.censys-scanner.com 1
azpdcs3yt5er.stretchoid.com 1
TOTAL: 4 12

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

CommandCount
#001 2
#026#003#001 2
?q?#021J#013? 1
Accept-Encoding: gzip 1
Accept: */* 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
MGLNDD_172.234.115.63_119 1
User-Agent: Mozilla/5.0 (Macintosh; Inte... 1
TOTAL: 10 12

NNRP client timeouts [Top 20]:

SystemConnPeer
173-255-221-189.ip.linodeusercontent.com 1 1
69.5.169.116 1 1
TOTAL: 2 2 6
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