Daily Usenet report

Jun 1 03:00:05 -- Jun 2 03:00:05

Unknown entries from news log file:

First 50 / 160 lines (31.2%)

Jun 1 03:00:35 twilightnode nnrpd[1013301]: Reading access from /usr/local/news/etc/readers.conf
Jun 1 03:00:35 twilightnode nnrpd[1013301]: Auth strategy 'localhost' does not match client. Removing.
Jun 1 03:00:35 twilightnode nnrpd[1013301]: 195-252-55-9-no600.tbcn.telia.com res <anonymous>
Jun 1 03:00:35 twilightnode nnrpd[1013301]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> <localhost>
Jun 1 03:00:35 twilightnode nnrpd[1013301]: 195-252-55-9-no600.tbcn.telia.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 1 03:00:35 twilightnode nnrpd[1013301]: 195-252-55-9-no600.tbcn.telia.com match_user <anonymous> <anonymous>
Jun 1 03:00:35 twilightnode nnrpd[1013301]: 195-252-55-9-no600.tbcn.telia.com no_post anonymous-read
Jun 1 03:01:23 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 1 03:02:24 twilightnode nnrpd[1013384]: Reading access from /usr/local/news/etc/readers.conf
Jun 1 03:02:24 twilightnode nnrpd[1013384]: Auth strategy 'localhost' does not match client. Removing.
Jun 1 03:02:24 twilightnode nnrpd[1013384]: r3-217-d9.monitoring.internet-measurement.com res <anonymous>
Jun 1 03:02:24 twilightnode nnrpd[1013384]: r3-217-d9.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Jun 1 03:02:24 twilightnode nnrpd[1013384]: r3-217-d9.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 1 03:02:24 twilightnode nnrpd[1013384]: r3-217-d9.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Jun 1 03:02:24 twilightnode nnrpd[1013384]: r3-217-d9.monitoring.internet-measurement.com no_post anonymous-read
Jun 1 04:59:56 twilightnode nnrpd[1018673]: Reading access from /usr/local/news/etc/readers.conf
Jun 1 04:59:56 twilightnode nnrpd[1018673]: Auth strategy 'localhost' does not match client. Removing.
Jun 1 04:59:56 twilightnode nnrpd[1018673]: azpdcg3nzfxi.stretchoid.com res <anonymous>
Jun 1 04:59:56 twilightnode nnrpd[1018673]: azpdcg3nzfxi.stretchoid.com no_match_user <anonymous> <localhost>
Jun 1 04:59:56 twilightnode nnrpd[1018673]: azpdcg3nzfxi.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 1 04:59:56 twilightnode nnrpd[1018673]: azpdcg3nzfxi.stretchoid.com match_user <anonymous> <anonymous>
Jun 1 04:59:56 twilightnode nnrpd[1018673]: azpdcg3nzfxi.stretchoid.com no_post anonymous-read
Jun 1 05:00:06 twilightnode nnrpd[1018778]: Reading access from /usr/local/news/etc/readers.conf
Jun 1 05:00:06 twilightnode nnrpd[1018778]: Auth strategy 'localhost' does not match client. Removing.
Jun 1 05:00:06 twilightnode nnrpd[1018778]: azpdcg3nzfxi.stretchoid.com res <anonymous>
Jun 1 05:00:06 twilightnode nnrpd[1018778]: azpdcg3nzfxi.stretchoid.com no_match_user <anonymous> <localhost>
Jun 1 05:00:06 twilightnode nnrpd[1018778]: azpdcg3nzfxi.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 1 05:00:06 twilightnode nnrpd[1018778]: azpdcg3nzfxi.stretchoid.com match_user <anonymous> <anonymous>
Jun 1 05:00:06 twilightnode nnrpd[1018778]: azpdcg3nzfxi.stretchoid.com no_post anonymous-read
Jun 1 05:41:44 twilightnode nnrpd[1020475]: Reading access from /usr/local/news/etc/readers.conf
Jun 1 05:41:44 twilightnode nnrpd[1020475]: Auth strategy 'localhost' does not match client. Removing.
Jun 1 05:41:44 twilightnode nnrpd[1020475]: 69.5.169.68 res <anonymous>
Jun 1 05:41:44 twilightnode nnrpd[1020475]: 69.5.169.68 no_match_user <anonymous> <localhost>
Jun 1 05:41:44 twilightnode nnrpd[1020475]: 69.5.169.68 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 1 05:41:44 twilightnode nnrpd[1020475]: 69.5.169.68 match_user <anonymous> <anonymous>
Jun 1 05:41:44 twilightnode nnrpd[1020475]: 69.5.169.68 no_post anonymous-read
Jun 1 06:11:16 twilightnode nnrpd[1021754]: Reading access from /usr/local/news/etc/readers.conf
Jun 1 06:11:16 twilightnode nnrpd[1021754]: Auth strategy 'localhost' does not match client. Removing.
Jun 1 06:11:16 twilightnode nnrpd[1021754]: 205.210.31.141 res <anonymous>
Jun 1 06:11:16 twilightnode nnrpd[1021754]: 205.210.31.141 no_match_user <anonymous> <localhost>
Jun 1 06:11:16 twilightnode nnrpd[1021754]: 205.210.31.141 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 1 06:11:16 twilightnode nnrpd[1021754]: 205.210.31.141 match_user <anonymous> <anonymous>
Jun 1 06:11:16 twilightnode nnrpd[1021754]: 205.210.31.141 no_post anonymous-read
Jun 1 06:38:35 twilightnode nnrpd[1022945]: Reading access from /usr/local/news/etc/readers.conf
Jun 1 06:38:35 twilightnode nnrpd[1022945]: Auth strategy 'localhost' does not match client. Removing.
Jun 1 06:38:35 twilightnode nnrpd[1022945]: 185.216.145.164 res <anonymous>
Jun 1 06:38:35 twilightnode nnrpd[1022945]: 185.216.145.164 no_match_user <anonymous> <localhost>
Jun 1 06:38:35 twilightnode nnrpd[1022945]: 185.216.145.164 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 1 06:38:35 twilightnode nnrpd[1022945]: 185.216.145.164 match_user <anonymous> <anonymous>
Jun 1 06:38:35 twilightnode nnrpd[1022945]: 185.216.145.164 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6058 68.9%907.9 KB 72.0%
innd 1257 14.3%166.4 KB 13.2%
innfeed 1156 13.2%152.7 KB 12.1%
nnrpd 302 3.4%31.1 KB 2.5%
controlchan 14 0.2%3.5 KB 0.3%
TOTAL: 5 8787 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6589 35.4%
Do not exist 6015 32.3%
Positive hits 6003 32.3%
Cache misses 6 0.0%
TOTAL: 4 18613 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.010 0.0%3 1.000 3.333 4.500
article cleanup00:00:00.061 0.0%6024 0.000 0.010 0.143
article logging00:00:00.253 0.0%6057 0.000 0.042 0.154
article parse00:00:00.140 0.0%7584 0.000 0.018 0.188
article write00:00:03.395 0.0%4158 0.250 0.816 4.333
data move00:00:00.069 0.0%19361 0.000 0.004 0.044
hisgrep/artcncl00:00:00.002 0.0%3 0.500 0.667 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.261 0.0%18649 0.123 0.228 0.758
history sync00:00:00.048 0.0%416 0.000 0.115 1.000
history write00:00:03.280 0.0%6024 0.200 0.544 1.250
idle24:00:37.782100.0%19097 2256.609 4526.249 17155.457
nntp read00:00:00.463 0.0%18738 0.000 0.025 0.063
overview write00:00:01.869 0.0%4158 0.098 0.449 1.300
perl filter00:00:10.910 0.0%6024 0.815 1.811 16.500
python filter00:00:00.782 0.0%6024 0.000 0.130 1.300
site send00:00:00.109 0.0%4159 0.000 0.026 0.154
TOTAL: 1724:01:03.434100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.016 0.0%4156 0.000 0.004 0.077
article prepare00:00:00.004 0.0%33204 0.000 0.000 0.006
article read00:00:00.611 0.0%4154 0.000 0.147 0.684
backlog stats00:00:00.030 0.0%23459 0.000 0.001 0.028
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.199 0.0%13356 0.000 0.015 0.067
data write00:00:00.236 0.0%9252 0.000 0.026 0.101
idle23:51:32.599100.0%23459 1907.181 3661.392 8961.970
status file00:00:00.170 0.0%175 0.000 0.971 6.000
TOTAL: 923:51:33.865100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:10:24.602100.0%43 29.333 14525.628299961.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%233 0.000 0.004 0.021
TOTAL: 300:10:24.603100.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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
control@eternal-september.org001001
TOTAL001001

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 2 12648 4148 6610 1890 32%49:54:27
2localhost 25 0 0 0 0 0%00:00:00
3news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 3 28 12648 4148 6610 1890 32%50:04:27
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.3 MB0.0 KB6.2 MB18.5 MB 66%3.1 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: 312.3 MB0.0 KB6.2 MB18.5 MB 66%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 1 03:00:05 - 03:59:59 69 1.7% 0.02193.9 KB 1.5% 0.05
Jun 1 04:00:00 - 04:59:59 108 2.6% 0.03432.5 KB 3.4% 0.12
Jun 1 05:00:00 - 05:59:59 83 2.0% 0.02212.6 KB 1.7% 0.06
Jun 1 06:00:00 - 06:59:59 109 2.6% 0.03318.2 KB 2.5% 0.09
Jun 1 07:00:00 - 07:59:59 117 2.8% 0.03312.3 KB 2.5% 0.09
Jun 1 08:00:00 - 08:59:59 194 4.7% 0.05718.5 KB 5.7% 0.20
Jun 1 09:00:00 - 09:59:59 221 5.3% 0.06641.7 KB 5.1% 0.18
Jun 1 10:00:00 - 10:59:59 199 4.8% 0.06636.5 KB 5.1% 0.18
Jun 1 11:00:00 - 11:59:59 230 5.5% 0.06524.0 KB 4.2% 0.15
Jun 1 12:00:00 - 12:59:59 223 5.4% 0.06574.8 KB 4.6% 0.16
Jun 1 13:00:00 - 13:59:59 206 5.0% 0.06558.9 KB 4.4% 0.16
Jun 1 14:00:00 - 14:59:59 204 4.9% 0.06547.4 KB 4.4% 0.15
Jun 1 15:00:00 - 15:59:59 232 5.6% 0.06651.5 KB 5.2% 0.18
Jun 1 16:00:00 - 16:59:59 221 5.3% 0.06655.2 KB 5.2% 0.18
Jun 1 17:00:00 - 17:59:59 194 4.7% 0.05711.6 KB 5.7% 0.20
Jun 1 18:00:00 - 18:59:59 195 4.7% 0.05668.8 KB 5.3% 0.19
Jun 1 19:00:00 - 19:59:59 191 4.6% 0.05524.5 KB 4.2% 0.15
Jun 1 20:00:00 - 20:59:59 209 5.0% 0.06547.5 KB 4.4% 0.15
Jun 1 21:00:00 - 21:59:59 240 5.8% 0.07678.9 KB 5.4% 0.19
Jun 1 22:00:00 - 22:59:59 245 5.9% 0.071.1 MB 8.9% 0.31
Jun 1 23:00:00 - 23:59:59 180 4.3% 0.05545.8 KB 4.3% 0.15
Jun 2 00:00:00 - 00:59:59 110 2.6% 0.03330.3 KB 2.6% 0.09
Jun 2 01:00:00 - 01:59:59 94 2.3% 0.03279.9 KB 2.2% 0.08
Jun 2 02:00:00 - 02:59:59 75 1.8% 0.02174.6 KB 1.4% 0.05
Jun 2 03:00:00 - 03:00:05 11 0.3% 2.2016.5 KB 0.1% 3.29
TOTAL: 24:00:00 4160 100.0% 0.0512.3 MB 100.0% 0.15
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1898 1200 0 0 0 0 0 0 698
TOTAL: 1 1898 1200 0 0 0 0 0 0 698

