Daily Usenet report

May 24 03:00:06 -- May 25 03:00:07

Unknown entries from news log file:

First 50 / 76 lines (65.8%)

May 24 03:01:41 mail innd: message repeated 2 times: [ ctlinnd command s]
May 24 04:27:33 mail nnrpd[431347]: Reading access from /usr/local/news/etc/readers.conf
May 24 04:27:33 mail nnrpd[431347]: Auth strategy 'localhost' does not match client. Removing.
May 24 04:27:33 mail nnrpd[431347]: zl-dala-us-gp1-wk124d.internet-census.org no_success_auth
May 24 10:32:17 mail nnrpd[446640]: Reading access from /usr/local/news/etc/readers.conf
May 24 10:32:17 mail nnrpd[446640]: Auth strategy 'localhost' does not match client. Removing.
May 24 10:32:17 mail nnrpd[446640]: 45.172.132.66.censys-scanner.com no_success_auth
May 24 10:32:18 mail nnrpd[446640]: unable to get certificates from '/usr/local/news/etc/cert.pem'
May 24 10:32:18 mail nnrpd[446640]: 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 24 10:56:00 mail nnrpd[447853]: Reading access from /usr/local/news/etc/readers.conf
May 24 10:56:00 mail nnrpd[447853]: Auth strategy 'localhost' does not match client. Removing.
May 24 10:56:00 mail nnrpd[447853]: 194.50.235.158 no_success_auth
May 24 11:05:57 mail nnrpd[448241]: Reading access from /usr/local/news/etc/readers.conf
May 24 11:05:57 mail nnrpd[448241]: Auth strategy 'localhost' does not match client. Removing.
May 24 11:05:57 mail nnrpd[448241]: 69.5.169.92 no_success_auth
May 24 13:45:58 mail nnrpd[454615]: Reading access from /usr/local/news/etc/readers.conf
May 24 13:45:58 mail nnrpd[454615]: Auth strategy 'localhost' does not match client. Removing.
May 24 13:45:58 mail nnrpd[454615]: 69.5.169.108 no_success_auth
May 24 15:03:05 mail nnrpd[457627]: Reading access from /usr/local/news/etc/readers.conf
May 24 15:03:05 mail nnrpd[457627]: Auth strategy 'localhost' does not match client. Removing.
May 24 15:03:05 mail nnrpd[457627]: 93.152.208.38 no_success_auth
May 24 15:03:06 mail nnrpd[457628]: Reading access from /usr/local/news/etc/readers.conf
May 24 15:03:06 mail nnrpd[457628]: Auth strategy 'localhost' does not match client. Removing.
May 24 15:03:06 mail nnrpd[457628]: 93.152.208.38 no_success_auth
May 24 16:03:08 mail nnrpd[459971]: Reading access from /usr/local/news/etc/readers.conf
May 24 16:03:08 mail nnrpd[459971]: Auth strategy 'localhost' does not match client. Removing.
May 24 16:03:08 mail nnrpd[459971]: 69.5.169.9 no_success_auth
May 24 16:52:46 mail nnrpd[462114]: Reading access from /usr/local/news/etc/readers.conf
May 24 16:52:46 mail nnrpd[462114]: Auth strategy 'localhost' does not match client. Removing.
May 24 16:52:46 mail nnrpd[462114]: 69.5.169.39 no_success_auth
May 24 18:32:18 mail nnrpd[466126]: Reading access from /usr/local/news/etc/readers.conf
May 24 18:32:18 mail nnrpd[466126]: Auth strategy 'localhost' does not match client. Removing.
May 24 18:32:18 mail nnrpd[466126]: azpdcsxdspys.stretchoid.com no_success_auth
May 24 18:32:29 mail nnrpd[466127]: Reading access from /usr/local/news/etc/readers.conf
May 24 18:32:29 mail nnrpd[466127]: Auth strategy 'localhost' does not match client. Removing.
May 24 18:32:29 mail nnrpd[466127]: azpdcsxdspys.stretchoid.com no_success_auth
May 24 22:56:38 mail nnrpd[480029]: Reading access from /usr/local/news/etc/readers.conf
May 24 22:56:38 mail nnrpd[480029]: Auth strategy 'localhost' does not match client. Removing.
May 24 22:56:38 mail nnrpd[480029]: 195-252-55-9-no600.tbcn.telia.com no_success_auth
May 24 22:56:39 mail nnrpd[480029]: unable to get certificates from '/etc/letsencrypt/live/news.twilightnode.org/fullchain.pem'
May 24 22:56:39 mail nnrpd[480029]: error initializing TLS: [CA_file: ] [CA_path: /etc/letsencrypt/live/news.twlilightnode.org] [cert_file: /etc/letsencrypt/live/news.twilightnode.org/fullchain.pem] [key_file: /etc/letsencrypt/live/news.twilightnode.org/privkey.pem]
May 24 23:00:04 mail nnrpd[480189]: Reading access from /usr/local/news/etc/readers.conf
May 24 23:00:04 mail nnrpd[480189]: Auth strategy 'localhost' does not match client. Removing.
May 24 23:00:04 mail nnrpd[480189]: 195-252-55-9-no600.tbcn.telia.com no_success_auth
May 24 23:00:05 mail nnrpd[480189]: unable to get certificates from '/etc/letsencrypt/live/news.twilightnode.org/fullchain.pem'
May 24 23:00:05 mail nnrpd[480189]: error initializing TLS: [CA_file: ] [CA_path: /etc/letsencrypt/live/news.twilightnode.org] [cert_file: /etc/letsencrypt/live/news.twilightnode.org/fullchain.pem] [key_file: /etc/letsencrypt/live/news.twilightnode.org/privkey.pem]
May 24 23:00:41 mail nnrpd[480218]: Reading access from /usr/local/news/etc/readers.conf
May 24 23:00:41 mail nnrpd[480218]: Auth strategy 'localhost' does not match client. Removing.
May 24 23:00:41 mail nnrpd[480218]: 195-252-55-9-no600.tbcn.telia.com no_success_auth
May 24 23:00:41 mail nnrpd[480218]: unable to get certificates from '/etc/letsencrypt/live/news.twilightnode.org/fullchain.pem'

