Daily Usenet report

May 21 03:00:06 -- May 22 03:00:05

Unknown entries from news log file:

First 46 / 46 lines (100.0%)

May 21 03:01:43 mail innd: message repeated 2 times: [ ctlinnd command s]
May 21 03:01:51 mail nnrpd[226916]: Reading access from /usr/local/news/etc/readers.conf
May 21 03:01:51 mail nnrpd[226916]: Auth strategy 'localhost' does not match client. Removing.
May 21 03:01:51 mail nnrpd[226916]: 160.119.76.61 no_success_auth
May 21 03:31:53 mail nnrpd[228294]: Reading access from /usr/local/news/etc/readers.conf
May 21 03:31:53 mail nnrpd[228294]: Auth strategy 'localhost' does not match client. Removing.
May 21 03:31:53 mail nnrpd[228294]: azpdcgcpp7d9.stretchoid.com no_success_auth
May 21 03:32:04 mail nnrpd[228302]: Reading access from /usr/local/news/etc/readers.conf
May 21 03:32:04 mail nnrpd[228302]: Auth strategy 'localhost' does not match client. Removing.
May 21 03:32:04 mail nnrpd[228302]: azpdcgcpp7d9.stretchoid.com no_success_auth
May 21 05:02:22 mail nnrpd[232287]: Reading access from /usr/local/news/etc/readers.conf
May 21 05:02:22 mail nnrpd[232287]: Auth strategy 'localhost' does not match client. Removing.
May 21 05:02:22 mail nnrpd[232287]: 81.19.219.225 no_success_auth
May 21 05:02:36 mail nnrpd[232291]: Reading access from /usr/local/news/etc/readers.conf
May 21 05:02:36 mail nnrpd[232291]: Auth strategy 'localhost' does not match client. Removing.
May 21 05:02:36 mail nnrpd[232291]: 69.5.169.35 no_success_auth
May 21 05:02:46 mail nnrpd[232293]: Reading access from /usr/local/news/etc/readers.conf
May 21 05:02:46 mail nnrpd[232293]: Auth strategy 'localhost' does not match client. Removing.
May 21 05:02:46 mail nnrpd[232293]: 69.5.169.44 no_success_auth
May 21 06:37:18 mail nnrpd[237198]: Reading access from /usr/local/news/etc/readers.conf
May 21 06:37:18 mail nnrpd[237198]: Auth strategy 'localhost' does not match client. Removing.
May 21 06:37:18 mail nnrpd[237198]: 66-228-62-150.ip.linodeusercontent.com no_success_auth
May 21 07:36:31 mail nnrpd[240313]: Reading access from /usr/local/news/etc/readers.conf
May 21 07:36:31 mail nnrpd[240313]: Auth strategy 'localhost' does not match client. Removing.
May 21 07:36:31 mail nnrpd[240313]: 5.226.140.62 no_success_auth
May 21 07:36:59 mail nnrpd[240315]: Reading access from /usr/local/news/etc/readers.conf
May 21 07:36:59 mail nnrpd[240315]: Auth strategy 'localhost' does not match client. Removing.
May 21 07:36:59 mail nnrpd[240315]: 69.5.169.117 no_success_auth
May 21 07:36:59 mail nnrpd[240316]: Reading access from /usr/local/news/etc/readers.conf
May 21 07:36:59 mail nnrpd[240316]: Auth strategy 'localhost' does not match client. Removing.
May 21 07:36:59 mail nnrpd[240316]: 69.5.169.106 no_success_auth
May 21 14:36:30 mail nnrpd[260497]: Reading access from /usr/local/news/etc/readers.conf
May 21 14:36:30 mail nnrpd[260497]: Auth strategy 'localhost' does not match client. Removing.
May 21 14:36:30 mail nnrpd[260497]: 174.186.132.66.censys-scanner.com no_success_auth
May 21 14:36:31 mail nnrpd[260497]: unable to get certificates from '/usr/local/news/etc/cert.pem'
May 21 14:36:31 mail nnrpd[260497]: 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 21 19:47:44 mail nnrpd[274624]: Reading access from /usr/local/news/etc/readers.conf
May 21 19:47:44 mail nnrpd[274624]: Auth strategy 'localhost' does not match client. Removing.
May 21 19:47:44 mail nnrpd[274624]: 147.185.133.104 no_success_auth
May 21 22:20:59 mail nnrpd[281978]: Reading access from /usr/local/news/etc/readers.conf
May 21 22:20:59 mail nnrpd[281978]: twilightnode.org res <localhost>
May 21 22:20:59 mail nnrpd[281978]: twilightnode.org match_user <localhost> <localhost>
May 21 23:19:45 mail innd: message repeated 2 times: [ ctlinnd command s]
May 22 03:00:04 mail nnrpd[296557]: Reading access from /usr/local/news/etc/readers.conf
May 22 03:00:04 mail nnrpd[296557]: Auth strategy 'localhost' does not match client. Removing.
May 22 03:00:04 mail nnrpd[296557]: 195-252-55-9-no600.tbcn.telia.com no_success_auth

