Daily Usenet report

May 30 03:00:03 -- May 31 03:00:06

Unknown entries from news log file:

First 50 / 174 lines (28.7%)

May 30 03:01:35 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
May 30 09:00:03 twilightnode nnrpd[877807]: Reading access from /usr/local/news/etc/readers.conf
May 30 09:00:03 twilightnode nnrpd[877807]: Auth strategy 'localhost' does not match client. Removing.
May 30 09:00:03 twilightnode nnrpd[877807]: 2603:6010:6800:e2b3:1a03:73ff:fe28:be0f no_success_auth
May 30 09:00:03 twilightnode nnrpd[877808]: Reading access from /usr/local/news/etc/readers.conf
May 30 09:00:03 twilightnode nnrpd[877808]: Auth strategy 'localhost' does not match client. Removing.
May 30 09:00:03 twilightnode nnrpd[877808]: 2603:6010:6800:e2b3:1a03:73ff:fe28:be0f no_success_auth
May 30 09:00:34 twilightnode nnrpd[877819]: Reading access from /usr/local/news/etc/readers.conf
May 30 09:00:34 twilightnode nnrpd[877819]: Auth strategy 'localhost' does not match client. Removing.
May 30 09:00:34 twilightnode nnrpd[877819]: 66.61.98.95 no_success_auth
May 30 09:00:35 twilightnode nnrpd[877820]: Reading access from /usr/local/news/etc/readers.conf
May 30 09:00:35 twilightnode nnrpd[877820]: Auth strategy 'localhost' does not match client. Removing.
May 30 09:00:35 twilightnode nnrpd[877820]: 66.61.98.95 no_success_auth
May 30 12:26:18 twilightnode nnrpd[888381]: Reading access from /usr/local/news/etc/readers.conf
May 30 12:26:18 twilightnode nnrpd[888381]: localhost res <localhost>
May 30 12:26:18 twilightnode nnrpd[888381]: localhost match_user <localhost> <localhost>
May 30 12:26:42 twilightnode nnrpd[888425]: Reading access from /usr/local/news/etc/readers.conf
May 30 12:26:42 twilightnode nnrpd[888425]: localhost res <localhost>
May 30 12:26:42 twilightnode nnrpd[888425]: localhost match_user <localhost> <localhost>
May 30 12:27:32 twilightnode nnrpd[888579]: Reading access from /usr/local/news/etc/readers.conf
May 30 12:27:32 twilightnode nnrpd[888579]: Auth strategy 'localhost' does not match client. Removing.
May 30 12:27:32 twilightnode nnrpd[888579]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
May 30 12:27:32 twilightnode nnrpd[888579]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
May 30 12:27:32 twilightnode nnrpd[888579]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
May 30 12:27:32 twilightnode nnrpd[888579]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
May 30 12:28:14 twilightnode nnrpd[888601]: Reading access from /usr/local/news/etc/readers.conf
May 30 12:28:14 twilightnode nnrpd[888601]: Auth strategy 'localhost' does not match client. Removing.
May 30 12:28:14 twilightnode nnrpd[888601]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
May 30 12:28:14 twilightnode nnrpd[888601]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
May 30 12:28:14 twilightnode nnrpd[888601]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
May 30 12:28:14 twilightnode nnrpd[888601]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
May 30 12:30:57 twilightnode nnrpd[888758]: Reading access from /usr/local/news/etc/readers.conf
May 30 12:30:57 twilightnode nnrpd[888758]: Auth strategy 'localhost' does not match client. Removing.
May 30 12:30:57 twilightnode nnrpd[888758]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
May 30 12:30:57 twilightnode nnrpd[888758]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
May 30 12:30:57 twilightnode nnrpd[888758]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
May 30 12:34:08 twilightnode nnrpd[888889]: Reading access from /usr/local/news/etc/readers.conf
May 30 12:34:08 twilightnode nnrpd[888889]: Auth strategy 'localhost' does not match client. Removing.
May 30 12:34:08 twilightnode nnrpd[888889]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
May 30 12:34:08 twilightnode nnrpd[888889]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
May 30 12:34:08 twilightnode nnrpd[888889]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
May 30 12:34:25 twilightnode nnrpd[888896]: Reading access from /usr/local/news/etc/readers.conf
May 30 12:34:25 twilightnode nnrpd[888896]: Auth strategy 'localhost' does not match client. Removing.
May 30 12:34:25 twilightnode nnrpd[888896]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
May 30 12:34:25 twilightnode nnrpd[888896]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
May 30 12:34:25 twilightnode nnrpd[888896]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
May 30 12:37:41 twilightnode nnrpd[889200]: Reading access from /usr/local/news/etc/readers.conf
May 30 12:37:41 twilightnode nnrpd[889200]: Auth strategy 'localhost' does not match client. Removing.
May 30 12:37:41 twilightnode nnrpd[889200]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
May 30 12:37:41 twilightnode nnrpd[889200]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>

