Daily Usenet report

May 29 03:00:05 -- May 30 03:00:03

Unknown entries from news log file:

First 50 / 59 lines (84.7%)

May 29 03:01:36 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
May 29 08:41:58 twilightnode nnrpd[804778]: Reading access from /usr/local/news/etc/readers.conf
May 29 08:41:58 twilightnode nnrpd[804778]: Auth strategy 'localhost' does not match client. Removing.
May 29 08:41:58 twilightnode nnrpd[804778]: 172-236-228-224.ip.linodeusercontent.com no_success_auth
May 29 08:41:59 twilightnode nnrpd[804781]: Reading access from /usr/local/news/etc/readers.conf
May 29 08:41:59 twilightnode nnrpd[804781]: Auth strategy 'localhost' does not match client. Removing.
May 29 08:41:59 twilightnode nnrpd[804781]: 172-236-228-224.ip.linodeusercontent.com no_success_auth
May 29 10:07:37 twilightnode nnrpd[809156]: Reading access from /usr/local/news/etc/readers.conf
May 29 10:07:37 twilightnode nnrpd[809156]: Auth strategy 'localhost' does not match client. Removing.
May 29 10:07:37 twilightnode nnrpd[809156]: 93.152.208.38 no_success_auth
May 29 10:07:37 twilightnode nnrpd[809157]: Reading access from /usr/local/news/etc/readers.conf
May 29 10:07:37 twilightnode nnrpd[809157]: Auth strategy 'localhost' does not match client. Removing.
May 29 10:07:37 twilightnode nnrpd[809157]: 93.152.208.38 no_success_auth
May 29 11:04:18 twilightnode nnrpd[812127]: Reading access from /usr/local/news/etc/readers.conf
May 29 11:04:18 twilightnode nnrpd[812127]: Auth strategy 'localhost' does not match client. Removing.
May 29 11:04:18 twilightnode nnrpd[812127]: 195-252-55-9-no600.tbcn.telia.com no_success_auth
May 29 11:14:29 twilightnode nnrpd[812714]: Reading access from /usr/local/news/etc/readers.conf
May 29 11:14:29 twilightnode nnrpd[812714]: Auth strategy 'localhost' does not match client. Removing.
May 29 11:14:29 twilightnode nnrpd[812714]: 66-228-62-150.ip.linodeusercontent.com no_success_auth
May 29 14:47:56 twilightnode nnrpd[824421]: Reading access from /usr/local/news/etc/readers.conf
May 29 14:47:56 twilightnode nnrpd[824421]: Auth strategy 'localhost' does not match client. Removing.
May 29 14:47:56 twilightnode nnrpd[824421]: azpdwslg2q69.stretchoid.com no_success_auth
May 29 14:48:07 twilightnode nnrpd[824430]: Reading access from /usr/local/news/etc/readers.conf
May 29 14:48:07 twilightnode nnrpd[824430]: Auth strategy 'localhost' does not match client. Removing.
May 29 14:48:07 twilightnode nnrpd[824430]: azpdwslg2q69.stretchoid.com no_success_auth
May 29 16:46:09 twilightnode nnrpd[830353]: Reading access from /usr/local/news/etc/readers.conf
May 29 16:46:09 twilightnode nnrpd[830353]: Auth strategy 'localhost' does not match client. Removing.
May 29 16:46:09 twilightnode nnrpd[830353]: 198.235.24.32 no_success_auth
May 29 16:46:46 twilightnode nnrpd[830370]: Reading access from /usr/local/news/etc/readers.conf
May 29 16:46:46 twilightnode nnrpd[830370]: Auth strategy 'localhost' does not match client. Removing.
May 29 16:46:46 twilightnode nnrpd[830370]: emre.probe.onyphe.net no_success_auth
May 29 16:46:46 twilightnode nnrpd[830371]: Reading access from /usr/local/news/etc/readers.conf
May 29 16:46:46 twilightnode nnrpd[830371]: Auth strategy 'localhost' does not match client. Removing.
May 29 16:46:46 twilightnode nnrpd[830371]: edwin.probe.onyphe.net no_success_auth
May 29 19:19:45 twilightnode nnrpd[838463]: Reading access from /usr/local/news/etc/readers.conf
May 29 19:19:45 twilightnode nnrpd[838463]: Auth strategy 'localhost' does not match client. Removing.
May 29 19:19:45 twilightnode nnrpd[838463]: 81.19.219.236 no_success_auth
May 29 19:19:53 twilightnode nnrpd[838466]: Reading access from /usr/local/news/etc/readers.conf
May 29 19:19:53 twilightnode nnrpd[838466]: Auth strategy 'localhost' does not match client. Removing.
May 29 19:19:53 twilightnode nnrpd[838466]: 69.5.169.94 no_success_auth
May 29 20:13:59 twilightnode nnrpd[841257]: Reading access from /usr/local/news/etc/readers.conf
May 29 20:13:59 twilightnode nnrpd[841257]: Auth strategy 'localhost' does not match client. Removing.
May 29 20:13:59 twilightnode nnrpd[841257]: 75.195.132.66.censys-scanner.com no_success_auth
May 29 20:14:03 twilightnode nnrpd[841266]: Reading access from /usr/local/news/etc/readers.conf
May 29 20:14:03 twilightnode nnrpd[841266]: Auth strategy 'localhost' does not match client. Removing.
May 29 20:14:03 twilightnode nnrpd[841266]: 75.195.132.66.censys-scanner.com no_success_auth
May 29 20:14:10 twilightnode nnrpd[841270]: Reading access from /usr/local/news/etc/readers.conf
May 29 20:14:10 twilightnode nnrpd[841270]: Auth strategy 'localhost' does not match client. Removing.
May 29 20:14:10 twilightnode nnrpd[841270]: 75.195.132.66.censys-scanner.com no_success_auth
May 29 20:14:10 twilightnode nnrpd[841270]: 75.195.132.66.censys-scanner.com no_success_auth

