Daily Usenet report

May 16 03:00:07 -- May 17 03:00:07

Unknown entries from news log file:

First 50 / 125 lines (40.0%)

May 16 03:02:05 mail innd: message repeated 2 times: [ ctlinnd command s]
May 16 08:09:49 mail nnrpd[24938]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:09:49 mail nnrpd[24938]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:09:49 mail nnrpd[24938]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:14:22 mail nnrpd[25233]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:14:22 mail nnrpd[25233]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:14:22 mail nnrpd[25233]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:14:35 mail nnrpd[25237]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:14:35 mail nnrpd[25237]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:14:35 mail nnrpd[25237]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:14:36 mail nnrpd[25238]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:14:36 mail nnrpd[25238]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:14:36 mail nnrpd[25238]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:14:38 mail nnrpd[25238]: unable to get certificates from '/usr/local/news/etc/cert.pem'
May 16 08:14:38 mail nnrpd[25238]: 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 16 08:14:49 mail nnrpd[25265]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:14:49 mail nnrpd[25265]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:14:49 mail nnrpd[25265]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:15:11 mail nnrpd[25342]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:15:11 mail nnrpd[25342]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:15:11 mail nnrpd[25342]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:15:11 mail nnrpd[25342]: unable to get certificates from '/usr/local/news/etc/cert.pem'
May 16 08:15:11 mail nnrpd[25342]: 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 16 08:15:11 mail nnrpd[25345]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:15:11 mail nnrpd[25345]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:15:11 mail nnrpd[25345]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:15:11 mail nnrpd[25346]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:15:11 mail nnrpd[25346]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:15:11 mail nnrpd[25346]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:15:15 mail nnrpd[25350]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:15:15 mail nnrpd[25350]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:15:15 mail nnrpd[25350]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:15:15 mail nnrpd[25350]: unable to get certificates from '/usr/local/news/etc/cert.pem'
May 16 08:15:15 mail nnrpd[25350]: 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 16 08:15:15 mail nnrpd[25351]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:15:15 mail nnrpd[25351]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:15:15 mail nnrpd[25351]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:15:15 mail nnrpd[25352]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:15:15 mail nnrpd[25352]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:15:15 mail nnrpd[25352]: prod47client01.academyforinternetresearch.org no_success_auth
May 16 08:40:12 mail nnrpd[26333]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:40:12 mail nnrpd[26333]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:40:12 mail nnrpd[26333]: 49.146.94.167.censys-scanner.com no_success_auth
May 16 08:40:15 mail nnrpd[26334]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:40:15 mail nnrpd[26334]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:40:15 mail nnrpd[26334]: 49.146.94.167.censys-scanner.com no_success_auth
May 16 08:40:22 mail nnrpd[26336]: Reading access from /usr/local/news/etc/readers.conf
May 16 08:40:22 mail nnrpd[26336]: Auth strategy 'localhost' does not match client. Removing.
May 16 08:40:22 mail nnrpd[26336]: 49.146.94.167.censys-scanner.com no_success_auth
May 16 08:40:22 mail nnrpd[26336]: unable to get certificates from '/usr/local/news/etc/cert.pem'

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 21576 73.0%2.5 MB 68.6%
inn 6229 21.1%880.8 KB 24.0%
innd 1432 4.8%239.5 KB 6.5%
nnrpd 304 1.0%31.6 KB 0.9%
controlchan 14 0.0%0.7 KB 0.0%
TOTAL: 5 29555 100.0%3.6 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6829 35.8%
Do not exist 6163 32.3%
Positive hits 6100 32.0%
Cache misses 0 0.0%
TOTAL: 4 19092 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.086 0.0%6177 0.000 0.014 0.121
article logging00:00:00.308 0.0%6177 0.000 0.050 0.161
article parse00:00:00.143 0.0%8347 0.000 0.017 0.100
article write00:00:01.468 0.0%1537 0.000 0.955 4.150
data move00:00:00.111 0.0%20881 0.000 0.005 0.069
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.615 0.0%19139 0.174 0.293 0.921
history sync00:00:00.086 0.0%406 0.000 0.212 2.667
history write00:00:04.539 0.0%6177 0.300 0.735 2.100
idle23:40:26.026100.0%21013 1256.153 4055.871 18191.030
nntp read00:00:00.703 0.0%20626 0.000 0.034 0.145
overview write00:00:01.294 0.0%1537 0.167 0.842 4.167
perl filter00:00:16.062 0.0%6177 1.494 2.600 13.375
python filter00:00:01.203 0.0%6181 0.046 0.195 0.842
site send00:00:00.067 0.0%1537 0.000 0.044 0.400
TOTAL: 1723:40:57.711100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.033 0.0%4357 0.000 0.008 0.074
article prepare00:00:00.001 0.0%6128 0.000 0.000 0.013
article read00:00:00.507 0.0%1528 0.000 0.332 2.000
backlog stats00:00:00.038 0.0%30295 0.000 0.001 0.010
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.200 0.0%12218 0.000 0.016 0.056
data write00:00:00.253 0.0%10729 0.000 0.024 0.069
idle23:36:44.479100.0%30295 1707.139 2805.891 3402.136
status file00:00:04.102 0.0%8577 0.267 0.478 1.667
TOTAL: 923:36:49.613100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:45:15.520100.0%150 1.333 18103.467300509.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.272 0.0%34039 0.000 0.008 0.429
readart00:00:00.004 0.0%8 0.333 0.500 1.000
TOTAL: 400:45:15.796100.0%----