Unwanted newsgroups [Top 20]:

NewsgroupCount
fido7.ru.news 80
uk.d-i-y 52
it.sport.calcio.milan 51
nl.politiek 44
eternal-september.test 36
fido7.ru.military 36
fido7.su.tormoz 32
alt.usage.english 29
linux.debian.user 25
de.sci.mathematik 23
aus.politics 19
cn.comp 19
alt.slack 18
de.alt.folklore.ddr 16
de.alt.fan.aldi 15
alt.bestjobsusa.computer.jobs 14
lada.talk 14
linux.debian.user.french 14
pl.soc.polityka 14
it.sport.calcio.inter-fc 13
TOTAL: 251 1200

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 327
EMP (phl) 202
User-issued cancel 117
Too many newsgroups 8
EMP (md5) 5
HTML Multipart 5
Too many newsgroups (meow) 1
TOTAL: 7 665

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
1chimia.se 4170 4140 0 29 0 0 99%23:59:55
2thebunker.nu 4160 3874 0 283 0 5 93%23:59:55
3sklaff.dev 0 0 0 0 0 4151 0%23:56:23
4usenet.blueworldhosting.com 0 0 0 0 0 3779 0%23:59:55
5weretis.net 0 0 0 0 0 0 0%00:10:30
TOTAL: 5 8330 8014 0 312 0 7935 96%96:06:38
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se12.3 MB37.0 KB12.3 MB0.1 KB/s3.0 KB23:59:55
2thebunker.nu11.1 MB1.1 MB12.3 MB0.1 KB/s3.0 KB23:59:55
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:23
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: 523.4 MB1.2 MB24.6 MB0.1 KB/s3.0 KB96:06:38
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
145.82.78.105 4 00.0 KB 0 0 000:00:00
258.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3195-252-55-9-no600.tbcn.telia.com 2 00.0 KB 0 0 000:10:00
4azpdcg3nzfxi.stretchoid.com 2 00.0 KB 0 0 000:00:10
5luxembourg.scan.bufferover.run 2 00.0 KB 0 0 000:00:00
6185.216.145.164 1 00.0 KB 0 0 000:00:00
7194.88.98.85 1 00.0 KB 0 0 000:00:00
8205.210.31.141 1 00.0 KB 0 0 000:00:00
969.5.169.12 1 00.0 KB 0 0 000:00:00
1069.5.169.20 1 00.0 KB 0 0 000:00:00
1169.5.169.68 1 00.0 KB 0 0 000:00:00
1269.5.169.81 1 00.0 KB 0 0 000:00:00
1372-14-178-148.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:03
14r3-217-d9.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 14 22 00.0 KB 0 0 000:10:24

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 11 00.0 KB 0 0 000:00:03
2*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3*.scan.bufferover.run 2 00.0 KB 0 0 000:00:00
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:03
7*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 7 22 00.0 KB 0 0 000:10:24

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.009 0.032 0.00000:10:00
azpdcg3nzfxi.stretchoid.com 0.004 0.027 0.00000:00:10
58.146.94.167.censys-scanner.com 0.065 0.033 0.00000:00:04
72-14-178-148.ip.linodeusercontent.com 0.007 0.006 0.00000:00:03
r3-217-d9.monitoring.internet-measurement.com 0.005 0.010 0.00000:00:01
205.210.31.141 0.005 0.012 0.00000:00:00
45.82.78.105 0.011 0.034 0.00000:00:00
luxembourg.scan.bufferover.run 0.005 0.022 0.00000:00:00
194.88.98.85 0.006 0.006 0.00000:00:00
69.5.169.20 0.004 0.012 0.00000:00:00
69.5.169.81 0.006 0.011 0.00000:00:00
69.5.169.12 0.004 0.011 0.00000:00:00
69.5.169.68 0.010 0.012 0.00000:00:00
185.216.145.164 0.004 0.015 0.00000:00:00
TOTAL: 14 0.145 0.243 0.00000:10:24

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

