Daily Usenet report

Jun 18 03:00:06 -- Jun 19 03:00:05

Unknown entries from news log file:

First 50 / 117 lines (42.7%)

Jun 18 03:01:17 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 18 07:03:21 twilightnode nnrpd[2162976]: Reading access from /usr/local/news/etc/readers.conf
Jun 18 07:03:21 twilightnode nnrpd[2162976]: Auth strategy 'localhost' does not match client. Removing.
Jun 18 07:03:21 twilightnode nnrpd[2162976]: azpdsg56zpoo.stretchoid.com res <anonymous>
Jun 18 07:03:21 twilightnode nnrpd[2162976]: azpdsg56zpoo.stretchoid.com no_match_user <anonymous> <localhost>
Jun 18 07:03:21 twilightnode nnrpd[2162976]: azpdsg56zpoo.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 18 07:03:21 twilightnode nnrpd[2162976]: azpdsg56zpoo.stretchoid.com match_user <anonymous> <anonymous>
Jun 18 07:03:21 twilightnode nnrpd[2162976]: azpdsg56zpoo.stretchoid.com no_post anonymous-read
Jun 18 07:03:32 twilightnode nnrpd[2162978]: Reading access from /usr/local/news/etc/readers.conf
Jun 18 07:03:32 twilightnode nnrpd[2162978]: Auth strategy 'localhost' does not match client. Removing.
Jun 18 07:03:32 twilightnode nnrpd[2162978]: azpdsg56zpoo.stretchoid.com res <anonymous>
Jun 18 07:03:32 twilightnode nnrpd[2162978]: azpdsg56zpoo.stretchoid.com no_match_user <anonymous> <localhost>
Jun 18 07:03:32 twilightnode nnrpd[2162978]: azpdsg56zpoo.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 18 07:03:32 twilightnode nnrpd[2162978]: azpdsg56zpoo.stretchoid.com match_user <anonymous> <anonymous>
Jun 18 07:03:32 twilightnode nnrpd[2162978]: azpdsg56zpoo.stretchoid.com no_post anonymous-read
Jun 18 08:10:26 twilightnode nnrpd[2166307]: Reading access from /usr/local/news/etc/readers.conf
Jun 18 08:10:26 twilightnode nnrpd[2166307]: Auth strategy 'localhost' does not match client. Removing.
Jun 18 08:10:26 twilightnode nnrpd[2166307]: 160.119.76.61 res <anonymous>
Jun 18 08:10:26 twilightnode nnrpd[2166307]: 160.119.76.61 no_match_user <anonymous> <localhost>
Jun 18 08:10:26 twilightnode nnrpd[2166307]: 160.119.76.61 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 18 08:10:26 twilightnode nnrpd[2166307]: 160.119.76.61 match_user <anonymous> <anonymous>
Jun 18 08:10:26 twilightnode nnrpd[2166307]: 160.119.76.61 no_post anonymous-read
Jun 18 08:10:26 twilightnode nnrpd[2166308]: Reading access from /usr/local/news/etc/readers.conf
Jun 18 08:10:26 twilightnode nnrpd[2166308]: Auth strategy 'localhost' does not match client. Removing.
Jun 18 08:10:26 twilightnode nnrpd[2166308]: 160.119.76.61 res <anonymous>
Jun 18 08:10:26 twilightnode nnrpd[2166308]: 160.119.76.61 no_match_user <anonymous> <localhost>
Jun 18 08:10:26 twilightnode nnrpd[2166308]: 160.119.76.61 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 18 08:10:26 twilightnode nnrpd[2166308]: 160.119.76.61 match_user <anonymous> <anonymous>
Jun 18 08:10:26 twilightnode nnrpd[2166308]: 160.119.76.61 no_post anonymous-read
Jun 18 08:17:31 twilightnode nnrpd[2166468]: Reading access from /usr/local/news/etc/readers.conf
Jun 18 08:17:31 twilightnode nnrpd[2166468]: Auth strategy 'localhost' does not match client. Removing.
Jun 18 08:17:31 twilightnode nnrpd[2166468]: 160.119.76.61 res <anonymous>
Jun 18 08:17:31 twilightnode nnrpd[2166468]: 160.119.76.61 no_match_user <anonymous> <localhost>
Jun 18 08:17:31 twilightnode nnrpd[2166468]: 160.119.76.61 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 18 08:17:31 twilightnode nnrpd[2166468]: 160.119.76.61 match_user <anonymous> <anonymous>
Jun 18 08:17:31 twilightnode nnrpd[2166468]: 160.119.76.61 no_post anonymous-read
Jun 18 09:55:18 twilightnode nnrpd[2171048]: Reading access from /usr/local/news/etc/readers.conf
Jun 18 09:55:18 twilightnode nnrpd[2171048]: Auth strategy 'localhost' does not match client. Removing.
Jun 18 09:55:18 twilightnode nnrpd[2171048]: 160.119.76.61 res <anonymous>
Jun 18 09:55:18 twilightnode nnrpd[2171048]: 160.119.76.61 no_match_user <anonymous> <localhost>
Jun 18 09:55:18 twilightnode nnrpd[2171048]: 160.119.76.61 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 18 09:55:18 twilightnode nnrpd[2171048]: 160.119.76.61 match_user <anonymous> <anonymous>
Jun 18 09:55:18 twilightnode nnrpd[2171048]: 160.119.76.61 no_post anonymous-read
Jun 18 13:49:19 twilightnode nnrpd[2183299]: Reading access from /usr/local/news/etc/readers.conf
Jun 18 13:49:19 twilightnode nnrpd[2183299]: Auth strategy 'localhost' does not match client. Removing.
Jun 18 13:49:19 twilightnode nnrpd[2183299]: 100.172.132.66.censys-scanner.com res <anonymous>
Jun 18 13:49:19 twilightnode nnrpd[2183299]: 100.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 18 13:49:19 twilightnode nnrpd[2183299]: 100.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 18 13:49:19 twilightnode nnrpd[2183299]: 100.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 18 13:49:19 twilightnode nnrpd[2183299]: 100.172.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 7814 61.2%1.1 MB 65.2%
innd 3167 24.8%381.7 KB 21.3%
innfeed 1574 12.3%215.8 KB 12.1%
nnrpd 204 1.6%22.0 KB 1.2%
controlchan 14 0.1%2.4 KB 0.1%
TOTAL: 5 12773 100.0%1.7 MB100.0%