Log entries by program:

Program nameLines%LinesSize%Size
inn 5927 69.3%843.5 KB 71.0%
innd 1313 15.4%180.2 KB 15.2%
innfeed 1171 13.7%150.7 KB 12.7%
nnrpd 134 1.6%12.7 KB 1.1%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 8547 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6610 35.9%
Do not exist 5914 32.1%
Positive hits 5909 32.1%
Cache misses 2 0.0%
TOTAL: 4 18435 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.005 0.0%3 0.000 1.667 4.000
article cleanup00:00:00.073 0.0%5927 0.000 0.012 0.100
article logging00:00:00.281 0.0%5927 0.000 0.047 0.379
article parse00:00:00.126 0.0%7314 0.000 0.017 0.188
article write00:00:01.365 0.0%1621 0.000 0.842 3.125
data move00:00:00.054 0.0%18963 0.000 0.003 0.038
hisgrep/artcncl00:00:00.003 0.0%3 0.000 1.000 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.613 0.0%18477 0.127 0.250 0.496
history sync00:00:00.052 0.0%412 0.000 0.126 1.000
history write00:00:03.714 0.0%5927 0.286 0.627 3.045
idle24:00:37.592100.0%18931 1973.247 4565.928 16670.806
nntp read00:00:00.505 0.0%18587 0.000 0.027 0.364
overview write00:00:01.156 0.0%1621 0.071 0.713 3.111
perl filter00:00:13.634 0.0%5927 1.109 2.300 18.550
python filter00:00:01.198 0.0%5928 0.074 0.202 1.900
site send00:00:00.065 0.0%1621 0.000 0.040 0.400
TOTAL: 1724:01:04.436100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.020 0.0%1612 0.000 0.012 0.167
article prepare00:00:00.000 0.0%12896 0.000 0.000 0.000
article read00:00:00.409 0.0%1612 0.000 0.254 1.571
backlog stats00:00:00.022 0.0%13393 0.000 0.002 0.033
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.091 0.0%5768 0.000 0.016 0.136
data write00:00:00.135 0.0%4197 0.000 0.032 0.167
idle23:59:12.388100.0%13393 2896.788 6447.576 14304.727
status file00:00:00.257 0.0%176 0.000 1.460 21.000
TOTAL: 923:59:13.322100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:10:21.145100.0%29 7.667 21418.793300278.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%88 0.000 0.000 0.000
TOTAL: 300:10:21.145100.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 3 11414 1534 5723 4157 13%45:46:29
2localhost 26 1 1 0 0 100%00:00:00
3news.chimia.se 1 0 0 0 0 0%00:10:00
4news.twilightnode.org 1 0 0 0 0 0%00:00:00
TOTAL: 4 31 11415 1535 5723 4157 13%45:56:29
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net4.9 MB0.0 KB12.3 MB17.2 MB 28%3.1 KB
2localhost0.6 KB0.0 KB0.0 KB0.6 KB100%0.6 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.9 MB0.0 KB12.3 MB17.2 MB 28%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 21 03:00:06 - 03:59:59 50 3.1% 0.01139.0 KB 2.6% 0.04
May 21 04:00:00 - 04:59:59 53 3.3% 0.01116.5 KB 2.2% 0.03
May 21 05:00:00 - 05:59:59 45 2.8% 0.01109.0 KB 2.1% 0.03
May 21 06:00:00 - 06:59:59 71 4.4% 0.02203.9 KB 3.9% 0.06
May 21 07:00:00 - 07:59:59 43 2.7% 0.0186.4 KB 1.6% 0.02
May 21 08:00:00 - 08:59:59 49 3.0% 0.01116.6 KB 2.2% 0.03
May 21 09:00:00 - 09:59:59 58 3.6% 0.02240.5 KB 4.6% 0.07
May 21 10:00:00 - 10:59:59 41 2.5% 0.01100.4 KB 1.9% 0.03
May 21 11:00:00 - 11:59:59 45 2.8% 0.01101.9 KB 1.9% 0.03
May 21 12:00:00 - 12:59:59 67 4.1% 0.02150.0 KB 2.9% 0.04
May 21 13:00:00 - 13:59:59 74 4.6% 0.02172.9 KB 3.3% 0.05
May 21 14:00:00 - 14:59:59 81 5.0% 0.02300.8 KB 5.7% 0.08
May 21 15:00:00 - 15:59:59 85 5.2% 0.02517.6 KB 9.9% 0.14
May 21 16:00:00 - 16:59:59 62 3.8% 0.02219.3 KB 4.2% 0.06
May 21 17:00:00 - 17:59:59 82 5.1% 0.02460.2 KB 8.8% 0.13
May 21 18:00:00 - 18:59:59 67 4.1% 0.02351.3 KB 6.7% 0.10
May 21 19:00:00 - 19:59:59 59 3.6% 0.02148.6 KB 2.8% 0.04
May 21 20:00:00 - 20:59:59 63 3.9% 0.02163.7 KB 3.1% 0.05
May 21 21:00:00 - 21:59:59 90 5.6% 0.03319.7 KB 6.1% 0.09
May 21 22:00:00 - 22:59:59 81 5.0% 0.02217.6 KB 4.1% 0.06
May 21 23:00:00 - 23:59:59 101 6.2% 0.03323.4 KB 6.2% 0.09
May 22 00:00:00 - 00:59:59 102 6.3% 0.03334.7 KB 6.4% 0.09
May 22 01:00:00 - 01:59:59 69 4.3% 0.02161.1 KB 3.1% 0.04
May 22 02:00:00 - 02:59:59 73 4.5% 0.02187.7 KB 3.6% 0.05
May 22 03:00:00 - 03:00:05 9 0.6% 1.807.1 KB 0.1% 1.43
TOTAL: 23:59:59 1620 100.0% 0.025.1 MB 100.0% 0.06
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 4307 3885 0 0 0 0 0 0 422
TOTAL: 1 4307 3885 0 0 0 0 0 0 422

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 969
free.pt 167
alt.buddha.short.fat.guy 153
linux.debian.changes.devel 127
linux.debian.bugs.dist 104
alt.arts.poetry.comments 89
de.soc.politik.misc 84
fr.sci.maths 65
muc.lists.freebsd.bugs 59
alt.russian.z1 58
de.alt.talk.unmut 56
alt.anonymous.messages 52
de.etc.sprache.deutsch 50
uk.legal.moderated 50
fido7.su.pol 49
fr.soc.politique 49
de.etc.fahrzeug.auto 46
nl.politiek 46
aus.cars 41
cn.comp 40
TOTAL: 245 3885

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 376
User-issued cancel 27
Too many newsgroups 8
EMP (md5) 5
HTML Multipart 4
EMP (phl) 1
Too many newsgroups (meow) 1
TOTAL: 7 422

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 1621 1620 0 0 0 0 99%23:59:31
2thebunker.nu 1619 1491 0 123 0 7 92%23:59:54
3weretis.net 1 0 0 1 0 0 0%00:20:30
4sklaff.dev 0 0 0 0 0 1615 0%23:57:40
5usenet.blueworldhosting.com 0 0 0 0 0 1426 0%23:59:54
TOTAL: 5 3241 3111 0 124 0 3048 95%96:17:29
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se5.1 MB0.0 KB5.1 MB0.1 KB/s3.2 KB23:59:31
2thebunker.nu4.6 MB525.9 KB5.1 MB0.1 KB/s3.3 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 KB0.6 KB0.6 KB0.0 KB/s0.6 KB00:20:30
TOTAL: 59.7 MB526.5 KB10.3 MB0.0 KB/s3.2 KB96:17:29
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1azpdcgcpp7d9.stretchoid.com 2 00.0 KB 0 0 000:00:10
2147.185.133.104 1 00.0 KB 0 0 000:00:01
3160.119.76.61 1 00.0 KB 0 0 000:00:04
4174.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
5195-252-55-9-no600.tbcn.telia.com 1 00.0 KB 0 0 000:10:00
65.226.140.62 1 00.0 KB 0 0 000:00:00
766-228-62-150.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
869.5.169.106 1 00.0 KB 0 0 000:00:00
969.5.169.117 1 00.0 KB 0 0 000:00:00
1069.5.169.35 1 00.0 KB 0 0 000:00:00
1169.5.169.44 1 00.0 KB 0 0 000:00:00
1281.19.219.225 1 00.0 KB 0 0 000:00:00
13twilightnode.org 1 00.0 KB 0 1 000:00:00
TOTAL: 13 14 00.0 KB 0 1 000:10:21

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 8 00.0 KB 0 0 000:00:06
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*.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
5*.org 1 00.0 KB 0 1 000:00:00
6*.tbcn.telia.com 1 00.0 KB 0 0 000:10:00
TOTAL: 6 14 00.0 KB 0 1 000:10:21

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.004 0.018 0.00000:10:00
azpdcgcpp7d9.stretchoid.com 0.007 0.016 0.00000:00:10
160.119.76.61 0.005 0.006 0.00000:00:04
66-228-62-150.ip.linodeusercontent.com 0.006 0.015 0.00000:00:01
174.186.132.66.censys-scanner.com 0.035 0.032 0.00000:00:01
147.185.133.104 0.005 0.020 0.00000:00:01
5.226.140.62 0.004 0.015 0.00000:00:00
81.19.219.225 0.004 0.017 0.00000:00:00
69.5.169.35 0.003 0.010 0.00000:00:00
69.5.169.44 0.004 0.004 0.00000:00:00
69.5.169.106 0.002 0.009 0.00000:00:00
69.5.169.117 0.003 0.005 0.00000:00:00
twilightnode.org 0.005 0.020 0.00000:00:00
TOTAL: 13 0.087 0.187 0.00000:10:21

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

SystemConn
69.5.169.35 6
147.185.133.104 4
69.5.169.106 3
69.5.169.117 3
69.5.169.44 2
174.186.132.66.censys-scanner.com 1
azpdcgcpp7d9.stretchoid.com 1
TOTAL: 7 20

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

CommandCount
#026#003#001#002?#001 3
?#011?#023?#024 3
Accept-Encoding: gzip 2
GET / HTTP/1.1 2
Host: 172.234.115.63:119 2
?#004-?P?%?M~f?@Ep?n?g?#031?)7#026!?... 1
?#010?#015?,?QV?|#022?oU?:?.=#035?#023\?|?#004qV?{?:?#036... 1
Accept: */* 1
Connection: close 1
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
User-Agent: Hello from Palo Alto Network... 1
User-Agent: Mozilla/5.0 (compatible; Inf... 1
TOTAL: 13 20

NNRP client timeouts [Top 20]:

SystemConnPeer
69.5.169.106 1 1
69.5.169.117 1 1
69.5.169.35 1 1
69.5.169.44 1 1
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