Log entries by program:

Program nameLines%LinesSize%Size
inn 6404 65.1%916.3 KB 68.0%
innfeed 1955 19.9%236.3 KB 17.5%
innd 1272 12.9%174.7 KB 13.0%
nnrpd 174 1.8%17.3 KB 1.3%
controlchan 30 0.3%2.1 KB 0.2%
TOTAL: 5 9835 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 7851 27.6%
Cache misses 7450 26.2%
Negative hits 6856 24.1%
Do not exist 6304 22.1%
TOTAL: 4 28461 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.079 0.0%6322 0.000 0.012 0.167
article logging00:00:00.335 0.0%6325 0.000 0.053 0.250
article parse00:00:00.132 0.0%8464 0.000 0.016 0.125
article write00:00:01.794 0.0%2200 0.222 0.815 4.222
data move00:00:00.053 0.0%29537 0.000 0.002 0.030
hisgrep/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.496 0.0%28517 0.090 0.193 0.632
history sync00:00:00.063 0.0%411 0.000 0.153 1.000
history write00:00:04.034 0.0%6320 0.273 0.638 3.474
idle23:50:37.611100.0%30181 55.062 2844.094 35343.588
nntp read00:00:00.563 0.0%29807 0.000 0.019 0.124
overview write00:00:01.344 0.0%2200 0.000 0.611 2.429
perl filter00:00:12.966 0.0%6086 1.091 2.130 17.389
python filter00:00:01.199 0.0%15947 0.018 0.075 1.611
site send00:00:00.098 0.0%2200 0.000 0.045 0.286
TOTAL: 1723:51:05.767100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.022 0.0%2175 0.000 0.010 0.250
article prepare00:00:00.002 0.0%17084 0.000 0.000 0.004
article read00:00:00.519 0.0%2158 0.000 0.241 1.714
backlog stats00:00:00.019 0.0%16668 0.000 0.001 0.036
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.129 0.0%8013 0.000 0.016 0.196
data write00:00:00.172 0.0%5915 0.000 0.029 0.214
idle23:28:13.629100.0%16668 438.150 5069.212 13375.689
status file00:00:00.282 0.0%210 0.000 1.343 37.000
TOTAL: 923:28:14.774100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:40:57.810100.0%47 41.000 52293.830900131.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.003 0.0%326 0.000 0.009 0.250
TOTAL: 300:40:57.813100.0%----

Control commands to innd:

CommandNumber
flush 3
flushlogs 2
go 2
logmode 2
lowmark 1
mode 150
name 2
newgroup 10
pause 2
paused 2
reload 2
reserve 2
xexec 5
TOTAL: 13 185