History cache:

ReasonCount%Count
Negative hits 8260 34.4%
Positive hits 7906 33.0%
Do not exist 7776 32.4%
Cache misses 36 0.2%
TOTAL: 4 23978 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.004 0.0%3 0.000 1.333 2.000
article cleanup00:00:00.082 0.0%7790 0.000 0.011 0.071
article logging00:00:00.344 0.0%7821 0.000 0.044 0.185
article parse00:00:00.096 0.0%10153 0.000 0.009 0.107
article write00:00:04.547 0.0%4146 0.388 1.097 11.733
data move00:00:00.078 0.0%19403 0.000 0.004 0.114
hisgrep/artcncl00:00:00.003 0.0%3 0.000 1.000 1.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.924 0.0%24033 0.079 0.246 6.796
history sync00:00:00.057 0.0%368 0.000 0.155 1.500
history write00:00:03.976 0.0%7790 0.143 0.510 9.657
idle24:00:13.142100.0%19777 701.883 4369.376 22248.074
nntp read00:00:00.599 0.0%19423 0.000 0.031 0.108
overview write00:00:02.583 0.0%4146 0.153 0.623 21.688
perl filter00:00:12.075 0.0%7790 0.643 1.550 9.405
python filter00:00:00.945 0.0%7792 0.000 0.121 0.541
site send00:00:00.109 0.0%4158 0.000 0.026 0.222
TOTAL: 1724:00:44.564100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.021 0.0%4132 0.000 0.005 0.077
article prepare00:00:00.001 0.0%23872 0.000 0.000 0.005
article read00:00:00.737 0.0%4132 0.000 0.178 0.933
backlog stats00:00:00.033 0.0%27154 0.000 0.001 0.015
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.243 0.0%14907 0.000 0.016 0.090
data write00:00:00.283 0.0%10859 0.000 0.026 0.091
idle23:59:45.493100.0%27154 1207.841 3181.317 8468.282
status file00:00:00.244 0.0%311 0.000 0.785 16.000
TOTAL: 923:59:47.055100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:32.660100.0%30 3.000 1088.667 10373.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%116 0.000 0.017 0.167
TOTAL: 300:00:32.662100.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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de3900012
TOTAL3900012

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 22 15278 4043 7646 3589 26%46:12:50
2news.sklaffkom.se 47 131 65 41 25 49%12:33:35
3localhost 27 2 2 0 0 100%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
5news.twilightnode.org 2 0 0 0 0 0%00:00:00
TOTAL: 5 99 15411 4110 7687 3614 26%58:56:25
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.2 MB0.0 KB10.0 MB21.2 MB 52%2.8 KB
2news.sklaffkom.se168.9 KB0.0 KB61.1 KB230.0 KB 73%2.6 KB
3localhost1.7 KB0.0 KB0.0 KB1.7 KB100%0.9 KB
4news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5news.twilightnode.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 511.3 MB0.0 KB10.1 MB21.4 MB 52%2.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 18 03:00:06 - 03:59:59 91 2.2% 0.03273.4 KB 2.3% 0.08
Jun 18 04:00:00 - 04:59:59 106 2.6% 0.03297.6 KB 2.5% 0.08
Jun 18 05:00:00 - 05:59:59 111 2.7% 0.03300.4 KB 2.6% 0.08
Jun 18 06:00:00 - 06:59:59 114 2.8% 0.03322.7 KB 2.8% 0.09
Jun 18 07:00:00 - 07:59:59 188 4.5% 0.05561.5 KB 4.8% 0.16
Jun 18 08:00:00 - 08:59:59 187 4.5% 0.05508.5 KB 4.3% 0.14
Jun 18 09:00:00 - 09:59:59 192 4.6% 0.05663.7 KB 5.7% 0.18
Jun 18 10:00:00 - 10:59:59 164 4.0% 0.05423.4 KB 3.6% 0.12
Jun 18 11:00:00 - 11:59:59 176 4.3% 0.05482.9 KB 4.1% 0.13
Jun 18 12:00:00 - 12:59:59 152 3.7% 0.04431.4 KB 3.7% 0.12
Jun 18 13:00:00 - 13:59:59 167 4.0% 0.05518.4 KB 4.4% 0.14
Jun 18 14:00:00 - 14:59:59 219 5.3% 0.06603.7 KB 5.1% 0.17
Jun 18 15:00:00 - 15:59:59 190 4.6% 0.05482.8 KB 4.1% 0.13
Jun 18 16:00:00 - 16:59:59 215 5.2% 0.06634.4 KB 5.4% 0.18
Jun 18 17:00:00 - 17:59:59 278 6.7% 0.08736.1 KB 6.3% 0.20
Jun 18 18:00:00 - 18:59:59 223 5.4% 0.06628.9 KB 5.4% 0.17
Jun 18 19:00:00 - 19:59:59 222 5.4% 0.06545.6 KB 4.7% 0.15
Jun 18 20:00:00 - 20:59:59 180 4.3% 0.05499.9 KB 4.3% 0.14
Jun 18 21:00:00 - 21:59:59 222 5.4% 0.06597.3 KB 5.1% 0.17
Jun 18 22:00:00 - 22:59:59 157 3.8% 0.04428.3 KB 3.7% 0.12
Jun 18 23:00:00 - 23:59:59 184 4.4% 0.05616.9 KB 5.3% 0.17
Jun 19 00:00:00 - 00:59:59 173 4.2% 0.05528.1 KB 4.5% 0.15
Jun 19 01:00:00 - 01:59:59 92 2.2% 0.03204.0 KB 1.7% 0.06
Jun 19 02:00:00 - 02:59:59 129 3.1% 0.04434.3 KB 3.7% 0.12
Jun 19 03:00:00 - 03:00:05 9 0.2% 1.807.3 KB 0.1% 1.46
TOTAL: 23:59:59 4141 100.0% 0.0511.5 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 3647 1334 0 0 0 0 0 0 2313
2news.sklaffkom.se 26 25 0 0 0 0 0 0 1
TOTAL: 2 3673 1359 0 0 0 0 0 0 2314

