Daily Usenet report

Jun 4 03:00:05 -- Jun 5 03:00:04

Unknown entries from news log file:

First 50 / 104 lines (48.1%)

Jun 4 03:00:05 twilightnode nnrpd[1214280]: Reading access from /usr/local/news/etc/readers.conf
Jun 4 03:00:05 twilightnode nnrpd[1214280]: Auth strategy 'localhost' does not match client. Removing.
Jun 4 03:00:05 twilightnode nnrpd[1214280]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
Jun 4 03:00:05 twilightnode nnrpd[1214280]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
Jun 4 03:00:05 twilightnode nnrpd[1214280]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 4 03:00:05 twilightnode nnrpd[1214280]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
Jun 4 03:00:05 twilightnode nnrpd[1214280]: 195-252-55-9-no600.tbcn.telia.com no_post anonymous-read
Jun 4 03:00:35 twilightnode nnrpd[1214507]: Reading access from /usr/local/news/etc/readers.conf
Jun 4 03:00:35 twilightnode nnrpd[1214507]: Auth strategy 'localhost' does not match client. Removing.
Jun 4 03:00:35 twilightnode nnrpd[1214507]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
Jun 4 03:00:35 twilightnode nnrpd[1214507]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
Jun 4 03:00:35 twilightnode nnrpd[1214507]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 4 03:00:35 twilightnode nnrpd[1214507]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
Jun 4 03:00:35 twilightnode nnrpd[1214507]: 195-252-55-9-no600.tbcn.telia.com no_post anonymous-read
Jun 4 03:01:22 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 4 05:45:41 twilightnode nnrpd[1221092]: Reading access from /usr/local/news/etc/readers.conf
Jun 4 05:45:41 twilightnode nnrpd[1221092]: Auth strategy 'localhost' does not match client. Removing.
Jun 4 05:45:41 twilightnode nnrpd[1221092]: 62.164.177.41 res <anonymous>
Jun 4 05:45:41 twilightnode nnrpd[1221092]: 62.164.177.41 no_match_user <anonymous> <localhost>
Jun 4 05:45:41 twilightnode nnrpd[1221092]: 62.164.177.41 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 4 05:45:41 twilightnode nnrpd[1221092]: 62.164.177.41 match_user <anonymous> <anonymous>
Jun 4 05:45:41 twilightnode nnrpd[1221092]: 62.164.177.41 no_post anonymous-read
Jun 4 10:05:11 twilightnode nnrpd[1234441]: Reading access from /usr/local/news/etc/readers.conf
Jun 4 10:05:11 twilightnode nnrpd[1234441]: Auth strategy 'localhost' does not match client. Removing.
Jun 4 10:05:11 twilightnode nnrpd[1234441]: r5-202-ca.monitoring.internet-measurement.com res <anonymous>
Jun 4 10:05:11 twilightnode nnrpd[1234441]: r5-202-ca.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Jun 4 10:05:11 twilightnode nnrpd[1234441]: r5-202-ca.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 4 10:05:11 twilightnode nnrpd[1234441]: r5-202-ca.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Jun 4 10:05:11 twilightnode nnrpd[1234441]: r5-202-ca.monitoring.internet-measurement.com no_post anonymous-read
Jun 4 11:51:49 twilightnode nnrpd[1239707]: Reading access from /usr/local/news/etc/readers.conf
Jun 4 11:51:49 twilightnode nnrpd[1239707]: Auth strategy 'localhost' does not match client. Removing.
Jun 4 11:51:49 twilightnode nnrpd[1239707]: 44.195.132.66.censys-scanner.com res <anonymous>
Jun 4 11:51:49 twilightnode nnrpd[1239707]: 44.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 4 11:51:49 twilightnode nnrpd[1239707]: 44.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 4 11:51:49 twilightnode nnrpd[1239707]: 44.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 4 11:51:49 twilightnode nnrpd[1239707]: 44.195.132.66.censys-scanner.com no_post anonymous-read
Jun 4 11:51:56 twilightnode nnrpd[1239710]: Reading access from /usr/local/news/etc/readers.conf
Jun 4 11:51:56 twilightnode nnrpd[1239710]: Auth strategy 'localhost' does not match client. Removing.
Jun 4 11:51:56 twilightnode nnrpd[1239710]: 44.195.132.66.censys-scanner.com res <anonymous>
Jun 4 11:51:56 twilightnode nnrpd[1239710]: 44.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 4 11:51:56 twilightnode nnrpd[1239710]: 44.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 4 11:51:56 twilightnode nnrpd[1239710]: 44.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 4 11:51:56 twilightnode nnrpd[1239710]: 44.195.132.66.censys-scanner.com no_post anonymous-read
Jun 4 11:52:04 twilightnode nnrpd[1239717]: Reading access from /usr/local/news/etc/readers.conf
Jun 4 11:52:04 twilightnode nnrpd[1239717]: Auth strategy 'localhost' does not match client. Removing.
Jun 4 11:52:04 twilightnode nnrpd[1239717]: 44.195.132.66.censys-scanner.com res <anonymous>
Jun 4 11:52:04 twilightnode nnrpd[1239717]: 44.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 4 11:52:04 twilightnode nnrpd[1239717]: 44.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 4 11:52:04 twilightnode nnrpd[1239717]: 44.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 4 11:52:04 twilightnode nnrpd[1239717]: 44.195.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 5617 67.1%892.2 KB 71.2%
innfeed 1544 18.4%196.4 KB 15.7%
innd 1028 12.3%145.4 KB 11.6%
nnrpd 173 2.1%17.8 KB 1.4%
controlchan 13 0.2%1.8 KB 0.1%
TOTAL: 5 8375 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6077 35.4%
Positive hits 5572 32.4%
Do not exist 5531 32.2%
Cache misses 0 0.0%
TOTAL: 4 17180 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.014 0.0%2 6.000 7.000 8.000
article cleanup00:00:00.076 0.0%5553 0.000 0.014 0.111
article logging00:00:00.221 0.0%5579 0.000 0.040 0.357
article parse00:00:00.099 0.0%6889 0.000 0.014 0.100
article write00:00:03.481 0.0%4034 0.286 0.863 2.300
data move00:00:00.070 0.0%18628 0.000 0.004 0.081
hisgrep/artcncl00:00:00.004 0.0%2 1.000 2.000 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.239 0.0%17247 0.129 0.246 0.958
history sync00:00:00.059 0.0%406 0.000 0.145 1.000
history write00:00:02.923 0.0%5553 0.235 0.526 1.190
idle23:50:38.363100.0%18550 2563.197 4627.405 20016.633
nntp read00:00:00.508 0.0%18171 0.000 0.028 0.109
overview write00:00:01.941 0.0%4034 0.133 0.481 2.176
perl filter00:00:10.621 0.0%5553 0.907 1.913 15.833
python filter00:00:00.904 0.0%5553 0.032 0.163 4.889
site send00:00:00.125 0.0%4043 0.000 0.031 0.250
TOTAL: 1723:51:03.648100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.018 0.0%5217 0.000 0.003 0.105
article prepare00:00:00.000 0.0%23724 0.000 0.000 0.000
article read00:00:00.723 0.0%4458 0.000 0.162 0.524
backlog stats00:00:00.032 0.0%24681 0.000 0.001 0.014
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.166 0.0%13100 0.000 0.013 0.171
data write00:00:00.278 0.0%9153 0.000 0.030 0.128
idle23:52:06.985100.0%24681 659.522 3481.503 7910.789
status file00:00:00.259 0.0%302 0.000 0.858 40.000
TOTAL: 923:52:08.461100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:10:24.553100.0%20 42.000 31227.650299894.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%113 0.000 0.000 0.000
TOTAL: 300:10:24.553100.0%----