Newsgroups created:

GroupMode
alt.arts.poetry.commentsy
alt.buddha.short.fat.guyy
fido7.binkdy
fido7.su.poly
free.pty
free.usenety
linux.debian.bugs.disty
linux.debian.bugs.rcy
linux.debian.changes.devely
linux.debian.devel.releasey
TOTAL10

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 14 11303 2009 5394 3900 17%47:34:51
2localhost 26 9861 163 9462 236 1%00:03:40
3news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 3 41 21164 2172 14856 4136 10%47:48:31
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net6.0 MB0.0 KB11.8 MB17.8 MB 33%3.1 KB
2localhost359.8 KB13.8 KB912.8 KB1.3 MB 27%3.2 KB
3news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 36.4 MB13.8 KB12.7 MB19.1 MB 33%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 24 03:00:06 - 03:59:59 44 2.0% 0.01169.8 KB 2.5% 0.05
May 24 04:00:00 - 04:59:59 41 1.8% 0.01128.8 KB 1.9% 0.04
May 24 05:00:00 - 05:59:59 127 5.6% 0.04398.1 KB 5.8% 0.11
May 24 06:00:00 - 06:59:59 35 1.6% 0.0151.6 KB 0.8% 0.01
May 24 07:00:00 - 07:59:59 81 3.6% 0.02241.2 KB 3.5% 0.07
May 24 08:00:00 - 08:59:59 44 2.0% 0.0173.7 KB 1.1% 0.02
May 24 09:00:00 - 09:59:59 64 2.8% 0.02118.8 KB 1.7% 0.03
May 24 10:00:00 - 10:59:59 59 2.6% 0.02137.3 KB 2.0% 0.04
May 24 11:00:00 - 11:59:59 54 2.4% 0.01127.1 KB 1.9% 0.04
May 24 12:00:00 - 12:59:59 54 2.4% 0.01128.7 KB 1.9% 0.04
May 24 13:00:00 - 13:59:59 79 3.5% 0.02151.8 KB 2.2% 0.04
May 24 14:00:00 - 14:59:59 68 3.0% 0.02168.5 KB 2.5% 0.05
May 24 15:00:00 - 15:59:59 60 2.7% 0.02148.9 KB 2.2% 0.04
May 24 16:00:00 - 16:59:59 68 3.0% 0.02209.7 KB 3.1% 0.06
May 24 17:00:00 - 17:59:59 76 3.4% 0.02472.0 KB 6.9% 0.13
May 24 18:00:00 - 18:59:59 86 3.8% 0.02359.8 KB 5.3% 0.10
May 24 19:00:00 - 19:59:59 143 6.4% 0.04405.9 KB 6.0% 0.11
May 24 20:00:00 - 20:59:59 112 5.0% 0.03343.7 KB 5.1% 0.10
May 24 21:00:00 - 21:59:59 77 3.4% 0.02189.8 KB 2.8% 0.05
May 24 22:00:00 - 22:59:59 277 12.3% 0.08674.5 KB 9.9% 0.19
May 24 23:00:00 - 23:59:59 195 8.7% 0.05666.2 KB 9.8% 0.19
May 25 00:00:00 - 00:59:59 225 10.0% 0.06825.4 KB 12.1% 0.23
May 25 01:00:00 - 01:59:59 93 4.1% 0.03272.1 KB 4.0% 0.08
May 25 02:00:00 - 02:59:59 75 3.3% 0.02269.3 KB 4.0% 0.07
May 25 03:00:00 - 03:00:07 12 0.5% 1.7173.3 KB 1.1% 10.47
TOTAL: 24:00:01 2249 100.0% 0.036.6 MB 100.0% 0.08
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 3919 3775 0 0 0 0 0 0 144
2localhost 236 0 0 2 0 234 0 0 0
TOTAL: 2 4155 3775 0 2 0 234 0 0 144

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 779
alt.buddha.short.fat.guy 156
de.soc.politik.misc 137
linux.debian.bugs.dist 136
linux.debian.changes.devel 130
free.pt 129
fr.soc.politique 123
uk.railway 114
linux.debian.bugs.rc 74
it.sport.calcio.milan 71
nl.politiek 65
de.talk.tagesgeschehen 61
de.etc.sprache.deutsch 51
alt.arts.poetry.comments 50
it.test 50
de.comp.os.unix.linux.misc 48
lada.talk 45
alt.slack 43
alt.fan.rush-limbaugh 42
uk.d-i-y 40
TOTAL: 247 3775

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phl) 51
User-issued cancel 51
EMP (phn path) 16
Too many newsgroups 9
HTML Multipart 7
EMP (md5) 5
TOTAL: 6 139