Control commands to innd:

CommandNumber
flush 3
flushlogs 2
go 2
logmode 2
lowmark 1
mode 148
name 2
pause 2
paused 2
reload 5
reserve 2
xexec 5
TOTAL: 12 176

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 14 12597 1603 6261 4733 12%48:58:21
2localhost 36 5 5 0 0 100%00:00:01
3news.twilightnode.org 12 0 0 0 0 0%00:00:00
TOTAL: 3 62 12602 1608 6261 4733 12%48:58:22
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net4.8 MB0.0 KB19.5 MB24.3 MB 19%3.9 KB
2localhost3.4 KB0.0 KB0.0 KB3.4 KB100%0.7 KB
3news.twilightnode.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 34.8 MB0.0 KB19.5 MB24.3 MB 19%3.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 16 03:00:07 - 03:59:59 35 2.3% 0.0195.2 KB 2.0% 0.03
May 16 04:00:00 - 04:59:59 51 3.3% 0.01126.7 KB 2.7% 0.04
May 16 05:00:00 - 05:59:59 56 3.6% 0.02118.1 KB 2.5% 0.03
May 16 06:00:00 - 06:59:59 78 5.0% 0.02305.6 KB 6.5% 0.08
May 16 07:00:00 - 07:59:59 52 3.3% 0.01227.1 KB 4.8% 0.06
May 16 08:00:00 - 08:59:59 53 3.4% 0.01107.9 KB 2.3% 0.03
May 16 09:00:00 - 09:59:59 48 3.1% 0.01203.7 KB 4.3% 0.06
May 16 10:00:00 - 10:59:59 55 3.5% 0.02113.2 KB 2.4% 0.03
May 16 11:00:00 - 11:59:59 79 5.1% 0.02224.0 KB 4.8% 0.06
May 16 12:00:00 - 12:59:59 53 3.4% 0.01250.3 KB 5.3% 0.07
May 16 13:00:00 - 13:59:59 52 3.3% 0.01102.3 KB 2.2% 0.03
May 16 14:00:00 - 14:59:59 76 4.9% 0.02225.2 KB 4.8% 0.06
May 16 15:00:00 - 15:59:59 70 4.5% 0.02207.3 KB 4.4% 0.06
May 16 16:00:00 - 16:59:59 78 5.0% 0.02283.0 KB 6.0% 0.08
May 16 17:00:00 - 17:59:59 70 4.5% 0.02168.9 KB 3.6% 0.05
May 16 18:00:00 - 18:59:59 87 5.6% 0.02155.4 KB 3.3% 0.04
May 16 19:00:00 - 19:59:59 88 5.7% 0.02220.8 KB 4.7% 0.06
May 16 20:00:00 - 20:59:59 112 7.2% 0.03364.4 KB 7.8% 0.10
May 16 21:00:00 - 21:59:59 64 4.1% 0.02202.6 KB 4.3% 0.06
May 16 22:00:00 - 22:59:59 68 4.4% 0.02173.1 KB 3.7% 0.05
May 16 23:00:00 - 23:59:59 60 3.9% 0.02243.7 KB 5.2% 0.07
May 17 00:00:00 - 00:59:59 50 3.2% 0.01312.3 KB 6.7% 0.09
May 17 01:00:00 - 01:59:59 58 3.7% 0.02112.4 KB 2.4% 0.03
May 17 02:00:00 - 02:59:59 50 3.2% 0.01135.6 KB 2.9% 0.04
May 17 03:00:00 - 03:00:07 10 0.6% 1.439.4 KB 0.2% 1.34
TOTAL: 24:00:00 1553 100.0% 0.024.6 MB 100.0% 0.05
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 4676 4400 0 0 0 0 0 0 276
TOTAL: 1 4676 4400 0 0 0 0 0 0 276

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1077
linux.debian.changes.devel 187
free.pt 185
linux.debian.devel.release 184
linux.debian.bugs.dist 131
alt.buddha.short.fat.guy 128
pl.soc.prawo 125
fr.soc.politique 101
de.soc.politik.misc 99
aus.cars 92
fr.sci.maths 84
linux.debian.bugs.rc 80
alt.arts.poetry.comments 78
alt.usage.english 63
it.politica 62
ger.ct 61
de.talk.tagesgeschehen 59
alt.fan.rush-limbaugh 58
fido7.ru.military 51
linux.debian.user 47
TOTAL: 268 4400

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phl) 213
EMP (md5) 16
Too many newsgroups (meow) 15
User-issued cancel 11
Too many newsgroups 9
EMP (phn path) 8
EMP (phr path) 2
HTML Multipart 2
TOTAL: 8 276

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1thebunker.nu 1554 1458 0 95 0 0 93%23:59:35
2chimia.se 2878 0 0 0 0 4431 0%23:59:35
3weretis.net 5 0 0 5 0 0 0%00:52:47
4sklaff.dev 0 0 0 0 0 1546 0%23:46:36
5usenet.blueworldhosting.com 0 0 0 0 0 1402 0%23:59:30
TOTAL: 5 4437 1458 0 100 0 7379 32%96:38:03
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1thebunker.nu4.2 MB353.8 KB4.6 MB0.1 KB/s3.0 KB23:59:35
2chimia.se0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:35
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:46:36
4usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:30
5weretis.net0.0 KB3.4 KB3.4 KB0.0 KB/s0.7 KB00:52:47
TOTAL: 54.2 MB357.2 KB4.6 MB0.0 KB/s3.0 KB96:38:03
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1localhost 6 86.1 KB 6 3 000:34:45
2mail.twilightnode.org 12 00.0 KB 0 2 000:00:00
TOTAL: 2 18 86.1 KB 6 5 000:34:45

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

