Daily Usenet report

Jun 2 03:00:05 -- Jun 3 03:00:05

Unknown entries from news log file:

First 50 / 146 lines (34.2%)

Jun 2 03:00:35 twilightnode nnrpd[1081934]: Reading access from /usr/local/news/etc/readers.conf
Jun 2 03:00:35 twilightnode nnrpd[1081934]: Auth strategy 'localhost' does not match client. Removing.
Jun 2 03:00:35 twilightnode nnrpd[1081934]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
Jun 2 03:00:35 twilightnode nnrpd[1081934]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
Jun 2 03:00:35 twilightnode nnrpd[1081934]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 2 03:00:35 twilightnode nnrpd[1081934]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
Jun 2 03:00:35 twilightnode nnrpd[1081934]: 195-252-55-9-no600.tbcn.telia.com no_post anonymous-read
Jun 2 03:01:22 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 2 04:49:22 twilightnode nnrpd[1086376]: Reading access from /usr/local/news/etc/readers.conf
Jun 2 04:49:22 twilightnode nnrpd[1086376]: Auth strategy 'localhost' does not match client. Removing.
Jun 2 04:49:22 twilightnode nnrpd[1086376]: 69.5.169.158 res <anonymous>
Jun 2 04:49:22 twilightnode nnrpd[1086376]: 69.5.169.158 no_match_user <anonymous> <localhost>
Jun 2 04:49:22 twilightnode nnrpd[1086376]: 69.5.169.158 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 2 04:49:22 twilightnode nnrpd[1086376]: 69.5.169.158 match_user <anonymous> <anonymous>
Jun 2 04:49:22 twilightnode nnrpd[1086376]: 69.5.169.158 no_post anonymous-read
Jun 2 05:18:46 twilightnode nnrpd[1087588]: Reading access from /usr/local/news/etc/readers.conf
Jun 2 05:18:46 twilightnode nnrpd[1087588]: Auth strategy 'localhost' does not match client. Removing.
Jun 2 05:18:46 twilightnode nnrpd[1087588]: 69.5.169.93 res <anonymous>
Jun 2 05:18:46 twilightnode nnrpd[1087588]: 69.5.169.93 no_match_user <anonymous> <localhost>
Jun 2 05:18:46 twilightnode nnrpd[1087588]: 69.5.169.93 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 2 05:18:46 twilightnode nnrpd[1087588]: 69.5.169.93 match_user <anonymous> <anonymous>
Jun 2 05:18:46 twilightnode nnrpd[1087588]: 69.5.169.93 no_post anonymous-read
Jun 2 08:36:20 twilightnode nnrpd[1096096]: Reading access from /usr/local/news/etc/readers.conf
Jun 2 08:36:20 twilightnode nnrpd[1096096]: Auth strategy 'localhost' does not match client. Removing.
Jun 2 08:36:20 twilightnode nnrpd[1096096]: 69.5.169.34 res <anonymous>
Jun 2 08:36:20 twilightnode nnrpd[1096096]: 69.5.169.34 no_match_user <anonymous> <localhost>
Jun 2 08:36:20 twilightnode nnrpd[1096096]: 69.5.169.34 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 2 08:36:20 twilightnode nnrpd[1096096]: 69.5.169.34 match_user <anonymous> <anonymous>
Jun 2 08:36:20 twilightnode nnrpd[1096096]: 69.5.169.34 no_post anonymous-read
Jun 2 08:43:25 twilightnode nnrpd[1096477]: Reading access from /usr/local/news/etc/readers.conf
Jun 2 08:43:25 twilightnode nnrpd[1096477]: Auth strategy 'localhost' does not match client. Removing.
Jun 2 08:43:25 twilightnode nnrpd[1096477]: azpdcsf9dzpw.stretchoid.com res <anonymous>
Jun 2 08:43:25 twilightnode nnrpd[1096477]: azpdcsf9dzpw.stretchoid.com no_match_user <anonymous> <localhost>
Jun 2 08:43:25 twilightnode nnrpd[1096477]: azpdcsf9dzpw.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 2 08:43:25 twilightnode nnrpd[1096477]: azpdcsf9dzpw.stretchoid.com match_user <anonymous> <anonymous>
Jun 2 08:43:25 twilightnode nnrpd[1096477]: azpdcsf9dzpw.stretchoid.com no_post anonymous-read
Jun 2 08:43:36 twilightnode nnrpd[1096479]: Reading access from /usr/local/news/etc/readers.conf
Jun 2 08:43:36 twilightnode nnrpd[1096479]: Auth strategy 'localhost' does not match client. Removing.
Jun 2 08:43:36 twilightnode nnrpd[1096479]: azpdcsf9dzpw.stretchoid.com res <anonymous>
Jun 2 08:43:36 twilightnode nnrpd[1096479]: azpdcsf9dzpw.stretchoid.com no_match_user <anonymous> <localhost>
Jun 2 08:43:36 twilightnode nnrpd[1096479]: azpdcsf9dzpw.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 2 08:43:36 twilightnode nnrpd[1096479]: azpdcsf9dzpw.stretchoid.com match_user <anonymous> <anonymous>
Jun 2 08:43:36 twilightnode nnrpd[1096479]: azpdcsf9dzpw.stretchoid.com no_post anonymous-read
Jun 2 08:49:42 twilightnode nnrpd[1096765]: Reading access from /usr/local/news/etc/readers.conf
Jun 2 08:49:42 twilightnode nnrpd[1096765]: Auth strategy 'localhost' does not match client. Removing.
Jun 2 08:49:42 twilightnode nnrpd[1096765]: 69.5.169.84 res <anonymous>
Jun 2 08:49:42 twilightnode nnrpd[1096765]: 69.5.169.84 no_match_user <anonymous> <localhost>
Jun 2 08:49:42 twilightnode nnrpd[1096765]: 69.5.169.84 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 2 08:49:42 twilightnode nnrpd[1096765]: 69.5.169.84 match_user <anonymous> <anonymous>
Jun 2 08:49:42 twilightnode nnrpd[1096765]: 69.5.169.84 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 5813 70.6%881.8 KB 73.1%
innfeed 1181 14.4%163.8 KB 13.6%
innd 984 12.0%134.9 KB 11.2%
nnrpd 248 3.0%25.2 KB 2.1%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 8228 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6275 35.3%
Do not exist 5754 32.4%
Positive hits 5746 32.3%
Cache misses 2 0.0%
TOTAL: 4 17777 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.002 0.0%2 0.000 1.000 2.000
article cleanup00:00:00.064 0.0%5764 0.000 0.011 0.088
article logging00:00:00.257 0.0%5796 0.000 0.044 0.379
article parse00:00:00.065 0.0%6977 0.000 0.009 0.100
article write00:00:03.330 0.0%4136 0.417 0.805 2.958
data move00:00:00.058 0.0%18179 0.000 0.003 0.033
hisgrep/artcncl00:00:00.001 0.0%2 0.000 0.500 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.037 0.0%17815 0.094 0.227 0.592
history sync00:00:00.046 0.0%409 0.000 0.112 1.667
history write00:00:02.792 0.0%5764 0.188 0.484 1.000
idle23:50:45.174100.0%18119 1784.893 4737.854 20019.900
nntp read00:00:00.451 0.0%17757 0.000 0.025 0.075
overview write00:00:01.967 0.0%4136 0.185 0.476 1.778
perl filter00:00:10.138 0.0%5764 0.895 1.759 19.238
python filter00:00:00.661 0.0%5764 0.000 0.115 3.667
site send00:00:00.132 0.0%4136 0.000 0.032 0.179
TOTAL: 1723:51:09.175100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.283 0.0%688302 0.000 0.000 0.200
article prepare00:00:00.097 0.0%1510540 0.000 0.000 0.013
article read00:01:22.463 0.1%373547 0.000 0.221 0.692
backlog stats00:00:00.123 0.0%594141 0.000 0.000 0.021
callbacks00:00:00.002 0.0%66 0.030 0.030 0.030
data read00:00:02.015 0.0%374655 0.000 0.005 0.056
data write00:00:04.225 0.0%368539 0.000 0.011 0.109
idle23:49:09.538 99.9%594141 0.887 144.325 10211.000
prepart/cb00:00:00.000 0.0%24 0.000 0.000 0.000
readart/cb00:00:00.002 0.0%6 0.333 0.333 0.333
status file00:00:00.158 0.0%181 0.000 0.873 4.000
TOTAL: 1123:50:38.906100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:20:37.665100.0%32 38.000 38677.031300167.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%184 0.000 0.011 0.111
TOTAL: 300:20:37.667100.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 4 11694 4126 5910 1658 35%48:59:23
2localhost 25 0 0 0 0 0%00:00:00
3news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 3 30 11694 4126 5910 1658 35%49:09:23
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net10.7 MB0.0 KB5.5 MB16.2 MB 65%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: 310.7 MB0.0 KB5.5 MB16.2 MB 65%2.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 2 03:00:05 - 03:59:59 81 2.0% 0.02219.1 KB 2.0% 0.06
Jun 2 04:00:00 - 04:59:59 93 2.2% 0.03251.8 KB 2.3% 0.07
Jun 2 05:00:00 - 05:59:59 69 1.7% 0.02191.3 KB 1.7% 0.05
Jun 2 06:00:00 - 06:59:59 66 1.6% 0.02144.5 KB 1.3% 0.04
Jun 2 07:00:00 - 07:59:59 109 2.6% 0.03324.4 KB 2.9% 0.09
Jun 2 08:00:00 - 08:59:59 175 4.2% 0.05495.6 KB 4.5% 0.14
Jun 2 09:00:00 - 09:59:59 201 4.8% 0.06521.1 KB 4.7% 0.14
Jun 2 10:00:00 - 10:59:59 209 5.0% 0.06512.9 KB 4.6% 0.14
Jun 2 11:00:00 - 11:59:59 222 5.3% 0.06547.2 KB 4.9% 0.15
Jun 2 12:00:00 - 12:59:59 171 4.1% 0.05422.1 KB 3.8% 0.12
Jun 2 13:00:00 - 13:59:59 174 4.2% 0.05421.4 KB 3.8% 0.12
Jun 2 14:00:00 - 14:59:59 178 4.3% 0.05391.3 KB 3.5% 0.11
Jun 2 15:00:00 - 15:59:59 226 5.4% 0.06517.8 KB 4.7% 0.14
Jun 2 16:00:00 - 16:59:59 222 5.3% 0.06701.1 KB 6.3% 0.19
Jun 2 17:00:00 - 17:59:59 243 5.9% 0.07585.7 KB 5.3% 0.16
Jun 2 18:00:00 - 18:59:59 218 5.3% 0.06567.3 KB 5.1% 0.16
Jun 2 19:00:00 - 19:59:59 271 6.5% 0.08726.2 KB 6.6% 0.20
Jun 2 20:00:00 - 20:59:59 199 4.8% 0.06586.8 KB 5.3% 0.16
Jun 2 21:00:00 - 21:59:59 228 5.5% 0.06668.4 KB 6.0% 0.19
Jun 2 22:00:00 - 22:59:59 230 5.5% 0.06625.1 KB 5.6% 0.17
Jun 2 23:00:00 - 23:59:59 214 5.2% 0.06676.8 KB 6.1% 0.19
Jun 3 00:00:00 - 00:59:59 142 3.4% 0.04354.6 KB 3.2% 0.10
Jun 3 01:00:00 - 01:59:59 96 2.3% 0.03297.0 KB 2.7% 0.08
Jun 3 02:00:00 - 02:59:59 104 2.5% 0.03319.0 KB 2.9% 0.09
Jun 3 03:00:00 - 03:00:05 10 0.2% 2.0011.9 KB 0.1% 2.38
TOTAL: 24:00:00 4151 100.0% 0.0510.8 MB 100.0% 0.13
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1662 1243 0 0 0 0 0 0 419
TOTAL: 1 1662 1243 0 0 0 0 0 0 419

