Daily Usenet report

Jun 9 03:00:05 -- Jun 10 03:00:05

Unknown entries from news log file:

First 50 / 153 lines (32.7%)

Jun 9 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 9 03:34:27 twilightnode nnrpd[1544452]: Reading access from /usr/local/news/etc/readers.conf
Jun 9 03:34:27 twilightnode nnrpd[1544452]: Auth strategy 'localhost' does not match client. Removing.
Jun 9 03:34:27 twilightnode nnrpd[1544452]: 69.5.169.176 res <anonymous>
Jun 9 03:34:27 twilightnode nnrpd[1544452]: 69.5.169.176 no_match_user <anonymous> <localhost>
Jun 9 03:34:27 twilightnode nnrpd[1544452]: 69.5.169.176 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 9 03:34:27 twilightnode nnrpd[1544452]: 69.5.169.176 match_user <anonymous> <anonymous>
Jun 9 03:34:27 twilightnode nnrpd[1544452]: 69.5.169.176 no_post anonymous-read
Jun 9 03:34:27 twilightnode nnrpd[1544453]: Reading access from /usr/local/news/etc/readers.conf
Jun 9 03:34:27 twilightnode nnrpd[1544453]: Auth strategy 'localhost' does not match client. Removing.
Jun 9 03:34:27 twilightnode nnrpd[1544453]: 69.5.169.218 res <anonymous>
Jun 9 03:34:27 twilightnode nnrpd[1544453]: 69.5.169.218 no_match_user <anonymous> <localhost>
Jun 9 03:34:27 twilightnode nnrpd[1544453]: 69.5.169.218 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 9 03:34:27 twilightnode nnrpd[1544453]: 69.5.169.218 match_user <anonymous> <anonymous>
Jun 9 03:34:27 twilightnode nnrpd[1544453]: 69.5.169.218 no_post anonymous-read
Jun 9 03:34:28 twilightnode nnrpd[1544454]: Reading access from /usr/local/news/etc/readers.conf
Jun 9 03:34:28 twilightnode nnrpd[1544454]: Auth strategy 'localhost' does not match client. Removing.
Jun 9 03:34:28 twilightnode nnrpd[1544454]: 69.5.169.186 res <anonymous>
Jun 9 03:34:28 twilightnode nnrpd[1544454]: 69.5.169.186 no_match_user <anonymous> <localhost>
Jun 9 03:34:28 twilightnode nnrpd[1544454]: 69.5.169.186 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 9 03:34:28 twilightnode nnrpd[1544454]: 69.5.169.186 match_user <anonymous> <anonymous>
Jun 9 03:34:28 twilightnode nnrpd[1544454]: 69.5.169.186 no_post anonymous-read
Jun 9 09:02:38 twilightnode nnrpd[1565002]: Reading access from /usr/local/news/etc/readers.conf
Jun 9 09:02:38 twilightnode nnrpd[1565002]: Auth strategy 'localhost' does not match client. Removing.
Jun 9 09:02:38 twilightnode nnrpd[1565002]: 89.21.67.145 res <anonymous>
Jun 9 09:02:38 twilightnode nnrpd[1565002]: 89.21.67.145 no_match_user <anonymous> <localhost>
Jun 9 09:02:38 twilightnode nnrpd[1565002]: 89.21.67.145 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 9 09:02:38 twilightnode nnrpd[1565002]: 89.21.67.145 match_user <anonymous> <anonymous>
Jun 9 09:02:38 twilightnode nnrpd[1565002]: 89.21.67.145 no_post anonymous-read
Jun 9 09:48:46 twilightnode nnrpd[1566933]: Reading access from /usr/local/news/etc/readers.conf
Jun 9 09:48:46 twilightnode nnrpd[1566933]: Auth strategy 'localhost' does not match client. Removing.
Jun 9 09:48:46 twilightnode nnrpd[1566933]: 69.5.169.239 res <anonymous>
Jun 9 09:48:46 twilightnode nnrpd[1566933]: 69.5.169.239 no_match_user <anonymous> <localhost>
Jun 9 09:48:46 twilightnode nnrpd[1566933]: 69.5.169.239 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 9 09:48:46 twilightnode nnrpd[1566933]: 69.5.169.239 match_user <anonymous> <anonymous>
Jun 9 09:48:46 twilightnode nnrpd[1566933]: 69.5.169.239 no_post anonymous-read
Jun 9 13:29:15 twilightnode nnrpd[1577823]: Reading access from /usr/local/news/etc/readers.conf
Jun 9 13:29:15 twilightnode nnrpd[1577823]: Auth strategy 'localhost' does not match client. Removing.
Jun 9 13:29:15 twilightnode nnrpd[1577823]: azpdcsirzx3e.stretchoid.com res <anonymous>
Jun 9 13:29:15 twilightnode nnrpd[1577823]: azpdcsirzx3e.stretchoid.com no_match_user <anonymous> <localhost>
Jun 9 13:29:15 twilightnode nnrpd[1577823]: azpdcsirzx3e.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 9 13:29:15 twilightnode nnrpd[1577823]: azpdcsirzx3e.stretchoid.com match_user <anonymous> <anonymous>
Jun 9 13:29:15 twilightnode nnrpd[1577823]: azpdcsirzx3e.stretchoid.com no_post anonymous-read
Jun 9 13:29:26 twilightnode nnrpd[1577826]: Reading access from /usr/local/news/etc/readers.conf
Jun 9 13:29:26 twilightnode nnrpd[1577826]: Auth strategy 'localhost' does not match client. Removing.
Jun 9 13:29:26 twilightnode nnrpd[1577826]: azpdcsirzx3e.stretchoid.com res <anonymous>
Jun 9 13:29:26 twilightnode nnrpd[1577826]: azpdcsirzx3e.stretchoid.com no_match_user <anonymous> <localhost>
Jun 9 13:29:26 twilightnode nnrpd[1577826]: azpdcsirzx3e.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 9 13:29:26 twilightnode nnrpd[1577826]: azpdcsirzx3e.stretchoid.com match_user <anonymous> <anonymous>
Jun 9 13:29:26 twilightnode nnrpd[1577826]: azpdcsirzx3e.stretchoid.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6396 67.3%1.0 MB 71.5%
innfeed 1599 16.8%216.4 KB 15.1%
innd 1211 12.8%164.9 KB 11.5%
nnrpd 292 3.1%28.8 KB 2.0%
TOTAL: 4 9498 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6962 35.1%
Positive hits 6498 32.8%
Do not exist 6362 32.1%
Cache misses 0 0.0%
TOTAL: 4 19822 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.005 0.0%2 2.000 2.500 3.000
article cleanup00:00:00.059 0.0%6380 0.000 0.009 0.111
article logging00:00:00.272 0.0%6400 0.000 0.043 0.200
article parse00:00:00.112 0.0%8306 0.000 0.013 0.200
article write00:00:03.685 0.0%4547 0.429 0.810 2.122
data move00:00:00.058 0.0%21279 0.000 0.003 0.016
hisgrep/artcncl00:00:00.003 0.0%2 1.000 1.500 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.596 0.0%19883 0.071 0.231 0.578
history sync00:00:00.063 0.0%419 0.000 0.150 1.000
history write00:00:03.189 0.0%6380 0.143 0.500 1.238
idle24:00:38.064100.0%20813 2104.126 4153.080 13640.318
nntp read00:00:00.574 0.0%20405 0.000 0.028 0.085
overview write00:00:02.092 0.0%4547 0.186 0.460 1.778
perl filter00:00:12.011 0.0%6380 0.905 1.883 16.500
python filter00:00:01.041 0.0%6380 0.024 0.163 1.708
site send00:00:00.186 0.0%4547 0.000 0.041 0.143
TOTAL: 1724:01:06.010100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.022 0.0%4541 0.000 0.005 0.111
article prepare00:00:00.001 0.0%26088 0.000 0.000 0.002
article read00:00:00.637 0.0%4541 0.000 0.140 0.824
backlog stats00:00:00.035 0.0%29250 0.000 0.001 0.021
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.242 0.0%16371 0.000 0.015 0.075
data write00:00:00.357 0.0%11898 0.000 0.030 0.161
idle23:58:54.642100.0%29250 1575.304 2951.612 7916.382
status file00:00:00.300 0.0%320 0.000 0.938 30.000
TOTAL: 923:58:56.236100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:18.360100.0%42 20.000 437.143 10672.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%192 0.000 0.010 0.500
TOTAL: 300:00:18.362100.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 4 12308 4416 5955 1937 35%44:47:51
2news.sklaffkom.se 43 138 81 25 32 58%12:59:04
3localhost 25 0 0 0 0 0%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 4 73 12446 4497 5980 1969 36%57:56:55
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.8 MB0.0 KB5.9 MB20.6 MB 71%3.3 KB
2news.sklaffkom.se248.1 KB0.0 KB58.0 KB306.1 KB 81%2.7 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: 415.0 MB0.0 KB5.9 MB20.9 MB 71%3.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 9 03:00:05 - 03:59:59 82 1.8% 0.02270.6 KB 1.7% 0.08
Jun 9 04:00:00 - 04:59:59 114 2.5% 0.03325.8 KB 2.1% 0.09
Jun 9 05:00:00 - 05:59:59 103 2.3% 0.03277.1 KB 1.8% 0.08
Jun 9 06:00:00 - 06:59:59 131 2.9% 0.04450.8 KB 2.9% 0.13
Jun 9 07:00:00 - 07:59:59 113 2.5% 0.03360.4 KB 2.3% 0.10
Jun 9 08:00:00 - 08:59:59 157 3.5% 0.04420.9 KB 2.7% 0.12
Jun 9 09:00:00 - 09:59:59 216 4.8% 0.06587.9 KB 3.8% 0.16
Jun 9 10:00:00 - 10:59:59 175 3.9% 0.05397.3 KB 2.5% 0.11
Jun 9 11:00:00 - 11:59:59 198 4.4% 0.06650.2 KB 4.2% 0.18
Jun 9 12:00:00 - 12:59:59 223 4.9% 0.061.2 MB 8.2% 0.35
Jun 9 13:00:00 - 13:59:59 218 4.8% 0.06808.1 KB 5.2% 0.22
Jun 9 14:00:00 - 14:59:59 186 4.1% 0.05460.5 KB 3.0% 0.13
Jun 9 15:00:00 - 15:59:59 238 5.2% 0.07692.7 KB 4.4% 0.19
Jun 9 16:00:00 - 16:59:59 269 5.9% 0.071015.9 KB 6.5% 0.28
Jun 9 17:00:00 - 17:59:59 274 6.0% 0.08990.6 KB 6.4% 0.28
Jun 9 18:00:00 - 18:59:59 243 5.3% 0.07673.3 KB 4.3% 0.19
Jun 9 19:00:00 - 19:59:59 230 5.1% 0.06818.5 KB 5.3% 0.23
Jun 9 20:00:00 - 20:59:59 238 5.2% 0.07841.7 KB 5.4% 0.23
Jun 9 21:00:00 - 21:59:59 232 5.1% 0.06976.9 KB 6.3% 0.27
Jun 9 22:00:00 - 22:59:59 262 5.8% 0.07926.3 KB 5.9% 0.26
Jun 9 23:00:00 - 23:59:59 173 3.8% 0.05583.8 KB 3.7% 0.16
Jun 10 00:00:00 - 00:59:59 192 4.2% 0.05778.0 KB 5.0% 0.22
Jun 10 01:00:00 - 01:59:59 143 3.1% 0.04568.7 KB 3.6% 0.16
Jun 10 02:00:00 - 02:59:59 126 2.8% 0.04427.1 KB 2.7% 0.12
Jun 10 03:00:00 - 03:00:05 9 0.2% 1.807.2 KB 0.0% 1.44
TOTAL: 24:00:00 4545 100.0% 0.0515.2 MB 100.0% 0.18
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1819 1363 0 0 0 0 0 0 456
2news.sklaffkom.se 32 32 0 0 0 0 0 0 0
TOTAL: 2 1851 1395 0 0 0 0 0 0 456

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 85
de.rec.fahrrad 38
nl.politiek 37
de.etc.finanz.banken+broker 34
alt.checkmate 32
uk.legal.moderated 29
fido7.crimea.talk 28
fr.sci.maths 26
pl.soc.prawo 26
alt.drwho.creative 25
spline.eisfair 21
cn.comp 20
pl.misc.elektronika 20
uk.d-i-y 20
alt.prophecies.nostradamus 18
de.talk.jokes 18
it.discussioni.auto 18
it.hobby.cucina 18
linux.debian.l10n.german 18
it.sport.calcio.milan 17
TOTAL: 220 1395

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 394
User-issued cancel 32
Too many newsgroups (meow) 6
HTML Multipart 2
EMP (md5) 1
Too many newsgroups 1
TOTAL: 6 436

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4552 4551 0 0 0 0 99%23:59:49
2sklaffkom.se 4907 745 2553 1241 0 1 15%23:59:56
3weretis.net 119 0 85 0 0 0 0%08:53:04
4sklaff.dev 0 0 0 0 0 4540 0%23:59:39
5thebunker.nu 0 0 0 0 0 4545 0%23:59:56
6usenet.blueworldhosting.com 0 0 0 0 0 4260 0%23:59:55
TOTAL: 6 9578 5296 2638 1241 0 13346 55%128:52:19
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se15.2 MB0.0 KB15.2 MB0.2 KB/s3.4 KB23:59:49
2sklaffkom.se1.3 MB4.6 MB5.8 MB0.1 KB/s3.0 KB23:59:56
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:39
4thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s008:53:04
TOTAL: 616.5 MB4.6 MB21.1 MB0.0 KB/s3.3 KB128:52:19
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
145.82.78.102 4 00.0 KB 0 0 000:00:01
293.152.208.38 2 00.0 KB 0 0 000:00:00
3azpdcsirzx3e.stretchoid.com 2 00.0 KB 0 0 000:00:10
4193.176.31.200 1 00.0 KB 0 0 000:00:00
569.5.169.100 1 00.0 KB 0 0 000:00:00
669.5.169.102 1 00.0 KB 0 0 000:00:00
769.5.169.103 1 00.0 KB 0 0 000:00:00
869.5.169.176 1 00.0 KB 0 0 000:00:00
969.5.169.186 1 00.0 KB 0 0 000:00:00
1069.5.169.218 1 00.0 KB 0 0 000:00:00
1169.5.169.239 1 00.0 KB 0 0 000:00:00
1269.5.169.28 1 00.0 KB 0 0 000:00:00
1389.21.67.145 1 00.0 KB 0 0 000:00:00
1490.224.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
15cloud.census.shodan.io 1 00.0 KB 0 0 000:00:00
16r4-47-2f.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 16 21 00.0 KB 0 0 000:00:18

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 16 00.0 KB 0 0 000:00:03
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
4*.census.shodan.io 1 00.0 KB 0 0 000:00:00
5*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 5 21 00.0 KB 0 0 000:00:18

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcsirzx3e.stretchoid.com 0.009 0.022 0.00000:00:10
r4-47-2f.monitoring.internet-measurement.com 0.008 0.008 0.00000:00:01
90.224.132.66.censys-scanner.com 0.031 0.017 0.00000:00:01
45.82.78.102 0.011 0.030 0.00000:00:01
cloud.census.shodan.io 0.005 0.011 0.00000:00:00
69.5.169.102 0.005 0.025 0.00000:00:00
93.152.208.38 0.012 0.021 0.00000:00:00
69.5.169.28 0.003 0.015 0.00000:00:00
69.5.169.239 0.001 0.014 0.00000:00:00
193.176.31.200 0.002 0.011 0.00000:00:00
69.5.169.176 0.005 0.012 0.00000:00:00
69.5.169.100 0.005 0.013 0.00000:00:00
89.21.67.145 0.004 0.014 0.00000:00:00
69.5.169.218 0.004 0.006 0.00000:00:00
69.5.169.186 0.004 0.009 0.00000:00:00
69.5.169.103 0.004 0.011 0.00000:00:00
TOTAL: 16 0.113 0.239 0.00000:00:18

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

