Daily Usenet report

Jun 7 03:00:05 -- Jun 8 03:00:05

Unknown entries from news log file:

First 50 / 109 lines (45.9%)

Jun 7 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 7 03:36:11 twilightnode nnrpd[1414300]: Reading access from /usr/local/news/etc/readers.conf
Jun 7 03:36:11 twilightnode nnrpd[1414300]: Auth strategy 'localhost' does not match client. Removing.
Jun 7 03:36:11 twilightnode nnrpd[1414300]: 193.176.31.233 res <anonymous>
Jun 7 03:36:11 twilightnode nnrpd[1414300]: 193.176.31.233 no_match_user <anonymous> <localhost>
Jun 7 03:36:11 twilightnode nnrpd[1414300]: 193.176.31.233 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 7 03:36:11 twilightnode nnrpd[1414300]: 193.176.31.233 match_user <anonymous> <anonymous>
Jun 7 03:36:11 twilightnode nnrpd[1414300]: 193.176.31.233 no_post anonymous-read
Jun 7 04:09:12 twilightnode nnrpd[1415490]: Reading access from /usr/local/news/etc/readers.conf
Jun 7 04:09:12 twilightnode nnrpd[1415490]: Auth strategy 'localhost' does not match client. Removing.
Jun 7 04:09:12 twilightnode nnrpd[1415490]: 188.240.59.26 res <anonymous>
Jun 7 04:09:12 twilightnode nnrpd[1415490]: 188.240.59.26 no_match_user <anonymous> <localhost>
Jun 7 04:09:12 twilightnode nnrpd[1415490]: 188.240.59.26 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 7 04:09:12 twilightnode nnrpd[1415490]: 188.240.59.26 match_user <anonymous> <anonymous>
Jun 7 04:09:12 twilightnode nnrpd[1415490]: 188.240.59.26 no_post anonymous-read
Jun 7 04:12:51 twilightnode nnrpd[1415807]: Reading access from /usr/local/news/etc/readers.conf
Jun 7 04:12:51 twilightnode nnrpd[1415807]: Auth strategy 'localhost' does not match client. Removing.
Jun 7 04:12:51 twilightnode nnrpd[1415807]: azpdcs2tpbom.stretchoid.com res <anonymous>
Jun 7 04:12:51 twilightnode nnrpd[1415807]: azpdcs2tpbom.stretchoid.com no_match_user <anonymous> <localhost>
Jun 7 04:12:51 twilightnode nnrpd[1415807]: azpdcs2tpbom.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 7 04:12:51 twilightnode nnrpd[1415807]: azpdcs2tpbom.stretchoid.com match_user <anonymous> <anonymous>
Jun 7 04:12:51 twilightnode nnrpd[1415807]: azpdcs2tpbom.stretchoid.com no_post anonymous-read
Jun 7 04:13:01 twilightnode nnrpd[1415808]: Reading access from /usr/local/news/etc/readers.conf
Jun 7 04:13:01 twilightnode nnrpd[1415808]: Auth strategy 'localhost' does not match client. Removing.
Jun 7 04:13:01 twilightnode nnrpd[1415808]: azpdcs2tpbom.stretchoid.com res <anonymous>
Jun 7 04:13:01 twilightnode nnrpd[1415808]: azpdcs2tpbom.stretchoid.com no_match_user <anonymous> <localhost>
Jun 7 04:13:01 twilightnode nnrpd[1415808]: azpdcs2tpbom.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 7 04:13:01 twilightnode nnrpd[1415808]: azpdcs2tpbom.stretchoid.com match_user <anonymous> <anonymous>
Jun 7 04:13:01 twilightnode nnrpd[1415808]: azpdcs2tpbom.stretchoid.com no_post anonymous-read
Jun 7 04:31:56 twilightnode nnrpd[1416556]: Reading access from /usr/local/news/etc/readers.conf
Jun 7 04:31:56 twilightnode nnrpd[1416556]: Auth strategy 'localhost' does not match client. Removing.
Jun 7 04:31:56 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com res <anonymous>
Jun 7 04:31:56 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 7 04:31:56 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 7 04:31:56 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 7 04:31:56 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com no_post anonymous-read
Jun 7 04:32:01 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com res <anonymous>
Jun 7 04:32:01 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 7 04:32:01 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 7 04:32:01 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 7 04:32:01 twilightnode nnrpd[1416556]: 182.172.132.66.censys-scanner.com no_post anonymous-read
Jun 7 08:17:28 twilightnode nnrpd[1425993]: Reading access from /usr/local/news/etc/readers.conf
Jun 7 08:17:28 twilightnode nnrpd[1425993]: Auth strategy 'localhost' does not match client. Removing.
Jun 7 08:17:28 twilightnode nnrpd[1425993]: r4-139-8b.monitoring.internet-measurement.com res <anonymous>
Jun 7 08:17:28 twilightnode nnrpd[1425993]: r4-139-8b.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Jun 7 08:17:28 twilightnode nnrpd[1425993]: r4-139-8b.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 7 08:17:28 twilightnode nnrpd[1425993]: r4-139-8b.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Jun 7 08:17:28 twilightnode nnrpd[1425993]: r4-139-8b.monitoring.internet-measurement.com no_post anonymous-read
Jun 7 14:18:04 twilightnode nnrpd[1442659]: Reading access from /usr/local/news/etc/readers.conf
Jun 7 14:18:04 twilightnode nnrpd[1442659]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 5271 67.6%854.9 KB 71.3%
innfeed 1539 19.7%199.1 KB 16.6%
innd 805 10.3%126.1 KB 10.5%
nnrpd 185 2.4%19.4 KB 1.6%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 7802 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 5571 34.7%
Positive hits 5294 32.9%
Do not exist 5210 32.4%
Cache misses 0 0.0%
TOTAL: 4 16075 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.005 0.0%5 0.500 1.000 2.000
article cleanup00:00:00.064 0.0%5231 0.000 0.012 0.111
article logging00:00:00.221 0.0%5267 0.000 0.042 0.200
article parse00:00:00.096 0.0%6531 0.000 0.015 0.085
article write00:00:03.158 0.0%3795 0.409 0.832 2.875
data move00:00:00.082 0.0%17594 0.000 0.005 0.140
hisgrep/artcncl00:00:00.001 0.0%5 0.000 0.200 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.945 0.0%16143 0.108 0.244 0.657
history sync00:00:00.058 0.0%415 0.000 0.140 0.667
history write00:00:02.664 0.0%5231 0.158 0.509 0.913
idle24:00:39.498100.0%17536 2610.878 4929.260 19375.613
nntp read00:00:00.522 0.0%17142 0.000 0.030 0.085
overview write00:00:01.813 0.0%3795 0.108 0.478 2.267
perl filter00:00:11.221 0.0%5231 1.071 2.145 12.652
python filter00:00:01.020 0.0%5231 0.044 0.195 0.714
site send00:00:00.143 0.0%3795 0.000 0.038 0.231
TOTAL: 1724:01:04.511100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.025 0.0%3782 0.000 0.007 0.111
article prepare00:00:00.001 0.0%22444 0.000 0.000 0.005
article read00:00:00.607 0.0%3782 0.000 0.160 0.750
backlog stats00:00:00.038 0.0%25721 0.000 0.001 0.010
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.223 0.0%13894 0.000 0.016 0.103
data write00:00:00.308 0.0%10182 0.000 0.030 0.100
idle23:51:42.850100.0%25721 1860.114 3339.794 7945.308
status file00:00:00.348 0.0%301 0.000 1.156 34.000
TOTAL: 923:51:44.400100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:24.825100.0%23 35.333 1079.348 10669.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%132 0.000 0.008 0.500
TOTAL: 300:00:24.826100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 9660 3779 4447 1434 39%44:25:38
2news.sklaffkom.se 40 81 43 27 11 53%09:28:39
3localhost 25 0 0 0 0 0%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 4 70 9741 3822 4474 1445 39%54:04:17
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.2 MB0.0 KB5.5 MB16.6 MB 67%3.3 KB
2news.sklaffkom.se109.8 KB0.0 KB22.4 KB132.2 KB 83%2.4 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: 411.3 MB0.0 KB5.5 MB16.8 MB 67%3.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 7 03:00:05 - 03:59:59 71 1.9% 0.02151.9 KB 1.3% 0.04
Jun 7 04:00:00 - 04:59:59 85 2.2% 0.02273.1 KB 2.4% 0.08
Jun 7 05:00:00 - 05:59:59 82 2.2% 0.02279.0 KB 2.4% 0.08
Jun 7 06:00:00 - 06:59:59 86 2.3% 0.02201.9 KB 1.8% 0.06
Jun 7 07:00:00 - 07:59:59 149 3.9% 0.04471.6 KB 4.1% 0.13
Jun 7 08:00:00 - 08:59:59 149 3.9% 0.04395.2 KB 3.4% 0.11
Jun 7 09:00:00 - 09:59:59 226 5.9% 0.06580.4 KB 5.0% 0.16
Jun 7 10:00:00 - 10:59:59 222 5.8% 0.06574.9 KB 5.0% 0.16
Jun 7 11:00:00 - 11:59:59 217 5.7% 0.06976.0 KB 8.5% 0.27
Jun 7 12:00:00 - 12:59:59 157 4.1% 0.04360.8 KB 3.1% 0.10
Jun 7 13:00:00 - 13:59:59 187 4.9% 0.05426.3 KB 3.7% 0.12
Jun 7 14:00:00 - 14:59:59 150 3.9% 0.04395.9 KB 3.4% 0.11
Jun 7 15:00:00 - 15:59:59 222 5.8% 0.06625.8 KB 5.4% 0.17
Jun 7 16:00:00 - 16:59:59 175 4.6% 0.05574.9 KB 5.0% 0.16
Jun 7 17:00:00 - 17:59:59 193 5.1% 0.05855.2 KB 7.4% 0.24
Jun 7 18:00:00 - 18:59:59 198 5.2% 0.06657.1 KB 5.7% 0.18
Jun 7 19:00:00 - 19:59:59 200 5.3% 0.06587.7 KB 5.1% 0.16
Jun 7 20:00:00 - 20:59:59 199 5.2% 0.06703.8 KB 6.1% 0.20
Jun 7 21:00:00 - 21:59:59 165 4.3% 0.05441.8 KB 3.8% 0.12
Jun 7 22:00:00 - 22:59:59 159 4.2% 0.04408.5 KB 3.5% 0.11
Jun 7 23:00:00 - 23:59:59 169 4.4% 0.05603.2 KB 5.2% 0.17
Jun 8 00:00:00 - 00:59:59 118 3.1% 0.03355.5 KB 3.1% 0.10
Jun 8 01:00:00 - 01:59:59 110 2.9% 0.03293.7 KB 2.6% 0.08
Jun 8 02:00:00 - 02:59:59 99 2.6% 0.03302.5 KB 2.6% 0.08
Jun 8 03:00:00 - 03:00:05 13 0.3% 2.6016.5 KB 0.1% 3.31
TOTAL: 24:00:00 3801 100.0% 0.0411.2 MB 100.0% 0.13
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1459 1371 0 0 0 0 0 0 88
2news.sklaffkom.se 11 11 0 0 0 0 0 0 0
TOTAL: 2 1470 1382 0 0 0 0 0 0 88

