Daily Usenet report

Jul 4 03:00:05 -- Jul 5 03:00:05

Unknown entries from news log file:

First 50 / 111 lines (45.0%)

Jul 4 03:01:23 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 4 05:56:05 twilightnode nnrpd[3199347]: Reading access from /usr/local/news/etc/readers.conf
Jul 4 05:56:05 twilightnode nnrpd[3199347]: Auth strategy 'localhost' does not match client. Removing.
Jul 4 05:56:05 twilightnode nnrpd[3199347]: 193.176.31.214 res <anonymous>
Jul 4 05:56:05 twilightnode nnrpd[3199347]: 193.176.31.214 no_match_user <anonymous> <localhost>
Jul 4 05:56:05 twilightnode nnrpd[3199347]: 193.176.31.214 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 4 05:56:05 twilightnode nnrpd[3199347]: 193.176.31.214 match_user <anonymous> <anonymous>
Jul 4 05:56:05 twilightnode nnrpd[3199347]: 193.176.31.214 no_post anonymous-read
Jul 4 05:56:09 twilightnode nnrpd[3199348]: Reading access from /usr/local/news/etc/readers.conf
Jul 4 05:56:09 twilightnode nnrpd[3199348]: Auth strategy 'localhost' does not match client. Removing.
Jul 4 05:56:09 twilightnode nnrpd[3199348]: 5.226.140.73 res <anonymous>
Jul 4 05:56:09 twilightnode nnrpd[3199348]: 5.226.140.73 no_match_user <anonymous> <localhost>
Jul 4 05:56:09 twilightnode nnrpd[3199348]: 5.226.140.73 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 4 05:56:09 twilightnode nnrpd[3199348]: 5.226.140.73 match_user <anonymous> <anonymous>
Jul 4 05:56:09 twilightnode nnrpd[3199348]: 5.226.140.73 no_post anonymous-read
Jul 4 06:58:42 twilightnode nnrpd[3201839]: Reading access from /usr/local/news/etc/readers.conf
Jul 4 06:58:42 twilightnode nnrpd[3201839]: Auth strategy 'localhost' does not match client. Removing.
Jul 4 06:58:42 twilightnode nnrpd[3201839]: 190.186.132.66.censys-scanner.com res <anonymous>
Jul 4 06:58:42 twilightnode nnrpd[3201839]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 4 06:58:42 twilightnode nnrpd[3201839]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 4 06:58:42 twilightnode nnrpd[3201839]: 190.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 4 06:58:42 twilightnode nnrpd[3201839]: 190.186.132.66.censys-scanner.com no_post anonymous-read
Jul 4 06:58:50 twilightnode nnrpd[3201840]: Reading access from /usr/local/news/etc/readers.conf
Jul 4 06:58:50 twilightnode nnrpd[3201840]: Auth strategy 'localhost' does not match client. Removing.
Jul 4 06:58:50 twilightnode nnrpd[3201840]: 190.186.132.66.censys-scanner.com res <anonymous>
Jul 4 06:58:50 twilightnode nnrpd[3201840]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 4 06:58:50 twilightnode nnrpd[3201840]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 4 06:58:50 twilightnode nnrpd[3201840]: 190.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 4 06:58:50 twilightnode nnrpd[3201840]: 190.186.132.66.censys-scanner.com no_post anonymous-read
Jul 4 06:58:51 twilightnode nnrpd[3201841]: Reading access from /usr/local/news/etc/readers.conf
Jul 4 06:58:51 twilightnode nnrpd[3201841]: Auth strategy 'localhost' does not match client. Removing.
Jul 4 06:58:51 twilightnode nnrpd[3201841]: 190.186.132.66.censys-scanner.com res <anonymous>
Jul 4 06:58:51 twilightnode nnrpd[3201841]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 4 06:58:51 twilightnode nnrpd[3201841]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 4 06:58:51 twilightnode nnrpd[3201841]: 190.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 4 06:58:51 twilightnode nnrpd[3201841]: 190.186.132.66.censys-scanner.com no_post anonymous-read
Jul 4 06:58:52 twilightnode nnrpd[3201980]: Reading access from /usr/local/news/etc/readers.conf
Jul 4 06:58:52 twilightnode nnrpd[3201980]: Auth strategy 'localhost' does not match client. Removing.
Jul 4 06:58:52 twilightnode nnrpd[3201980]: 190.186.132.66.censys-scanner.com res <anonymous>
Jul 4 06:58:52 twilightnode nnrpd[3201980]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 4 06:58:52 twilightnode nnrpd[3201980]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 4 06:58:52 twilightnode nnrpd[3201980]: 190.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 4 06:58:52 twilightnode nnrpd[3201980]: 190.186.132.66.censys-scanner.com no_post anonymous-read
Jul 4 06:59:00 twilightnode nnrpd[3201981]: Reading access from /usr/local/news/etc/readers.conf
Jul 4 06:59:00 twilightnode nnrpd[3201981]: Auth strategy 'localhost' does not match client. Removing.
Jul 4 06:59:00 twilightnode nnrpd[3201981]: 190.186.132.66.censys-scanner.com res <anonymous>
Jul 4 06:59:00 twilightnode nnrpd[3201981]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 4 06:59:00 twilightnode nnrpd[3201981]: 190.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 4 06:59:00 twilightnode nnrpd[3201981]: 190.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 4 06:59:00 twilightnode nnrpd[3201981]: 190.186.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 41338 83.4%5.6 MB 78.5%
inn 6456 13.0%1.2 MB 16.9%
innd 1596 3.2%312.3 KB 4.3%
nnrpd 179 0.4%20.0 KB 0.3%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 49571 100.0%7.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15753 51.0%
Negative hits 8678 28.1%
Do not exist 6423 20.8%
Cache misses 18 0.1%
TOTAL: 4 30872 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.002 0.0%2 0.000 1.000 2.000
article cleanup00:00:00.083 0.0%6438 0.000 0.013 0.091
article logging00:00:00.242 0.0%6451 0.000 0.038 0.167
article parse00:00:00.128 0.0%8482 0.000 0.015 0.092
article write00:00:04.694 0.0%4518 0.512 1.039 3.870
data move00:00:00.069 0.0%32149 0.000 0.002 0.022
hisgrep/artcncl00:00:00.002 0.0%2 0.000 1.000 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.632 0.0%30943 0.090 0.150 0.316
history sync00:00:00.065 0.0%413 0.000 0.157 1.000
history write00:00:03.713 0.0%6438 0.182 0.577 1.100
idle24:00:24.265100.0%32006 1060.224 2700.252 14998.125
nntp read00:00:00.768 0.0%31671 0.000 0.024 0.105
overview write00:00:02.380 0.0%4518 0.100 0.527 2.875
perl filter00:00:11.332 0.0%6438 1.048 1.760 12.200
python filter00:00:00.953 0.0%6438 0.000 0.148 1.200
site send00:00:00.116 0.0%4518 0.000 0.026 0.143
TOTAL: 1724:00:53.444100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.020 0.0%10127 0.000 0.002 0.047
article prepare00:00:00.003 0.0%8676 0.000 0.000 0.013
article read00:00:00.530 0.0%2169 0.000 0.244 5.333
backlog stats00:00:00.044 0.0%64224 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.453 0.0%28839 0.000 0.016 0.054
data write00:00:00.766 0.0%24420 0.000 0.031 0.123
idle23:59:22.450100.0%64224 993.062 1344.707 1799.473
status file00:00:10.392 0.0%17137 0.423 0.606 0.805
TOTAL: 923:59:34.658100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:30.385100.0%20 50.000 1519.250 10379.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%145 0.000 0.000 0.000
TOTAL: 300:00:30.385100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 3 11555 3952 6464 1139 34%42:04:26
2newsfeed.endofthelinebbs.com 1 5039 170 4150 719 3%24:50:37
3news.sklaffkom.se 42 167 101 40 26 60%14:17:57
4news.tebibyte.org 1 1253 7 1231 15 0%05:46:17
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 72 18014 4230 11885 1899 23%86:59:17
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net10.8 MB0.0 KB5.5 MB16.2 MB 66%3.3 KB
2newsfeed.endofthelinebbs.com1.3 MB0.0 KB2.6 MB3.8 MB 32%4.4 KB
3news.sklaffkom.se427.7 KB0.0 KB53.6 KB481.3 KB 88%3.8 KB
4news.tebibyte.org13.5 KB0.0 KB40.4 KB53.9 KB 25%2.4 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 512.4 MB0.0 KB8.1 MB20.5 MB 60%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 4 03:00:05 - 03:59:59 87 1.9% 0.02295.0 KB 2.2% 0.08
Jul 4 04:00:00 - 04:59:59 91 2.0% 0.03268.1 KB 2.0% 0.07
Jul 4 05:00:00 - 05:59:59 93 2.1% 0.03264.3 KB 1.9% 0.07
Jul 4 06:00:00 - 06:59:59 116 2.6% 0.03266.8 KB 1.9% 0.07
Jul 4 07:00:00 - 07:59:59 183 4.0% 0.05486.1 KB 3.6% 0.14
Jul 4 08:00:00 - 08:59:59 176 3.9% 0.05454.4 KB 3.3% 0.13
Jul 4 09:00:00 - 09:59:59 175 3.9% 0.05534.1 KB 3.9% 0.15
Jul 4 10:00:00 - 10:59:59 217 4.8% 0.06606.6 KB 4.4% 0.17
Jul 4 11:00:00 - 11:59:59 210 4.6% 0.06717.7 KB 5.2% 0.20
Jul 4 12:00:00 - 12:59:59 233 5.2% 0.06793.9 KB 5.8% 0.22
Jul 4 13:00:00 - 13:59:59 219 4.8% 0.06579.4 KB 4.2% 0.16
Jul 4 14:00:00 - 14:59:59 173 3.8% 0.05475.0 KB 3.5% 0.13
Jul 4 15:00:00 - 15:59:59 275 6.1% 0.08777.0 KB 5.7% 0.22
Jul 4 16:00:00 - 16:59:59 236 5.2% 0.07632.0 KB 4.6% 0.18
Jul 4 17:00:00 - 17:59:59 266 5.9% 0.071.0 MB 7.8% 0.30
Jul 4 18:00:00 - 18:59:59 280 6.2% 0.081.0 MB 7.6% 0.29
Jul 4 19:00:00 - 19:59:59 247 5.5% 0.07721.4 KB 5.3% 0.20
Jul 4 20:00:00 - 20:59:59 224 5.0% 0.06632.1 KB 4.6% 0.18
Jul 4 21:00:00 - 21:59:59 213 4.7% 0.06794.6 KB 5.8% 0.22
Jul 4 22:00:00 - 22:59:59 223 4.9% 0.06621.4 KB 4.5% 0.17
Jul 4 23:00:00 - 23:59:59 201 4.4% 0.06538.3 KB 3.9% 0.15
Jul 5 00:00:00 - 00:59:59 146 3.2% 0.04356.8 KB 2.6% 0.10
Jul 5 01:00:00 - 01:59:59 121 2.7% 0.03361.2 KB 2.6% 0.10
Jul 5 02:00:00 - 02:59:59 108 2.4% 0.03390.9 KB 2.9% 0.11
Jul 5 03:00:00 - 03:00:05 11 0.2% 2.2015.0 KB 0.1% 3.01
TOTAL: 24:00:00 4524 100.0% 0.0513.4 MB 100.0% 0.16
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1170 954 0 0 0 0 0 0 216
2newsfeed.endofthelinebbs.com 694 71 0 0 0 0 0 0 623
3news.tebibyte.org 42 42 0 0 0 0 0 0 0
4news.sklaffkom.se 26 26 0 0 0 0 0 0 0
TOTAL: 4 1932 1093 0 0 0 0 0 0 839

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.debian.changes 69
alt.checkmate 55
alt.usage.english 49
de.talk.jokes 31
uk.legal.moderated 27
linux.debian.kernel 25
alt.recovery.aa 23
fido7.ru.home 23
hfx.general 23
pl.soc.polityka 22
lada.talk 20
cn.comp 19
linux.debian.user 19
pl.soc.prawo 18
eternal-september.talk 15
alt.bible 14
dk.politik 14
alt.english.usage 12
fido7.lorapvt.horo 12
linux.debian.maint.emacsen 12
TOTAL: 207 1093

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 623
EMP (phn path) 175
User-issued cancel 13
Too many newsgroups (meow) 8
HTML Multipart 5
Too many newsgroups 2
TOTAL: 6 826

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 4791 949 2345 1223 0 1 19%23:59:54
2news.tebibyte.org 2838 0 0 0 0 7354 0%23:59:56
3newsfeed.endofthelinebbs.com 2776 0 0 0 0 7119 0%23:59:56
4weretis.net 382 0 321 0 0 0 0%19:25:36
5chimia.se 0 0 0 0 0 4524 0%23:59:56
6sklaff.dev 0 0 0 0 0 4516 0%23:58:32
7thebunker.nu 0 0 0 0 0 4524 0%23:59:56
8usenet.blueworldhosting.com 0 0 0 0 0 4073 0%23:59:56
TOTAL: 8 10787 949 2666 1223 0 32111 8%187:23:42
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1sklaffkom.se1.5 MB4.7 MB6.2 MB0.1 KB/s2.9 KB23:59:54
2chimia.se0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
4newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
5sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:32
6thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
7usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
8weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s019:25:36
TOTAL: 81.5 MB4.7 MB6.2 MB0.0 KB/s2.9 KB187:23:42
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1190.186.132.66.censys-scanner.com 5 00.0 KB 0 0 000:00:08
2172.186.132.66.censys-scanner.com 4 00.0 KB 0 0 000:00:10
3azpdcs7c64su.stretchoid.com 2 00.0 KB 0 0 000:00:10
4193.176.31.214 1 00.0 KB 0 0 000:00:00
55.226.140.73 1 00.0 KB 0 0 000:00:00
6o335.scanner.modat.io 1 00.0 KB 0 0 000:00:00
7o348.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 15 00.0 KB 0 0 000:00:30

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 9 00.0 KB 0 0 000:00:19
2*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4unresolved 2 00.0 KB 0 0 000:00:00
TOTAL: 4 15 00.0 KB 0 0 000:00:30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
172.186.132.66.censys-scanner.com 0.018 0.039 0.00000:00:10
azpdcs7c64su.stretchoid.com 0.006 0.020 0.00000:00:10
190.186.132.66.censys-scanner.com 0.038 0.052 0.00000:00:08
o335.scanner.modat.io 0.005 0.021 0.00000:00:00
5.226.140.73 0.005 0.005 0.00000:00:00
193.176.31.214 0.008 0.022 0.00000:00:00
o348.scanner.modat.io 0.003 0.015 0.00000:00:00
TOTAL: 7 0.083 0.174 0.00000:00:30

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

SystemConn
190.186.132.66.censys-scanner.com 2
azpdcs7c64su.stretchoid.com 1
TOTAL: 2 3

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

CommandCount
#026#003#001 1
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
172.186.132.66.censys-scanner.com 1 4
190.186.132.66.censys-scanner.com 1 3
5.226.140.73 1 1
TOTAL: 3 3 9
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