SystemConn
45.82.78.102 15
93.152.208.38 10
69.5.169.176 6
69.5.169.186 3
69.5.169.218 3
90.224.132.66.censys-scanner.com 1
azpdcsirzx3e.stretchoid.com 1
TOTAL: 7 39

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

CommandCount
#021? 2
#026#003#001#002?#001 2
#026#003#001#005?#001 2
?#011?#023?#024 2
?#024#023#001#023#002#023#003#001 2
Connection: close 2
GET / HTTP/1.1 2
Host: 172.234.115.63:119 2
#010#013#010#004#010#005#010#006#004#001#005#001#006#001#003#003#003#001#003#002#004#002#005#002#006#002 1
#014?#004?#0236I?#014XeqBJ 1
#026#003#001#002 1
#031#015?Eq-s?=?hK8W?\o?#007^{?c:#032o#023#022#006?... 1
<$#031?SL#022?b#023?j{@4[?#002?L?,rj?"#002?y?... 1
?#003+(#023:#033?s?%?&,.?s?#026#032[?o?[?#024?... 1
?#014?#011?W#022t?#035?E#023?I?#033(PE)5u?#010Z4T?)?... 1
?#024?#011?#023 1
?#034[?5?Qyg?b?%?:?V? 1
?@J?#023U?DVk?~\{?P6?#031? 1
?z5?]?<?(s#006#001)wV?#035?s?#027JT?#016?T?x?g#007jJ... 1
@L?D?#035U?#036Y?m1?M@GY#0275?(#010?$?x?+?#015?+... 1
TOTAL: 31 39

NNRP client timeouts [Top 20]:

SystemConnPeer
69.5.169.100 1 1
69.5.169.102 1 1
69.5.169.176 1 1
69.5.169.186 1 1
69.5.169.218 1 1
69.5.169.239 1 1
69.5.169.28 1 1
TOTAL: 7 7 10
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