Unwanted newsgroups [Top 20]:

NewsgroupCount
pl.soc.prawo 40
fr.sci.maths 38
de.alt.folklore.computer 34
alt.usage.english 33
de.etc.haushalt 32
dk.politik 29
it.sport.calcio.milan 29
aus.politics 25
linux.debian.l10n.german 25
nl.religie 25
nl.culinair 23
uk.tech.broadcast 22
cn.comp 20
uk.d-i-y 20
de.sci.ing.elektrotechnik 19
de.sci.mathematik 19
linux.gentoo.user 19
alt.slack 18
fido7.ru.fidonet.today 18
fido7.ru.military 18
TOTAL: 383 1382

Perl filter (innd) [Top 20]:

ReasonCount
User-issued cancel 35
EMP (md5) 6
HTML Multipart 5
EMP (phn path) 2
Too many newsgroups 2
Too many newsgroups (meow) 2
TOTAL: 6 52

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 3809 3762 0 46 0 0 98%23:59:55
2sklaffkom.se 4239 932 1950 904 0 7 21%23:59:16
3weretis.net 49 0 45 0 0 0 0%05:40:44
4sklaff.dev 0 0 0 0 0 3793 0%23:59:24
5thebunker.nu 0 0 0 0 0 3801 0%23:59:55
6usenet.blueworldhosting.com 0 0 0 0 0 3468 0%23:59:55
TOTAL: 6 8097 4694 1995 950 0 11069 57%125:39:09
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se11.2 MB72.0 KB11.3 MB0.1 KB/s3.0 KB23:59:55
2sklaffkom.se1.5 MB3.3 MB4.8 MB0.1 KB/s2.7 KB23:59:16
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:24
4thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s005:40:44
TOTAL: 612.7 MB3.3 MB16.0 MB0.0 KB/s2.9 KB125:39:09
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
137.172.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:03
293.152.208.38 2 00.0 KB 0 0 000:00:00
3azpdcs2tpbom.stretchoid.com 2 00.0 KB 0 0 000:00:10
4182.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:05
5188.240.59.26 1 00.0 KB 0 0 000:00:00
6193.176.31.233 1 00.0 KB 0 0 000:00:00
7193.32.209.235 1 00.0 KB 0 0 000:00:00
8195.206.182.212 1 00.0 KB 0 0 000:00:00
9198.235.24.19 1 00.0 KB 0 0 000:00:00
10r4-139-8b.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 10 14 00.0 KB 0 0 000:00:24

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

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

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcs2tpbom.stretchoid.com 0.008 0.016 0.00000:00:10
182.172.132.66.censys-scanner.com 0.030 0.016 0.00000:00:05
37.172.132.66.censys-scanner.com 0.053 0.036 0.00000:00:03
r4-139-8b.monitoring.internet-measurement.com 0.006 0.014 0.00000:00:01
193.32.209.235 0.005 0.016 0.00000:00:00
198.235.24.19 0.003 0.014 0.00000:00:00
193.176.31.233 0.006 0.010 0.00000:00:00
188.240.59.26 0.004 0.011 0.00000:00:00
93.152.208.38 0.007 0.018 0.00000:00:00
195.206.182.212 0.005 0.011 0.00000:00:00
TOTAL: 10 0.127 0.162 0.00000:00:24

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

SystemConn
93.152.208.38 9
182.172.132.66.censys-scanner.com 1
37.172.132.66.censys-scanner.com 1
azpdcs2tpbom.stretchoid.com 1
TOTAL: 4 12

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

CommandCount
EHLO www.censys.io 2
#026#003#001#002 1
?#024?#011?#023 1
Accept-Encoding: gzip, deflate, br 1
Accept-Language: en-US,en;q=0.9 1
Accept: text/html,application/xhtml+xml,... 1
Connection: close 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 (Windows NT 10.0... 1
TOTAL: 11 12

NNRP client timeouts [Top 20]:

SystemConnPeer
188.240.59.26 1 1
193.32.209.235 1 1
37.172.132.66.censys-scanner.com 1 2
TOTAL: 3 3 7
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