Unwanted newsgroups [Top 20]:

NewsgroupCount
nl.politiek 61
de.alt.fan.aldi 60
alt.slack 45
cn.comp 40
fido7.ru.military 35
eternal-september.test 31
muc.lists.freebsd.bugs 26
de.sci.mathematik 25
pl.misc.samochody 25
uk.d-i-y 25
pl.soc.prawo 24
de.talk.jokes 19
it.tlc.cellulari.android 19
pl.soc.polityka 19
linux.gentoo.dev 18
alt.food.fast-food 17
aus.politics 17
de.alt.folklore.ddr 17
de.comm.software.newsreader 17
de.comp.sys.mac.misc 17
TOTAL: 205 1243

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 344
User-issued cancel 20
Too many newsgroups 13
EMP (md5) 6
HTML Multipart 3
TOTAL: 5 386

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaff.dev 685933 305060 314761 64105 402 3990 44%23:56:47
2chimia.se 4152 4144 0 7 0 0 99%23:59:55
3thebunker.nu 4158 3792 0 359 0 5 91%23:59:29
4usenet.blueworldhosting.com 0 0 0 0 0 3733 0%23:59:55
5weretis.net 0 0 0 0 0 0 0%00:10:30
TOTAL: 5 694243 312996 314761 64471 402 7728 45%96:06:36
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1sklaff.dev1.0 GB153.5 MB1.2 GB14.4 KB/s3.4 KB23:56:47
2chimia.se10.8 MB8.5 KB10.8 MB0.1 KB/s2.7 KB23:59:55
3thebunker.nu9.5 MB1.3 MB10.8 MB0.1 KB/s2.7 KB23:59:29
4usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
5weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:30
TOTAL: 51.1 GB154.8 MB1.2 GB3.7 KB/s3.4 KB96:06:36
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1222.172.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:06
2195-252-55-9-no600.tbcn.telia.com 2 00.0 KB 0 0 000:10:00
393.152.208.38 2 00.0 KB 0 0 000:00:00
4azpdcsf9dzpw.stretchoid.com 2 00.0 KB 0 0 000:00:10
5171.105.76.114 1 00.0 KB 0 0 000:00:00
6172.234.120.199 1 00.0 KB 0 0 000:10:00
7198.235.24.218 1 00.0 KB 0 0 000:00:00
847.245.176.214 1 00.0 KB 0 0 000:00:03
969.5.169.158 1 00.0 KB 0 0 000:00:00
1069.5.169.34 1 00.0 KB 0 0 000:00:00
1169.5.169.84 1 00.0 KB 0 0 000:00:00
1269.5.169.93 1 00.0 KB 0 0 000:00:00
1378.128.113.46 1 00.0 KB 0 0 000:00:10
14bradford.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
15mccarthy.probe.onyphe.net 1 00.0 KB 0 0 000:00:03
TOTAL: 15 20 00.0 KB 0 0 000:20:37

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 11 00.0 KB 0 0 000:10:15
2*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:06
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
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
TOTAL: 5 20 00.0 KB 0 0 000:20:37

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.002 0.033 0.00000:10:00
172.234.120.199 0.006 0.010 0.00000:10:00
azpdcsf9dzpw.stretchoid.com 0.009 0.012 0.00000:00:10
78.128.113.46 0.011 0.006 0.00000:00:10
222.172.132.66.censys-scanner.com 0.034 0.028 0.00000:00:06
47.245.176.214 0.002 0.012 0.00000:00:03
mccarthy.probe.onyphe.net 0.001 0.008 0.00000:00:03
bradford.probe.onyphe.net 0.004 0.015 0.00000:00:00
171.105.76.114 0.003 0.014 0.00000:00:00
69.5.169.93 0.005 0.015 0.00000:00:00
198.235.24.218 0.006 0.012 0.00000:00:00
69.5.169.84 0.003 0.009 0.00000:00:00
93.152.208.38 0.008 0.016 0.00000:00:00
69.5.169.34 0.001 0.017 0.00000:00:00
69.5.169.158 0.006 0.011 0.00000:00:00
TOTAL: 15 0.101 0.218 0.00000:20:37

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

SystemConn
93.152.208.38 9
bradford.probe.onyphe.net 2
222.172.132.66.censys-scanner.com 1
azpdcsf9dzpw.stretchoid.com 1
TOTAL: 4 13

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

CommandCount
#026#003#001#002 1
#026#003#003#002c#001 1
?#024?#011?#023 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 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: 13 13

NNRP client timeouts [Top 20]:

SystemConnPeer
222.172.132.66.censys-scanner.com 1 2
47.245.176.214 1 1
69.5.169.84 1 1
69.5.169.93 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