Log entries by program:

Program nameLines%LinesSize%Size
inn 6065 69.5%899.5 KB 71.6%
innd 1319 15.1%192.2 KB 15.3%
innfeed 1176 13.5%146.9 KB 11.7%
nnrpd 164 1.9%16.8 KB 1.3%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 8726 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6573 35.5%
Do not exist 5998 32.4%
Positive hits 5923 32.0%
Cache misses 0 0.0%
TOTAL: 4 18494 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.003 0.0%1 3.000 3.000 3.000
article cleanup00:00:00.081 0.0%6012 0.000 0.013 0.125
article logging00:00:00.270 0.0%6050 0.000 0.045 0.188
article parse00:00:00.126 0.0%7541 0.000 0.017 0.091
article write00:00:02.455 0.0%3350 0.286 0.733 1.667
data move00:00:00.070 0.0%19271 0.000 0.004 0.068
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.283 0.0%18536 0.136 0.231 0.346
history sync00:00:00.064 0.0%406 0.000 0.158 0.800
history write00:00:03.293 0.0%6012 0.200 0.548 1.238
idle23:50:33.555100.0%19157 1333.189 4480.532 23073.000
nntp read00:00:00.431 0.0%18804 0.000 0.023 0.091
overview write00:00:01.494 0.0%3350 0.057 0.446 1.333
perl filter00:00:11.574 0.0%6012 0.955 1.925 19.905
python filter00:00:00.924 0.0%6012 0.000 0.154 1.619
site send00:00:00.127 0.0%3350 0.000 0.038 0.167
TOTAL: 1723:50:58.750100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.019 0.0%3348 0.000 0.006 0.222
article prepare00:00:00.001 0.0%17144 0.000 0.000 0.006
article read00:00:00.567 0.0%3348 0.000 0.169 0.625
backlog stats00:00:00.036 0.0%17196 0.000 0.002 0.023
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.161 0.0%8281 0.000 0.019 0.167
data write00:00:00.182 0.0%4992 0.000 0.036 0.385
idle23:50:23.626100.0%17196 2286.259 4990.906 10563.228
status file00:00:00.207 0.0%194 0.000 1.067 14.000
TOTAL: 923:50:24.799100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:10:46.554100.0%36 36.000 17959.833300048.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%196 0.000 0.005 0.500
TOTAL: 300:10:46.555100.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 5 12140 3339 6133 2668 27%47:45:27
2localhost 25 0 0 0 0 0%00:00:00
3news.chimia.se 0 0 0 0 0 0%00:10:00
TOTAL: 3 30 12140 3339 6133 2668 27%47:55:27
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net9.4 MB0.0 KB7.5 MB16.9 MB 55%2.9 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: 39.4 MB0.0 KB7.5 MB16.9 MB 55%2.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 29 03:00:05 - 03:59:59 62 1.8% 0.02200.8 KB 2.1% 0.06
May 29 04:00:00 - 04:59:59 57 1.7% 0.02202.7 KB 2.1% 0.06
May 29 05:00:00 - 05:59:59 69 2.1% 0.02303.8 KB 3.1% 0.08
May 29 06:00:00 - 06:59:59 85 2.5% 0.02289.2 KB 3.0% 0.08
May 29 07:00:00 - 07:59:59 141 4.2% 0.04599.9 KB 6.2% 0.17
May 29 08:00:00 - 08:59:59 177 5.3% 0.05541.6 KB 5.6% 0.15
May 29 09:00:00 - 09:59:59 170 5.1% 0.05445.8 KB 4.6% 0.12
May 29 10:00:00 - 10:59:59 148 4.4% 0.04320.3 KB 3.3% 0.09
May 29 11:00:00 - 11:59:59 179 5.3% 0.05428.2 KB 4.4% 0.12
May 29 12:00:00 - 12:59:59 133 4.0% 0.04334.9 KB 3.5% 0.09
May 29 13:00:00 - 13:59:59 156 4.6% 0.04403.2 KB 4.2% 0.11
May 29 14:00:00 - 14:59:59 204 6.1% 0.06557.7 KB 5.7% 0.15
May 29 15:00:00 - 15:59:59 181 5.4% 0.05499.4 KB 5.1% 0.14
May 29 16:00:00 - 16:59:59 190 5.6% 0.05634.2 KB 6.5% 0.18
May 29 17:00:00 - 17:59:59 145 4.3% 0.04373.5 KB 3.8% 0.10
May 29 18:00:00 - 18:59:59 143 4.3% 0.04440.8 KB 4.5% 0.12
May 29 19:00:00 - 19:59:59 217 6.5% 0.06611.1 KB 6.3% 0.17
May 29 20:00:00 - 20:59:59 152 4.5% 0.04430.5 KB 4.4% 0.12
May 29 21:00:00 - 21:59:59 139 4.1% 0.04334.1 KB 3.4% 0.09
May 29 22:00:00 - 22:59:59 129 3.8% 0.04398.0 KB 4.1% 0.11
May 29 23:00:00 - 23:59:59 142 4.2% 0.04401.1 KB 4.1% 0.11
May 30 00:00:00 - 00:59:59 116 3.4% 0.03376.8 KB 3.9% 0.10
May 30 01:00:00 - 01:59:59 138 4.1% 0.04367.7 KB 3.8% 0.10
May 30 02:00:00 - 02:59:59 81 2.4% 0.02200.3 KB 2.1% 0.06
May 30 03:00:00 - 03:00:03 9 0.3% 3.007.6 KB 0.1% 2.54
TOTAL: 23:59:58 3363 100.0% 0.049.5 MB 100.0% 0.11
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 2702 2233 0 0 0 0 0 0 469
TOTAL: 1 2702 2233 0 0 0 0 0 0 469