Control commands to innd:

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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de090009
TOTAL090009

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 18 10565 3971 5147 1447 37%45:28:04
2news.sklaffkom.se 23 77 38 27 12 49%07:12:29
3localhost 25 0 0 0 0 0%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 4 67 10642 4009 5174 1459 37%52:50:33
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.9 MB0.0 KB5.4 MB17.3 MB 68%3.3 KB
2news.sklaffkom.se140.4 KB0.0 KB22.5 KB162.9 KB 86%3.3 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
4news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 412.0 MB0.0 KB5.4 MB17.5 MB 68%3.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 4 03:00:05 - 03:59:59 73 1.8% 0.02284.5 KB 2.3% 0.08
Jun 4 04:00:00 - 04:59:59 82 2.0% 0.02253.6 KB 2.1% 0.07
Jun 4 05:00:00 - 05:59:59 93 2.3% 0.03294.1 KB 2.4% 0.08
Jun 4 06:00:00 - 06:59:59 135 3.3% 0.04297.1 KB 2.4% 0.08
Jun 4 07:00:00 - 07:59:59 162 4.0% 0.04458.8 KB 3.7% 0.13
Jun 4 08:00:00 - 08:59:59 173 4.3% 0.05405.1 KB 3.3% 0.11
Jun 4 09:00:00 - 09:59:59 182 4.5% 0.05546.2 KB 4.5% 0.15
Jun 4 10:00:00 - 10:59:59 214 5.3% 0.06505.6 KB 4.1% 0.14
Jun 4 11:00:00 - 11:59:59 206 5.1% 0.06558.2 KB 4.6% 0.16
Jun 4 12:00:00 - 12:59:59 161 4.0% 0.04435.1 KB 3.6% 0.12
Jun 4 13:00:00 - 13:59:59 200 4.9% 0.06528.3 KB 4.3% 0.15
Jun 4 14:00:00 - 14:59:59 200 4.9% 0.06647.9 KB 5.3% 0.18
Jun 4 15:00:00 - 15:59:59 192 4.7% 0.05460.2 KB 3.8% 0.13
Jun 4 16:00:00 - 16:59:59 218 5.4% 0.06671.1 KB 5.5% 0.19
Jun 4 17:00:00 - 17:59:59 221 5.4% 0.061.0 MB 8.6% 0.29
Jun 4 18:00:00 - 18:59:59 240 5.9% 0.07847.6 KB 6.9% 0.24
Jun 4 19:00:00 - 19:59:59 209 5.1% 0.06595.5 KB 4.9% 0.17
Jun 4 20:00:00 - 20:59:59 206 5.1% 0.06576.3 KB 4.7% 0.16
Jun 4 21:00:00 - 21:59:59 202 5.0% 0.06637.9 KB 5.2% 0.18
Jun 4 22:00:00 - 22:59:59 175 4.3% 0.05597.8 KB 4.9% 0.17
Jun 4 23:00:00 - 23:59:59 179 4.4% 0.05570.8 KB 4.7% 0.16
Jun 5 00:00:00 - 00:59:59 118 2.9% 0.03345.9 KB 2.8% 0.10
Jun 5 01:00:00 - 01:59:59 115 2.8% 0.03346.8 KB 2.8% 0.10
Jun 5 02:00:00 - 02:59:59 97 2.4% 0.03308.6 KB 2.5% 0.09
Jun 5 03:00:00 - 03:00:04 9 0.2% 2.257.6 KB 0.1% 1.90
TOTAL: 23:59:59 4062 100.0% 0.0512.0 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1543 1269 0 0 0 0 0 0 274
2news.sklaffkom.se 12 12 0 0 0 0 0 0 0
TOTAL: 2 1555 1281 0 0 0 0 0 0 274