SystemConnArtsSizeGroupsPostRejElapsed
1*localhost 6 86.1 KB 6 3 000:34:45
2*.twilightnode.org 12 00.0 KB 0 2 000:00:00
3*.academyforinternetresearch.org 11 00.0 KB 0 0 000:00:09
4*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:03
5*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
6*.stretchoid.com 2 00.0 KB 0 0 000:00:11
7*.tbcn.telia.com 1 00.0 KB 0 0 000:10:01
TOTAL: 7 38 86.1 KB 6 5 000:45:15

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
localhost 0.119 0.404 0.00000:34:45
195-252-55-9-no600.tbcn.telia.com 0.013 0.015 0.00000:10:01
azpdsg60p5wz.stretchoid.com 0.013 0.020 0.00000:00:11
prod47client01.academyforinternetresearch.org 0.154 0.124 0.00000:00:09
59.195.132.66.censys-scanner.com 0.050 0.023 0.00000:00:04
49.146.94.167.censys-scanner.com 0.035 0.046 0.00000:00:03
mail.twilightnode.org 0.060 0.106 0.00000:00:00
TOTAL: 7 0.444 0.738 0.00000:45:15

Curious NNRP explorers [Top 20]:

SystemConn
prod47client01.academyforinternetresearch.org 11
49.146.94.167.censys-scanner.com 3
59.195.132.66.censys-scanner.com 3
azpdsg60p5wz.stretchoid.com 2
195-252-55-9-no600.tbcn.telia.com 1
TOTAL: 5 20

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

SystemConn
prod47client01.academyforinternetresearch.org 7
49.146.94.167.censys-scanner.com 1
59.195.132.66.censys-scanner.com 1
azpdsg60p5wz.stretchoid.com 1
TOTAL: 4 10

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

CommandCount
#026#003#001 2
#026#003#001#002 2
?#024 2
EHLO www.censys.io 2
I?#011?x^?h?2#022#014 G?q?exZ?R#021?#030??##037{qI... 1
MGLNDD_172.234.115.63_119 1
TOTAL: 6 10

NNRP client timeouts [Top 20]:

SystemConnPeer
prod47client01.academyforinternetresearch.org 2 2
49.146.94.167.censys-scanner.com 1 2
59.195.132.66.censys-scanner.com 1 2
localhost 1 0
TOTAL: 4 5 6

Newsgroup request counts (by hierarchy) [Top 100]:

HierarchyCountPct
1swnet 5 62.5%
2misc 2 25.0%
3alt 1 12.5%
TOTAL: 3 8100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1swnet.sklaff.test 5
2alt.test 1
3misc.test 1
4misc.test.moderated 1
TOTAL: 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