Daily Usenet report

May 19 03:00:04 -- May 20 03:00:02

Unknown entries from news log file:

First 50 / 51 lines (98.0%)

May 19 03:00:05 mail nnrpd[86918]: Reading access from /usr/local/news/etc/readers.conf
May 19 03:00:05 mail nnrpd[86918]: Auth strategy 'localhost' does not match client. Removing.
May 19 03:00:05 mail nnrpd[86918]: 195-252-55-9-no600.tbcn.telia.com no_success_auth
May 19 03:00:35 mail nnrpd[87149]: Reading access from /usr/local/news/etc/readers.conf
May 19 03:00:35 mail nnrpd[87149]: Auth strategy 'localhost' does not match client. Removing.
May 19 03:00:35 mail nnrpd[87149]: 195-252-55-9-no600.tbcn.telia.com no_success_auth
May 19 06:10:33 mail nnrpd[96103]: Reading access from /usr/local/news/etc/readers.conf
May 19 06:10:33 mail nnrpd[96103]: Auth strategy 'localhost' does not match client. Removing.
May 19 06:10:33 mail nnrpd[96103]: 193.124.20.251 no_success_auth
May 19 07:38:01 mail nnrpd[100505]: Reading access from /usr/local/news/etc/readers.conf
May 19 07:38:01 mail nnrpd[100505]: Auth strategy 'localhost' does not match client. Removing.
May 19 07:38:01 mail nnrpd[100505]: 69.5.169.96 no_success_auth
May 19 07:53:12 mail nnrpd[101266]: Reading access from /usr/local/news/etc/readers.conf
May 19 07:53:12 mail nnrpd[101266]: Auth strategy 'localhost' does not match client. Removing.
May 19 07:53:12 mail nnrpd[101266]: 45-79-207-181.ip.linodeusercontent.com no_success_auth
May 19 10:49:27 mail nnrpd[109530]: Reading access from /usr/local/news/etc/readers.conf
May 19 10:49:27 mail nnrpd[109530]: Auth strategy 'localhost' does not match client. Removing.
May 19 10:49:27 mail nnrpd[109530]: 209.141.54.55 no_success_auth
May 19 17:19:40 mail nnrpd[128209]: Reading access from /usr/local/news/etc/readers.conf
May 19 17:19:40 mail nnrpd[128209]: Auth strategy 'localhost' does not match client. Removing.
May 19 17:19:40 mail nnrpd[128209]: 53.146.94.167.censys-scanner.com no_success_auth
May 19 17:19:44 mail nnrpd[128211]: Reading access from /usr/local/news/etc/readers.conf
May 19 17:19:44 mail nnrpd[128211]: Auth strategy 'localhost' does not match client. Removing.
May 19 17:19:44 mail nnrpd[128211]: 53.146.94.167.censys-scanner.com no_success_auth
May 19 17:19:50 mail nnrpd[128214]: Reading access from /usr/local/news/etc/readers.conf
May 19 17:19:50 mail nnrpd[128214]: Auth strategy 'localhost' does not match client. Removing.
May 19 17:19:50 mail nnrpd[128214]: 53.146.94.167.censys-scanner.com no_success_auth
May 19 17:19:50 mail nnrpd[128214]: unable to get certificates from '/usr/local/news/etc/cert.pem'
May 19 17:19:50 mail nnrpd[128214]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]
May 19 21:57:53 mail innd: message repeated 2 times: [ ctlinnd command s]
May 19 22:32:44 mail nnrpd[142822]: Reading access from /usr/local/news/etc/readers.conf
May 19 22:32:44 mail nnrpd[142822]: twilightnode.org res <localhost>
May 19 22:32:44 mail nnrpd[142822]: twilightnode.org match_user <localhost> <localhost>
May 19 22:40:46 mail nnrpd[143289]: Reading access from /usr/local/news/etc/readers.conf
May 19 22:40:46 mail nnrpd[143289]: Auth strategy 'localhost' does not match client. Removing.
May 19 22:40:46 mail nnrpd[143289]: azpdws8p4rry.stretchoid.com no_success_auth
May 19 22:40:56 mail nnrpd[143294]: Reading access from /usr/local/news/etc/readers.conf
May 19 22:40:56 mail nnrpd[143294]: Auth strategy 'localhost' does not match client. Removing.
May 19 22:40:56 mail nnrpd[143294]: azpdws8p4rry.stretchoid.com no_success_auth
May 19 23:23:44 mail nnrpd[146446]: Reading access from /usr/local/news/etc/readers.conf
May 19 23:23:44 mail nnrpd[146446]: twilightnode.org res <localhost>
May 19 23:23:44 mail nnrpd[146446]: twilightnode.org match_user <localhost> <localhost>
May 20 00:08:17 mail nnrpd[149134]: Reading access from /usr/local/news/etc/readers.conf
May 20 00:08:17 mail nnrpd[149134]: twilightnode.org res <localhost>
May 20 00:08:17 mail nnrpd[149134]: twilightnode.org match_user <localhost> <localhost>
May 20 00:09:50 mail nnrpd[149368]: Reading access from /usr/local/news/etc/readers.conf
May 20 00:09:50 mail nnrpd[149368]: twilightnode.org res <localhost>
May 20 00:09:50 mail nnrpd[149368]: twilightnode.org match_user <localhost> <localhost>
May 20 00:20:21 mail nnrpd[150241]: Reading access from /usr/local/news/etc/readers.conf
May 20 00:20:21 mail nnrpd[150241]: twilightnode.org res <localhost>