Unwanted newsgroups [Top 20]:

NewsgroupCount
fido7.ru.news 150
de.alt.talk.unmut 50
alt.usage.english 45
lada.talk 42
it.sport.calcio.milan 40
fido7.ru.military 31
aus.politics 26
nl.politiek 25
alt.prophecies.nostradamus 23
fido7.ru.fidonet.today 22
alt.food.fast-food 21
alt.slack 20
dk.politik 20
de.rec.fahrrad 17
muc.lists.freebsd.doc 17
fr.sci.maths 16
pl.misc.telefonia.gsm 16
bln.test 15
fido7.su.general 15
linux.gentoo.dev 15
TOTAL: 226 1359

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 2249
User-issued cancel 23
HTML Multipart 7
Too many newsgroups 2
Too many newsgroups (meow) 2
TOTAL: 5 2283

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4142 4141 0 0 0 0 99%23:59:55
2sklaffkom.se 4495 741 2299 1096 0 0 16%23:59:25
3weretis.net 88 2 65 0 0 0 2%07:22:06
4sklaff.dev 0 0 0 0 0 4136 0%23:58:17
5thebunker.nu 0 0 0 0 0 4141 0%23:59:55
6usenet.blueworldhosting.com 0 0 0 0 0 3796 0%23:59:55
TOTAL: 6 8725 4884 2364 1096 0 12073 55%127:19:33
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se11.5 MB0.0 KB11.5 MB0.1 KB/s2.8 KB23:59:55
2sklaffkom.se1.2 MB3.4 MB4.6 MB0.1 KB/s2.6 KB23:59:25
3weretis.net1.7 KB0.0 KB1.7 KB0.0 KB/s0.9 KB07:22:06
4sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:17
5thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
6usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
TOTAL: 612.7 MB3.4 MB16.1 MB0.0 KB/s2.8 KB127:19:33
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1100.172.132.66.censys-scanner.com 5 00.0 KB 0 0 000:00:07
2160.119.76.61 4 00.0 KB 0 0 000:00:10
3azpdsg56zpoo.stretchoid.com 2 00.0 KB 0 0 000:00:10
4twilightnode.org 2 00.0 KB 0 2 000:00:00
5198.235.24.254 1 00.0 KB 0 0 000:00:00
672.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
7r3-225-e1.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
8r5-68-44.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 8 17 00.0 KB 0 2 000:00:32

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 5 00.0 KB 0 0 000:00:07
2unresolved 5 00.0 KB 0 0 000:00:10
3*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
4*.org 2 00.0 KB 0 2 000:00:00
5*.stretchoid.com 2 00.0 KB 0 0 000:00:10
6*.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
TOTAL: 6 17 00.0 KB 0 2 000:00:32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdsg56zpoo.stretchoid.com 0.005 0.022 0.00000:00:10
160.119.76.61 0.016 0.043 0.00000:00:10
100.172.132.66.censys-scanner.com 0.059 0.041 0.00000:00:07
r5-68-44.monitoring.internet-measurement.com 0.005 0.019 0.00000:00:01
r3-225-e1.monitoring.internet-measurement.com 0.006 0.022 0.00000:00:01
198.235.24.254 0.003 0.014 0.00000:00:00
72.211.203.35.bc.googleusercontent.com 0.005 0.016 0.00000:00:00
twilightnode.org 0.009 0.028 0.00000:00:00
TOTAL: 8 0.108 0.205 0.00000:00:32

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

SystemConn
160.119.76.61 5
72.211.203.35.bc.googleusercontent.com 4
100.172.132.66.censys-scanner.com 2
azpdsg56zpoo.stretchoid.com 1
TOTAL: 4 12

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

CommandCount
GET / HTTP/1.1 2
#026#003#001 1
Accept-Encoding: gzip 1
Accept: */* 1
Connection: close 1
EHLO www.censys.io 1
Host: 172.234.115.63 1
Host: 172.234.115.63:119 1
MGLNDD_172.234.115.63_119 1
User-Agent: Hello from Palo Alto Network... 1
User-Agent: Mozilla/5.0 1
TOTAL: 11 12

NNRP client timeouts [Top 20]:

SystemConnPeer
100.172.132.66.censys-scanner.com 1 3
TOTAL: 1 1 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