Daily Usenet report

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

Unknown entries from news log file:

First 50 / 69 lines (72.5%)

Jul 22 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 22 06:01:01 twilightnode nnrpd[423739]: Reading access from /usr/local/news/etc/readers.conf
Jul 22 06:01:01 twilightnode nnrpd[423739]: Auth strategy 'localhost' does not match client. Removing.
Jul 22 06:01:01 twilightnode nnrpd[423739]: 185.216.145.163 res <anonymous>
Jul 22 06:01:01 twilightnode nnrpd[423739]: 185.216.145.163 no_match_user <anonymous> <localhost>
Jul 22 06:01:01 twilightnode nnrpd[423739]: 185.216.145.163 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 22 06:01:01 twilightnode nnrpd[423739]: 185.216.145.163 match_user <anonymous> <anonymous>
Jul 22 06:01:01 twilightnode nnrpd[423739]: 185.216.145.163 no_post anonymous-read
Jul 22 06:01:11 twilightnode nnrpd[423740]: Reading access from /usr/local/news/etc/readers.conf
Jul 22 06:01:11 twilightnode nnrpd[423740]: Auth strategy 'localhost' does not match client. Removing.
Jul 22 06:01:11 twilightnode nnrpd[423740]: 217.146.80.112 res <anonymous>
Jul 22 06:01:11 twilightnode nnrpd[423740]: 217.146.80.112 no_match_user <anonymous> <localhost>
Jul 22 06:01:11 twilightnode nnrpd[423740]: 217.146.80.112 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 22 06:01:11 twilightnode nnrpd[423740]: 217.146.80.112 match_user <anonymous> <anonymous>
Jul 22 06:01:11 twilightnode nnrpd[423740]: 217.146.80.112 no_post anonymous-read
Jul 22 07:01:41 twilightnode nnrpd[426501]: Reading access from /usr/local/news/etc/readers.conf
Jul 22 07:01:41 twilightnode nnrpd[426501]: Auth strategy 'localhost' does not match client. Removing.
Jul 22 07:01:41 twilightnode nnrpd[426501]: 47.251.30.16 res <anonymous>
Jul 22 07:01:41 twilightnode nnrpd[426501]: 47.251.30.16 no_match_user <anonymous> <localhost>
Jul 22 07:01:41 twilightnode nnrpd[426501]: 47.251.30.16 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 22 07:01:41 twilightnode nnrpd[426501]: 47.251.30.16 match_user <anonymous> <anonymous>
Jul 22 07:01:41 twilightnode nnrpd[426501]: 47.251.30.16 no_post anonymous-read
Jul 22 22:11:41 twilightnode nnrpd[466196]: Reading access from /usr/local/news/etc/readers.conf
Jul 22 22:11:41 twilightnode nnrpd[466196]: Auth strategy 'localhost' does not match client. Removing.
Jul 22 22:11:41 twilightnode nnrpd[466196]: 198.235.24.149 res <anonymous>
Jul 22 22:11:41 twilightnode nnrpd[466196]: 198.235.24.149 no_match_user <anonymous> <localhost>
Jul 22 22:11:41 twilightnode nnrpd[466196]: 198.235.24.149 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 22 22:11:41 twilightnode nnrpd[466196]: 198.235.24.149 match_user <anonymous> <anonymous>
Jul 22 22:11:41 twilightnode nnrpd[466196]: 198.235.24.149 no_post anonymous-read
Jul 22 23:30:40 twilightnode nnrpd[469601]: Reading access from /usr/local/news/etc/readers.conf
Jul 22 23:30:40 twilightnode nnrpd[469601]: Auth strategy 'localhost' does not match client. Removing.
Jul 22 23:30:40 twilightnode nnrpd[469601]: 71.6.241.136 res <anonymous>
Jul 22 23:30:40 twilightnode nnrpd[469601]: 71.6.241.136 no_match_user <anonymous> <localhost>
Jul 22 23:30:40 twilightnode nnrpd[469601]: 71.6.241.136 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 22 23:30:40 twilightnode nnrpd[469601]: 71.6.241.136 match_user <anonymous> <anonymous>
Jul 22 23:30:40 twilightnode nnrpd[469601]: 71.6.241.136 no_post anonymous-read
Jul 22 23:30:42 twilightnode nnrpd[469608]: Reading access from /usr/local/news/etc/readers.conf
Jul 22 23:30:42 twilightnode nnrpd[469608]: Auth strategy 'localhost' does not match client. Removing.
Jul 22 23:30:42 twilightnode nnrpd[469608]: 71.6.241.136 res <anonymous>
Jul 22 23:30:42 twilightnode nnrpd[469608]: 71.6.241.136 no_match_user <anonymous> <localhost>
Jul 22 23:30:42 twilightnode nnrpd[469608]: 71.6.241.136 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 22 23:30:42 twilightnode nnrpd[469608]: 71.6.241.136 match_user <anonymous> <anonymous>
Jul 22 23:30:42 twilightnode nnrpd[469608]: 71.6.241.136 no_post anonymous-read
Jul 22 23:31:53 twilightnode nnrpd[469622]: Reading access from /usr/local/news/etc/readers.conf
Jul 22 23:31:53 twilightnode nnrpd[469622]: Auth strategy 'localhost' does not match client. Removing.
Jul 22 23:31:53 twilightnode nnrpd[469622]: 71.6.241.136 res <anonymous>
Jul 22 23:31:53 twilightnode nnrpd[469622]: 71.6.241.136 no_match_user <anonymous> <localhost>
Jul 22 23:31:53 twilightnode nnrpd[469622]: 71.6.241.136 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 22 23:31:53 twilightnode nnrpd[469622]: 71.6.241.136 match_user <anonymous> <anonymous>
Jul 22 23:31:53 twilightnode nnrpd[469622]: 71.6.241.136 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6396 71.8%969.4 KB 72.1%
innfeed 1225 13.7%184.1 KB 13.7%
innd 1174 13.2%178.7 KB 13.3%
nnrpd 118 1.3%12.2 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 8914 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13928 48.0%
Negative hits 8729 30.1%
Do not exist 6361 21.9%
Cache misses 0 0.0%
TOTAL: 4 29018 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.010 0.0%6 0.500 1.667 2.500
article cleanup00:00:00.057 0.0%6379 0.000 0.009 0.143
article logging00:00:00.265 0.0%6401 0.000 0.041 0.188
article parse00:00:00.118 0.0%8570 0.000 0.014 0.222
article write00:00:04.729 0.0%4701 0.400 1.006 4.364
data move00:00:00.087 0.0%32080 0.000 0.003 0.024
hisgrep/artcncl00:00:00.005 0.0%6 0.000 0.833 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.391 0.0%29093 0.102 0.185 0.465
history sync00:00:00.076 0.0%415 0.000 0.183 1.000
history write00:00:03.612 0.0%6379 0.200 0.566 1.623
idle24:00:33.340100.0%31201 1419.567 2770.211 9837.131
nntp read00:00:00.834 0.0%30866 0.000 0.027 0.173
overview write00:00:02.954 0.0%4701 0.231 0.628 3.357
perl filter00:00:11.173 0.0%6379 1.000 1.752 20.312
python filter00:00:00.753 0.0%6379 0.000 0.118 3.438
site send00:00:00.144 0.0%4695 0.000 0.031 0.125
TOTAL: 1724:01:03.548100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.017 0.0%4855 0.000 0.004 0.133
article prepare00:00:00.001 0.0%16060 0.000 0.000 0.006
article read00:00:00.707 0.0%2932 0.000 0.241 4.500
backlog stats00:00:00.039 0.0%51958 0.000 0.001 0.017
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.486 0.0%28057 0.000 0.017 0.082
data write00:00:00.799 0.0%23273 0.000 0.034 0.135
idle23:50:56.734100.0%51958 439.568 1652.426 4151.435
status file00:00:00.158 0.0%222 0.000 0.712 5.000
TOTAL: 923:50:58.941100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:07.984100.0%14 107.000 570.286 1617.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%82 0.000 0.000 0.000
TOTAL: 300:00:07.984100.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 5 10855 4202 5447 1206 38%48:09:38
2newsfeed.endofthelinebbs.com 1 4042 396 3313 333 9%20:20:31
3news.tebibyte.org 1 4867 130 4718 19 2%23:59:52
4news.sklaffkom.se 35 50 25 15 10 50%06:49:20
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 67 19814 4753 13493 1568 23%99:19:21
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.3 MB0.0 KB3.8 MB17.1 MB 77%3.2 KB
2newsfeed.endofthelinebbs.com3.8 MB0.0 KB721.0 KB4.5 MB 84%6.3 KB
3news.tebibyte.org490.2 KB0.0 KB42.1 KB532.4 KB 92%3.6 KB
4news.sklaffkom.se92.1 KB0.0 KB17.0 KB109.1 KB 84%3.1 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 517.6 MB0.0 KB4.6 MB22.2 MB 79%3.6 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 22 03:00:05 - 03:59:59 114 2.4% 0.03671.1 KB 3.7% 0.19
Jul 22 04:00:00 - 04:59:59 125 2.7% 0.03616.1 KB 3.4% 0.17
Jul 22 05:00:00 - 05:59:59 128 2.7% 0.041.1 MB 6.0% 0.30
Jul 22 06:00:00 - 06:59:59 147 3.1% 0.04499.2 KB 2.8% 0.14
Jul 22 07:00:00 - 07:59:59 127 2.7% 0.04327.4 KB 1.8% 0.09
Jul 22 08:00:00 - 08:59:59 157 3.3% 0.04455.4 KB 2.5% 0.13
Jul 22 09:00:00 - 09:59:59 222 4.7% 0.06762.3 KB 4.2% 0.21
Jul 22 10:00:00 - 10:59:59 251 5.3% 0.07748.4 KB 4.1% 0.21
Jul 22 11:00:00 - 11:59:59 274 5.8% 0.08863.5 KB 4.8% 0.24
Jul 22 12:00:00 - 12:59:59 210 4.5% 0.06723.8 KB 4.0% 0.20
Jul 22 13:00:00 - 13:59:59 200 4.3% 0.06670.8 KB 3.7% 0.19
Jul 22 14:00:00 - 14:59:59 236 5.0% 0.07769.4 KB 4.3% 0.21
Jul 22 15:00:00 - 15:59:59 244 5.2% 0.07900.0 KB 5.0% 0.25
Jul 22 16:00:00 - 16:59:59 304 6.5% 0.081.2 MB 7.0% 0.35
Jul 22 17:00:00 - 17:59:59 221 4.7% 0.061004.1 KB 5.6% 0.28
Jul 22 18:00:00 - 18:59:59 227 4.8% 0.061013.4 KB 5.6% 0.28
Jul 22 19:00:00 - 19:59:59 264 5.6% 0.071015.5 KB 5.6% 0.28
Jul 22 20:00:00 - 20:59:59 228 4.9% 0.06781.8 KB 4.3% 0.22
Jul 22 21:00:00 - 21:59:59 238 5.1% 0.07828.4 KB 4.6% 0.23
Jul 22 22:00:00 - 22:59:59 223 4.7% 0.06900.5 KB 5.0% 0.25
Jul 22 23:00:00 - 23:59:59 192 4.1% 0.05844.3 KB 4.7% 0.23
Jul 23 00:00:00 - 00:59:59 150 3.2% 0.04582.8 KB 3.2% 0.16
Jul 23 01:00:00 - 01:59:59 117 2.5% 0.03394.9 KB 2.2% 0.11
Jul 23 02:00:00 - 02:59:59 91 1.9% 0.03321.9 KB 1.8% 0.09
Jul 23 03:00:00 - 03:00:04 9 0.2% 2.258.0 KB 0.0% 2.01
TOTAL: 23:59:59 4699 100.0% 0.0517.6 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1233 1094 0 0 0 0 0 0 139
2newsfeed.endofthelinebbs.com 412 101 0 0 0 0 0 0 311
3news.tebibyte.org 39 36 0 0 0 0 0 0 3
4news.sklaffkom.se 13 12 0 0 0 0 0 0 1
TOTAL: 4 1697 1243 0 0 0 0 0 0 454

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.checkmate 92
alt.usage.english 74
alt.slack 67
de.etc.haushalt 25
hfx.general 25
linux.debian.vote 25
alt.bestjobsusa.computer.jobs 23
linux.debian.kernel 23
de.soc.recht.misc 22
it.hobby.cucina 22
lada.talk 22
pl.soc.polityka 22
alt.food.fast-food 21
cn.comp 20
linux.debian.maint.ocaml.maint 20
pl.misc.elektronika 19
alt.home.repair 17
de.talk.jokes 17
it.sport.calcio.inter-fc 16
uk.legal.moderated 16
TOTAL: 220 1243

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 377
User-issued cancel 35
EMP (phn path) 16
HTML Multipart 4
TOTAL: 4 432

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6636 1112 3574 7 0 5 16%23:59:55
2sklaffkom.se 5130 819 1817 2055 0 5 15%23:59:18
3newsfeed.endofthelinebbs.com 7635 24 4213 6 0 171 0%23:59:55
4weretis.net 560 3 554 0 0 1 0%21:34:47
TOTAL: 4 19961 1958 10158 2068 0 182 9%93:33:55
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.8 MB779.7 KB3.6 MB0.0 KB/s3.3 KB23:59:55
2sklaffkom.se1.3 MB10.4 MB11.7 MB0.1 KB/s4.2 KB23:59:18
3newsfeed.endofthelinebbs.com86.0 KB5.9 KB91.9 KB0.0 KB/s3.1 KB23:59:55
4weretis.net8.1 KB0.0 KB8.1 KB0.0 KB/s2.7 KB21:34:47
TOTAL: 44.3 MB11.2 MB15.4 MB0.0 KB/s3.9 KB93:33:55
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
171.6.241.136 3 00.0 KB 0 0 000:00:01
2128.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
3147.185.133.188 1 00.0 KB 0 0 000:00:01
4185.216.145.163 1 00.0 KB 0 0 000:00:00
5198.235.24.149 1 00.0 KB 0 0 000:00:00
6217.146.80.112 1 00.0 KB 0 0 000:00:00
747.251.30.16 1 00.0 KB 0 0 000:00:03
TOTAL: 7 9 00.0 KB 0 0 000:00:07

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 8 00.0 KB 0 0 000:00:06
2*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
TOTAL: 2 9 00.0 KB 0 0 000:00:07

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47.251.30.16 0.003 0.013 0.00000:00:03
128.172.132.66.censys-scanner.com 0.029 0.018 0.00000:00:01
71.6.241.136 0.010 0.024 0.00000:00:01
147.185.133.188 0.007 0.005 0.00000:00:01
198.235.24.149 0.006 0.010 0.00000:00:00
185.216.145.163 0.010 0.016 0.00000:00:00
217.146.80.112 0.002 0.006 0.00000:00:00
TOTAL: 7 0.067 0.092 0.00000:00:07

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

SystemConn
147.185.133.188 4
71.6.241.136 2
128.172.132.66.censys-scanner.com 1
TOTAL: 3 7

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

CommandCount
#023#003#023#001#023#002#001 1
#026#003#001 1
Accept-Encoding: gzip 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
User-Agent: Hello from Palo Alto Network... 1
TOTAL: 7 7

NNRP client timeouts [Top 20]:

SystemConnPeer
217.146.80.112 1 1
47.251.30.16 1 1
TOTAL: 2 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