Log entries by program:

Program nameLines%LinesSize%Size
inn 6103 68.5%854.7 KB 70.5%
innd 1480 16.6%193.6 KB 16.0%
innfeed 1194 13.4%151.2 KB 12.5%
nnrpd 135 1.5%12.8 KB 1.1%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 8914 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6780 35.8%
Do not exist 6091 32.1%
Positive hits 6080 32.1%
Cache misses 0 0.0%
TOTAL: 4 18951 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%0 0.000 0.000 0.000
article cleanup00:00:00.077 0.0%6105 0.000 0.013 0.120
article logging00:00:00.271 0.0%6105 0.000 0.044 0.484
article parse00:00:00.112 0.0%7537 0.000 0.015 0.136
article write00:00:01.406 0.0%1450 0.091 0.970 3.667
data move00:00:00.050 0.0%18988 0.000 0.003 0.031
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.780 0.0%18997 0.128 0.252 0.551
history sync00:00:00.080 0.0%415 0.000 0.193 1.333
history write00:00:04.153 0.0%6105 0.333 0.680 1.350
idle24:00:40.981100.0%19379 1924.356 4460.549 20718.586
nntp read00:00:00.459 0.0%19026 0.000 0.024 0.076
overview write00:00:01.049 0.0%1450 0.182 0.723 3.000
perl filter00:00:13.559 0.0%6105 0.967 2.221 14.810
python filter00:00:01.145 0.0%6110 0.027 0.187 1.238
site send00:00:00.067 0.0%1450 0.000 0.046 0.333
TOTAL: 1624:01:08.189100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.012 0.0%1447 0.000 0.008 0.250
article prepare00:00:00.001 0.0%11568 0.000 0.000 0.008
article read00:00:00.408 0.0%1447 0.000 0.282 3.167
backlog stats00:00:00.022 0.0%13162 0.000 0.002 0.020
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.096 0.0%5480 0.000 0.018 0.154
data write00:00:00.144 0.0%4071 0.000 0.035 0.200
idle23:58:45.140100.0%13162 2879.780 6558.664 12020.840
status file00:00:00.238 0.0%190 0.000 1.253 12.500
TOTAL: 923:58:46.061100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:10:17.678100.0%35 2.750 17647.943299979.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.003 0.0%136 0.000 0.022 0.167
TOTAL: 300:10:17.681100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 12682 1468 6527 4687 11%50:10:22
2localhost 30 5 5 0 0 100%00:00:00
3news.chimia.se 0 0 0 0 0 0%00:10:00
4news.twilightnode.org 5 0 0 0 0 0%00:00:00
TOTAL: 4 39 12687 1473 6527 4687 11%50:20:22
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net4.2 MB0.0 KB13.4 MB17.6 MB 23%2.9 KB
2localhost3.7 KB0.0 KB0.0 KB3.7 KB100%0.7 KB
3news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
4news.twilightnode.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 44.2 MB0.0 KB13.4 MB17.6 MB 23%2.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 19 03:00:04 - 03:59:59 53 3.7% 0.01131.5 KB 3.1% 0.04
May 19 04:00:00 - 04:59:59 66 4.6% 0.02167.4 KB 3.9% 0.05
May 19 05:00:00 - 05:59:59 49 3.4% 0.01433.0 KB 10.1% 0.12
May 19 06:00:00 - 06:59:59 90 6.2% 0.03276.7 KB 6.5% 0.08
May 19 07:00:00 - 07:59:59 44 3.0% 0.0187.5 KB 2.0% 0.02
May 19 08:00:00 - 08:59:59 46 3.2% 0.0194.7 KB 2.2% 0.03
May 19 09:00:00 - 09:59:59 40 2.8% 0.0166.3 KB 1.5% 0.02
May 19 10:00:00 - 10:59:59 48 3.3% 0.01105.1 KB 2.5% 0.03
May 19 11:00:00 - 11:59:59 49 3.4% 0.01123.1 KB 2.9% 0.03
May 19 12:00:00 - 12:59:59 51 3.5% 0.0194.3 KB 2.2% 0.03
May 19 13:00:00 - 13:59:59 49 3.4% 0.0192.6 KB 2.2% 0.03
May 19 14:00:00 - 14:59:59 60 4.1% 0.02158.5 KB 3.7% 0.04
May 19 15:00:00 - 15:59:59 67 4.6% 0.02181.4 KB 4.2% 0.05
May 19 16:00:00 - 16:59:59 53 3.7% 0.01163.9 KB 3.8% 0.05
May 19 17:00:00 - 17:59:59 82 5.7% 0.02247.9 KB 5.8% 0.07
May 19 18:00:00 - 18:59:59 86 5.9% 0.02313.3 KB 7.3% 0.09
May 19 19:00:00 - 19:59:59 65 4.5% 0.02229.8 KB 5.4% 0.06
May 19 20:00:00 - 20:59:59 85 5.9% 0.02159.9 KB 3.7% 0.04
May 19 21:00:00 - 21:59:59 55 3.8% 0.02222.9 KB 5.2% 0.06
May 19 22:00:00 - 22:59:59 55 3.8% 0.02231.9 KB 5.4% 0.06
May 19 23:00:00 - 23:59:59 57 3.9% 0.02149.4 KB 3.5% 0.04
May 20 00:00:00 - 00:59:59 84 5.8% 0.02219.3 KB 5.1% 0.06
May 20 01:00:00 - 01:59:59 58 4.0% 0.02157.6 KB 3.7% 0.04
May 20 02:00:00 - 02:59:59 48 3.3% 0.01165.2 KB 3.9% 0.05
May 20 03:00:00 - 03:00:02 8 0.6% 4.006.4 KB 0.1% 3.18
TOTAL: 23:59:58 1448 100.0% 0.024.2 MB 100.0% 0.05
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 4655 4101 0 0 0 0 0 0 554
TOTAL: 1 4655 4101 0 0 0 0 0 0 554

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1094
linux.debian.changes.devel 185
linux.debian.bugs.dist 156
free.pt 147
alt.usage.english 107
fido7.ru.news 105
fr.soc.politique 92
it.hobby.cucina 91
alt.buddha.short.fat.guy 90
linux.debian.bugs.rc 90
de.soc.politik.misc 87
it.politica 84
fr.sci.maths 69
uk.legal.moderated 69
ger.ct 55
de.talk.tagesgeschehen 54
uk.railway 45
alt.fan.rush-limbaugh 44
it.test 41
pl.soc.prawo 41
TOTAL: 243 4101

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 308
EMP (phl) 180
User-issued cancel 28
EMP (md5) 20
Too many newsgroups 13
HTML Multipart 5
TOTAL: 6 554

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 1449 1448 0 0 0 0 99%23:57:02
2thebunker.nu 1457 1309 0 144 0 2 89%23:59:54
3weretis.net 5 0 0 5 0 0 0%00:52:03
4sklaff.dev 0 0 0 0 0 1443 0%23:57:40
5usenet.blueworldhosting.com 0 0 0 0 0 1216 0%23:59:54
TOTAL: 5 2911 2757 0 149 0 2661 94%96:46:33
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se4.2 MB0.0 KB4.2 MB0.0 KB/s3.0 KB23:57:02
2thebunker.nu3.7 MB485.2 KB4.2 MB0.0 KB/s3.0 KB23:59:54
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:40
4usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
5weretis.net0.0 KB3.7 KB3.7 KB0.0 KB/s0.7 KB00:52:03
TOTAL: 57.9 MB488.9 KB8.4 MB0.0 KB/s3.0 KB96:46:33
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1twilightnode.org 5 00.0 KB 0 5 000:00:00
253.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:03
3195-252-55-9-no600.tbcn.telia.com 2 00.0 KB 0 0 000:10:00
4azpdws8p4rry.stretchoid.com 2 00.0 KB 0 0 000:00:10
5193.124.20.251 1 00.0 KB 0 0 000:00:00
6209.141.54.55 1 00.0 KB 0 0 000:00:00
745-79-207-181.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
869.5.169.96 1 00.0 KB 0 0 000:00:00
TOTAL: 8 16 00.0 KB 0 5 000:10:17

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.org 5 00.0 KB 0 5 000:00:00
2*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:03
3unresolved 3 00.0 KB 0 0 000:00:01
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
5*.tbcn.telia.com 2 00.0 KB 0 0 000:10:00
6*.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
TOTAL: 6 16 00.0 KB 0 5 000:10:17

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.006 0.035 0.00000:10:00
azpdws8p4rry.stretchoid.com 0.010 0.021 0.00000:00:10
53.146.94.167.censys-scanner.com 0.031 0.034 0.00000:00:03
45-79-207-181.ip.linodeusercontent.com 0.008 0.013 0.00000:00:01
209.141.54.55 0.003 0.018 0.00000:00:00
193.124.20.251 0.003 0.016 0.00000:00:00
69.5.169.96 0.007 0.013 0.00000:00:00
twilightnode.org 0.025 0.084 0.00000:00:00
TOTAL: 8 0.093 0.234 0.00000:10:17

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

SystemConn
45-79-207-181.ip.linodeusercontent.com 5
53.146.94.167.censys-scanner.com 1
azpdws8p4rry.stretchoid.com 1
TOTAL: 3 7

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

CommandCount
Accept-Encoding: gzip 1
Accept: */* 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63 1
MGLNDD_172.234.115.63_119 1
User-Agent: Mozilla/5.0 zgrab/0.x 1
TOTAL: 7 7

NNRP client timeouts [Top 20]:

SystemConnPeer
45-79-207-181.ip.linodeusercontent.com 1 1
53.146.94.167.censys-scanner.com 1 2
69.5.169.96 1 1
TOTAL: 3 3 5
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