Miscellaneous innd events:

EventsCount
CCreader 1
TOTAL: 1 1

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
localhost 1
TOTAL: 1 1
Including strange strings
twilightnode-out.news.weretis.net 5
TOTAL: 1 5
TOTAL: 2 6

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 2244 2150 35 57 0 0 95%23:59:22
2thebunker.nu 2233 1946 0 286 0 0 87%23:59:22
3weretis.net 89 0 89 0 0 0 0%00:56:49
4sklaff.dev 0 0 0 0 0 2210 0%23:44:07
5usenet.blueworldhosting.com 0 0 0 0 0 2024 0%23:59:20
TOTAL: 5 4566 4096 124 343 0 4234 89%96:39:00
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se6.4 MB127.0 KB6.6 MB0.1 KB/s3.0 KB23:59:22
2thebunker.nu5.6 MB1.1 MB6.6 MB0.1 KB/s3.0 KB23:59:22
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:44:07
4usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:20
5weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:56:49
TOTAL: 512.0 MB1.2 MB13.2 MB0.0 KB/s3.0 KB96:39:00
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1195-252-55-9-no600.tbcn.telia.com 7 00.0 KB 0 0 000:40:32
293.152.208.38 2 00.0 KB 0 0 000:00:00
3azpdcsxdspys.stretchoid.com 2 00.0 KB 0 0 000:00:10
4194.50.235.158 1 00.0 KB 0 0 000:00:00
545.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
669.5.169.108 1 00.0 KB 0 0 000:00:00
769.5.169.18 1 00.0 KB 0 0 000:00:00
869.5.169.39 1 00.0 KB 0 0 000:00:00
969.5.169.9 1 00.0 KB 0 0 000:00:00
1069.5.169.92 1 00.0 KB 0 0 000:00:00
11zl-dala-us-gp1-wk124d.internet-census.org 1 00.0 KB 0 0 000:00:10
TOTAL: 11 19 00.0 KB 0 0 000:40:57

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 8 00.0 KB 0 0 000:00:02
2*.tbcn.telia.com 7 00.0 KB 0 0 000:40:32
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
5*.internet-census.org 1 00.0 KB 0 0 000:00:10
TOTAL: 5 19 00.0 KB 0 0 000:40:57

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.229 0.118 0.00500:40:32
azpdcsxdspys.stretchoid.com 0.009 0.015 0.00000:00:10
zl-dala-us-gp1-wk124d.internet-census.org 0.005 0.019 0.00000:00:10
45.172.132.66.censys-scanner.com 0.041 0.025 0.00000:00:01
69.5.169.39 0.004 0.018 0.00000:00:00
69.5.169.18 0.005 0.020 0.00000:00:00
93.152.208.38 0.007 0.018 0.00000:00:00
69.5.169.108 0.006 0.015 0.00000:00:00
69.5.169.92 0.006 0.009 0.00000:00:00
69.5.169.9 0.006 0.017 0.00000:00:00
194.50.235.158 0.007 0.008 0.00000:00:00
TOTAL: 11 0.325 0.282 0.00500:40:57

NNRP no permission clients [Top 20]:

SystemConn
195-252-55-9-no600.tbcn.telia.com 1
TOTAL: 1 1

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

SystemConn
93.152.208.38 9
45.172.132.66.censys-scanner.com 1
azpdcsxdspys.stretchoid.com 1
TOTAL: 3 11

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

CommandCount
#026#003#001#002 1
?#024?#011?#023 1
Accept-Encoding: gzip, deflate, br 1
Accept-Language: en-US,en;q=0.9 1
Accept: text/html,application/xhtml+xml,... 1
Connection: close 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
MGLNDD_172.234.115.63_119 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
TOTAL: 11 11

NNRP client timeouts [Top 20]:

SystemConnPeer
195-252-55-9-no600.tbcn.telia.com 2 1
69.5.169.108 1 1
69.5.169.18 1 1
69.5.169.39 1 1
69.5.169.9 1 1
69.5.169.92 1 1
TOTAL: 6 7 7