Unwanted newsgroups [Top 20]:

NewsgroupCount
pl.soc.prawo 53
fr.sci.maths 50
nl.politiek 47
fido7.ru.military 46
it.hobby.cucina 43
cn.comp 40
it.sport.calcio.milan 40
uk.legal.moderated 37
de.alt.talk.unmut 34
de.talk.jokes 30
it.discussioni.auto 30
uk.d-i-y 27
dk.politik 25
linux.debian.l10n.german 22
pl.soc.polityka 21
fido7.ru.fidonet.today 20
lada.talk 19
linux.debian.user 19
alt.slack 18
it.tlc.cellulari.android 18
TOTAL: 213 1281

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phl) 185
Too many newsgroups (meow) 32
User-issued cancel 16
EMP (md5) 7
EMP (phn path) 4
Too many newsgroups 4
TOTAL: 6 248

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4064 4034 0 28 0 0 99%23:59:51
2thebunker.nu 566 454 0 92 0 3518 80%23:59:51
3sklaffkom.se 3244 440 1745 922 0 1191 13%16:49:27
4weretis.net 54 0 38 0 0 0 0%04:48:20
5sklaff.dev 0 0 0 0 0 4056 0%23:54:32
6usenet.blueworldhosting.com 0 0 0 0 0 3627 0%23:59:50
TOTAL: 6 7928 4928 1783 1042 0 12392 62%117:31:51
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se11.9 MB41.5 KB12.0 MB0.1 KB/s3.0 KB23:59:51
2thebunker.nu1.3 MB277.6 KB1.6 MB0.0 KB/s2.9 KB23:59:51
3sklaffkom.se719.2 KB2.9 MB3.6 MB0.1 KB/s2.7 KB16:49:27
4sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:32
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s004:48:20
TOTAL: 613.9 MB3.3 MB17.2 MB0.0 KB/s2.9 KB117:31:51
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
144.195.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:09
2195-252-55-9-no600.tbcn.telia.com 2 00.0 KB 0 0 000:10:00
3azpdcgx66h89.stretchoid.com 2 00.0 KB 0 0 000:00:10
4193.176.31.199 1 00.0 KB 0 0 000:00:00
5194.88.98.94 1 00.0 KB 0 0 000:00:00
6205.210.31.148 1 00.0 KB 0 0 000:00:00
762.164.177.41 1 00.0 KB 0 0 000:00:01
869.5.169.108 1 00.0 KB 0 0 000:00:00
969.5.169.99 1 00.0 KB 0 0 000:00:00
10r5-202-ca.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 10 14 00.0 KB 0 0 000:10:24

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 6 00.0 KB 0 0 000:00:02
2*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:09
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.tbcn.telia.com 2 00.0 KB 0 0 000:10:00
5*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 5 14 00.0 KB 0 0 000:10:24

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.010 0.027 0.00000:10:00
azpdcgx66h89.stretchoid.com 0.007 0.037 0.00000:00:10
44.195.132.66.censys-scanner.com 0.028 0.032 0.00000:00:09
r5-202-ca.monitoring.internet-measurement.com 0.008 0.009 0.00000:00:01
62.164.177.41 0.006 0.016 0.00000:00:01
194.88.98.94 0.003 0.006 0.00000:00:00
69.5.169.99 0.004 0.006 0.00000:00:00
205.210.31.148 0.004 0.011 0.00000:00:00
193.176.31.199 0.004 0.010 0.00000:00:00
69.5.169.108 0.007 0.015 0.00000:00:00
TOTAL: 10 0.081 0.169 0.00000:10:24

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

SystemConn
44.195.132.66.censys-scanner.com 1
62.164.177.41 1
azpdcgx66h89.stretchoid.com 1
TOTAL: 3 3

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
194.88.98.94 1 1
44.195.132.66.censys-scanner.com 1 2
62.164.177.41 1 1
69.5.169.99 1 1
TOTAL: 4 4 8
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