Unwanted newsgroups [Top 20]:

NewsgroupCount
ger.ct 124
fr.soc.politique 119
de.soc.politik.misc 76
alt.fan.rush-limbaugh 74
aus.cars 74
de.etc.fahrzeug.auto 70
alt.anonymous.messages 66
de.etc.sprache.deutsch 59
alt.russian.z1 58
de.talk.tagesgeschehen 44
fr.rec.photo 41
it.politica 36
de.comp.os.unix.linux.misc 33
sfnet.keskustelu.varaventtiili 33
uk.railway 31
de.comm.software.mozilla.mailnews 30
pl.soc.prawo 30
it.hobby.fai-da-te 29
de.sci.electronics 28
fr.soc.environnement 28
TOTAL: 241 2233

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 359
User-issued cancel 58
EMP (md5) 6
Too many newsgroups 6
HTML Multipart 2
TOTAL: 5 431

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 3373 3372 0 0 0 0 99%23:59:53
2thebunker.nu 940 883 0 55 0 2426 93%23:59:53
3sklaff.dev 0 0 0 0 0 3358 0%23:57:38
4usenet.blueworldhosting.com 0 0 0 0 0 2910 0%23:59:53
5weretis.net 0 0 0 0 0 0 0%00:10:30
TOTAL: 5 4313 4255 0 55 0 8694 98%96:07:47
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se9.5 MB0.0 KB9.5 MB0.1 KB/s2.9 KB23:59:53
2thebunker.nu2.6 MB308.1 KB2.9 MB0.0 KB/s3.2 KB23:59:53
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:38
4usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
5weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:30
TOTAL: 512.1 MB308.1 KB12.4 MB0.0 KB/s2.9 KB96:07:47
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
175.195.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
2172-236-228-224.ip.linodeusercontent.com 2 00.0 KB 0 0 000:00:00
393.152.208.38 2 00.0 KB 0 0 000:00:00
4azpdwslg2q69.stretchoid.com 2 00.0 KB 0 0 000:00:10
5195-252-55-9-no600.tbcn.telia.com 1 00.0 KB 0 0 000:10:14
6198.235.24.32 1 00.0 KB 0 0 000:00:00
766-228-62-150.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:10
869.5.169.114 1 00.0 KB 0 0 000:00:00
969.5.169.134 1 00.0 KB 0 0 000:00:00
1069.5.169.58 1 00.0 KB 0 0 000:00:00
1169.5.169.94 1 00.0 KB 0 0 000:00:00
1281.19.219.236 1 00.0 KB 0 0 000:00:00
13edwin.probe.onyphe.net 1 00.0 KB 0 0 000:00:03
14emre.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
TOTAL: 14 19 00.0 KB 0 0 000:10:46

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 8 00.0 KB 0 0 000:00:01
2*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3*.ip.linodeusercontent.com 3 00.0 KB 0 0 000:00:10
4*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
5*.stretchoid.com 2 00.0 KB 0 0 000:00:10
6*.tbcn.telia.com 1 00.0 KB 0 0 000:10:14
TOTAL: 6 19 00.0 KB 0 0 000:10:46

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.013 0.020 0.00000:10:14
azpdwslg2q69.stretchoid.com 0.008 0.025 0.00000:00:10
66-228-62-150.ip.linodeusercontent.com 0.006 0.011 0.00000:00:10
75.195.132.66.censys-scanner.com 0.038 0.026 0.00000:00:04
edwin.probe.onyphe.net 0.003 0.005 0.00000:00:03
emre.probe.onyphe.net 0.004 0.005 0.00000:00:00
172-236-228-224.ip.linodeusercontent.com 0.008 0.020 0.00000:00:00
198.235.24.32 0.005 0.009 0.00000:00:00
69.5.169.58 0.003 0.011 0.00000:00:00
69.5.169.94 0.003 0.006 0.00000:00:00
93.152.208.38 0.009 0.018 0.00000:00:00
69.5.169.114 0.005 0.016 0.00000:00:00
69.5.169.134 0.005 0.009 0.00000:00:00
81.19.219.236 0.005 0.013 0.00000:00:00
TOTAL: 14 0.115 0.194 0.00000:10:46

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

SystemConn
93.152.208.38 10
172-236-228-224.ip.linodeusercontent.com 7
emre.probe.onyphe.net 2
75.195.132.66.censys-scanner.com 1
azpdwslg2q69.stretchoid.com 1
TOTAL: 5 21

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

CommandCount
GET / HTTP/1.1 2
Host: 172.234.115.63:119 2
#001 1
#026#003#001 1
#026#003#001#002 1
#026#003#003#002c#001 1
?#024?#011?#023 1
?#035?'#013#036c? 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
Accept-Encoding: gzip 1
Accept-Encoding: gzip, deflate, br 1
Accept-Language: en-US,en;q=0.9 1
Accept: */* 1
Accept: text/html,application/xhtml+xml,... 1
Connection: close 1
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
User-Agent: Mozilla/5.0 (Macintosh; Inte... 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
TOTAL: 19 21

NNRP client timeouts [Top 20]:

SystemConnPeer
69.5.169.114 1 1
69.5.169.58 1 1
69.5.169.94 1 1
75.195.132.66.censys-scanner.com 1 2
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