SystemConn
45.82.78.105 18
luxembourg.scan.bufferover.run 7
69.5.169.81 6
72-14-178-148.ip.linodeusercontent.com 5
194.88.98.85 4
58.146.94.167.censys-scanner.com 1
azpdcg3nzfxi.stretchoid.com 1
TOTAL: 7 42

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

CommandCount
Accept-Encoding: gzip 3
Accept: */* 3
GET / HTTP/1.1 3
#021? 2
#026#003#001#005?#001 2
?#024#023#001#023#002#023#003#001 2
Host: 172.234.115.63:119 2
#001 1
#026#003#001 1
#026#003#001#002?#001 1
#026?6?e?6?fF?`#020U{?6(!?#010?cK?z?C?(#013? 1
#037?/?#022?:i?#005f?m?sNt#005?cQ?}?v?h?h#033... 1
+e?!y_s?CTj 1
?#011?#023?#024 1
?#026 &f?y?#001"2?7?_?#004?M?#030#024#026H?ug?)4C?... 1
?F?#023?#022?%?T?#026?#025 S(?I?2?~x... 1
?Th/?#017?R#022?#004#032?YuO?1#007? 1
?Y?Lg> 1
?|#005s?8?6?(h'"_gx?#037?Qjf0t?9?#005??W#011\?... 1
Connection: close 1
TOTAL: 32 42

NNRP client timeouts [Top 20]:

SystemConnPeer
194.88.98.85 1 1
58.146.94.167.censys-scanner.com 1 2
69.5.169.12 1 1
69.5.169.20 1 1
69.5.169.68 1 1
69.5.169.81 1 1
72-14-178-148.ip.linodeusercontent.com 1 1
TOTAL: 7 7 10
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