Log entries by program:

Program nameLines%LinesSize%Size
inn 5552 55.8%844.7 KB 61.2%
innfeed 2521 25.3%295.4 KB 21.4%
innd 1527 15.3%203.6 KB 14.7%
nnrpd 302 3.0%32.5 KB 2.4%
controlchan 54 0.5%4.2 KB 0.3%
TOTAL: 5 9956 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 5928 35.5%
Do not exist 5394 32.3%
Positive hits 5368 32.2%
Cache misses 3 0.0%
TOTAL: 4 16693 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.007 0.0%1 7.000 7.000 7.000
article cleanup00:00:00.067 0.0%5422 0.000 0.012 0.143
article logging00:00:00.227 0.0%5460 0.000 0.042 0.167
article parse00:00:00.110 0.0%6546 0.000 0.017 0.176
article write00:00:02.893 0.0%3720 0.314 0.778 4.654
data move00:00:00.073 0.0%17835 0.000 0.004 0.061
hisgrep/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.174 0.0%16778 0.083 0.249 0.491
history sync00:00:00.063 0.0%408 0.000 0.154 2.000
history write00:00:03.236 0.0%5422 0.250 0.597 1.429
idle23:40:40.737100.0%17739 1828.634 4805.273 25034.333
nntp read00:00:00.423 0.0%17354 0.000 0.024 0.086
overview write00:00:01.570 0.0%3720 0.129 0.422 4.182
perl filter00:00:10.662 0.0%5422 0.897 1.966 18.333
python filter00:00:00.773 0.0%5422 0.000 0.143 1.056
site send00:00:00.137 0.0%3720 0.000 0.037 0.222
TOTAL: 1723:41:05.152100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.013 0.0%3670 0.000 0.004 0.100
article prepare00:00:00.000 0.0%14680 0.000 0.000 0.000
article read00:00:00.590 0.0%3670 0.000 0.161 1.273
backlog stats00:00:00.022 0.0%16498 0.000 0.001 0.020
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.152 0.0%7697 0.000 0.020 0.136
data write00:00:00.151 0.0%4100 0.000 0.037 0.231
idle23:20:45.821100.0%16498 1866.202 5094.304 12276.163
status file00:00:00.200 0.0%222 0.000 0.901 7.000
TOTAL: 923:20:46.949100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:08:53.431100.0%116 59.500 4598.543 21439.750
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.039 0.0%3217 0.000 0.012 0.250
TOTAL: 300:08:53.470100.0%----

Control commands to innd:

CommandNumber
flush 3
flushlogs 2
go 2
logmode 2
lowmark 1
mode 148
name 2
newgroup 20
pause 2
paused 2
reload 4
reserve 2
shutdown 3
xexec 6
TOTAL: 14 199

Newsgroups created:

GroupMode
alt.anonymous.messagesy
alt.fan.rush-limbaughy
alt.russian.z1y
aus.carsy
de.comm.software.mozilla.mailnewsy
de.comp.os.unix.linux.miscy
de.etc.fahrzeug.autoy
de.etc.sprache.deutschy
de.sci.electronicsy
de.soc.politik.miscy
de.talk.tagesgescheheny
fr.rec.photoy
fr.soc.environnementy
fr.soc.politiquey
ger.cty
it.hobby.fai-da-tey
it.politicay
sfnet.keskustelu.varaventtiiliy
uk.railwayy
TOTAL19

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 19 10723 3695 5291 1737 34%45:58:56
2localhost 28 0 0 0 0 0%00:00:17
3news.chimia.se 2 0 0 0 0 0%00:10:01
TOTAL: 3 49 10723 3695 5291 1737 34%46:09:14
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net10.5 MB0.0 KB6.7 MB17.2 MB 61%3.2 KB
2localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
3news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 310.5 MB0.0 KB6.7 MB17.2 MB 61%3.2 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 30 03:00:03 - 03:59:59 57 1.5% 0.02148.3 KB 1.3% 0.04
May 30 04:00:00 - 04:59:59 70 1.9% 0.02263.3 KB 2.4% 0.07
May 30 05:00:00 - 05:59:59 75 2.0% 0.02205.7 KB 1.9% 0.06
May 30 06:00:00 - 06:59:59 98 2.6% 0.03248.6 KB 2.2% 0.07
May 30 07:00:00 - 07:59:59 109 2.9% 0.03325.8 KB 2.9% 0.09
May 30 08:00:00 - 08:59:59 129 3.4% 0.04322.7 KB 2.9% 0.09
May 30 09:00:00 - 09:59:59 144 3.8% 0.04389.3 KB 3.5% 0.11
May 30 10:00:00 - 10:59:59 185 4.9% 0.05433.9 KB 3.9% 0.12
May 30 11:00:00 - 11:59:59 195 5.2% 0.05405.3 KB 3.7% 0.11
May 30 12:00:00 - 12:59:59 149 4.0% 0.04346.7 KB 3.1% 0.10
May 30 13:00:00 - 13:59:59 172 4.6% 0.05394.8 KB 3.6% 0.11
May 30 14:00:00 - 14:59:59 179 4.8% 0.05489.6 KB 4.4% 0.14
May 30 15:00:00 - 15:59:59 221 5.9% 0.06686.8 KB 6.2% 0.19
May 30 16:00:00 - 16:59:59 190 5.0% 0.05588.6 KB 5.3% 0.16
May 30 17:00:00 - 17:59:59 206 5.5% 0.06632.0 KB 5.7% 0.18
May 30 18:00:00 - 18:59:59 402 10.7% 0.111.8 MB 16.3% 0.50
May 30 19:00:00 - 19:59:59 189 5.0% 0.05588.0 KB 5.3% 0.16
May 30 20:00:00 - 20:59:59 176 4.7% 0.05441.5 KB 4.0% 0.12
May 30 21:00:00 - 21:59:59 202 5.4% 0.06645.6 KB 5.8% 0.18
May 30 22:00:00 - 22:59:59 161 4.3% 0.04431.5 KB 3.9% 0.12
May 30 23:00:00 - 23:59:59 116 3.1% 0.03336.2 KB 3.0% 0.09
May 31 00:00:00 - 00:59:59 124 3.3% 0.03358.1 KB 3.2% 0.10
May 31 01:00:00 - 01:59:59 110 2.9% 0.03320.7 KB 2.9% 0.09
May 31 02:00:00 - 02:59:59 96 2.6% 0.03258.7 KB 2.3% 0.07
May 31 03:00:00 - 03:00:06 9 0.2% 1.507.5 KB 0.1% 1.26
TOTAL: 24:00:03 3764 100.0% 0.0410.8 MB 100.0% 0.13
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1788 1542 0 0 0 0 0 0 246
TOTAL: 1 1788 1542 0 0 0 0 0 0 246

Unwanted newsgroups [Top 20]:

NewsgroupCount
fr.soc.politique 66
fr.bio.medecine 58
alt.russian.z1 51
it.test 41
aus.cars 40
fr.lettres.langue.francaise 37
uk.rec.motorcycles 36
de.soc.politik.misc 31
uk.current-events.terrorism 28
de.etc.fahrzeug.auto 27
de.talk.tagesgeschehen 27
de.alt.comm.android 26
de.comp.os.unix.linux.misc 26
it.tlc.cellulari.android 24
pl.soc.prawo 24
ger.ct 22
eternal-september.support 21
pl.soc.polityka 21
cn.comp 20
uk.legal.moderated 19
TOTAL: 227 1542

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phl) 157
User-issued cancel 27
Too many newsgroups 11
EMP (md5) 8
EMP (phn path) 3
Too many newsgroups (meow) 1
TOTAL: 6 207

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 3766 3740 0 24 0 0 99%23:58:41
2sklaff.dev 0 0 0 0 0 3746 0%23:39:19
3thebunker.nu 0 0 0 0 0 3764 0%23:58:41
4usenet.blueworldhosting.com 0 0 0 0 0 3428 0%23:58:35
5weretis.net 0 0 0 0 0 0 0%00:44:00
TOTAL: 5 3766 3740 0 24 0 10938 99%96:19:16
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se10.8 MB35.0 KB10.8 MB0.1 KB/s2.9 KB23:58:41
2sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:39:19
3thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:41
4usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:35
5weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:44:00
TOTAL: 510.8 MB35.0 KB10.8 MB0.0 KB/s2.9 KB96:19:16
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1195-252-55-9-no600.tbcn.telia.com 9 00.0 KB 0 0 200:04:43
2152.32.174.186 3 00.0 KB 0 0 000:00:11
32603:6010:6800:e2b3:1a03:73ff:fe28:be0f 2 00.0 KB 0 0 000:00:01
466.61.98.95 2 00.0 KB 0 0 000:00:01
5azpdes9ysn2y.stretchoid.com 2 00.0 KB 0 0 000:00:10
6azpdsg56zpoo.stretchoid.com 2 00.0 KB 0 0 000:00:10
7localhost 2 00.0 KB 0 0 000:03:30
8172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
9213.166.84.62 1 00.0 KB 0 0 000:00:00
1069.5.169.125 1 00.0 KB 0 0 000:00:00
1169.5.169.2 1 00.0 KB 0 0 000:00:00
1269.5.169.44 1 00.0 KB 0 0 000:00:00
1384.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
TOTAL: 13 28 00.0 KB 0 0 200:08:53

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 11 00.0 KB 0 0 000:00:16
2*.tbcn.telia.com 9 00.0 KB 0 0 200:04:43
3*.stretchoid.com 4 00.0 KB 0 0 000:00:21
4*localhost 2 00.0 KB 0 0 000:03:30
5*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
6*.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
TOTAL: 6 28 00.0 KB 0 0 200:08:53

NNRP auth users [Top 20]:

UserConn
peterlondon 4
TOTAL: 1 4

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.046 0.073 0.08000:04:43
localhost 0.008 0.017 0.00000:03:30
152.32.174.186 0.008 0.020 0.00000:00:11
azpdes9ysn2y.stretchoid.com 0.006 0.031 0.00000:00:10
azpdsg56zpoo.stretchoid.com 0.009 0.018 0.00000:00:10
84.195.132.66.censys-scanner.com 0.032 0.017 0.00000:00:01
2603:6010:6800:e2b3:1a03:73ff:fe28:be0f 0.009 0.020 0.00000:00:01
66.61.98.95 0.007 0.021 0.00000:00:01
213.166.84.62 0.002 0.014 0.00000:00:00
69.5.169.2 0.004 0.006 0.00000:00:00
69.5.169.125 0.005 0.008 0.00000:00:00
172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 0.004 0.013 0.00000:00:00
69.5.169.44 0.006 0.010 0.00000:00:00
TOTAL: 13 0.146 0.268 0.08000:08:53

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

SystemConn
152.32.174.186 2
195-252-55-9-no600.tbcn.telia.com 1
84.195.132.66.censys-scanner.com 1
azpdes9ysn2y.stretchoid.com 1
azpdsg56zpoo.stretchoid.com 1
TOTAL: 5 6

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

CommandCount
MGLNDD_172.234.115.63_119 2
#026#003#001 1
?#006q 1
?#024 1
EHLO www.censys.io 1
TOTAL: 5 6

NNRP client timeouts [Top 20]:

SystemConnPeer
69.5.169.125 1 1
69.5.169.2 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