Daily Usenet report

Aug 2 03:00:04 -- Aug 3 03:00:04

Unknown entries from news log file:

First 50 / 134 lines (37.3%)

Aug 2 03:01:26 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 2 05:18:37 twilightnode nnrpd[485212]: Reading access from /usr/local/news/etc/readers.conf
Aug 2 05:18:37 twilightnode nnrpd[485212]: Auth strategy 'localhost' does not match client. Removing.
Aug 2 05:18:37 twilightnode nnrpd[485212]: ayana.probe.onyphe.net res <anonymous>
Aug 2 05:18:37 twilightnode nnrpd[485212]: ayana.probe.onyphe.net no_match_user <anonymous> <localhost>
Aug 2 05:18:37 twilightnode nnrpd[485212]: ayana.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 2 05:18:37 twilightnode nnrpd[485212]: ayana.probe.onyphe.net match_user <anonymous> <anonymous>
Aug 2 05:18:37 twilightnode nnrpd[485212]: ayana.probe.onyphe.net no_post anonymous-read
Aug 2 05:18:38 twilightnode nnrpd[485213]: Reading access from /usr/local/news/etc/readers.conf
Aug 2 05:18:38 twilightnode nnrpd[485213]: Auth strategy 'localhost' does not match client. Removing.
Aug 2 05:18:38 twilightnode nnrpd[485213]: shelby.probe.onyphe.net res <anonymous>
Aug 2 05:18:38 twilightnode nnrpd[485213]: shelby.probe.onyphe.net no_match_user <anonymous> <localhost>
Aug 2 05:18:38 twilightnode nnrpd[485213]: shelby.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 2 05:18:38 twilightnode nnrpd[485213]: shelby.probe.onyphe.net match_user <anonymous> <anonymous>
Aug 2 05:18:38 twilightnode nnrpd[485213]: shelby.probe.onyphe.net no_post anonymous-read
Aug 2 05:32:12 twilightnode nnrpd[485731]: Reading access from /usr/local/news/etc/readers.conf
Aug 2 05:32:12 twilightnode nnrpd[485731]: Auth strategy 'localhost' does not match client. Removing.
Aug 2 05:32:12 twilightnode nnrpd[485731]: 217.146.80.98 res <anonymous>
Aug 2 05:32:12 twilightnode nnrpd[485731]: 217.146.80.98 no_match_user <anonymous> <localhost>
Aug 2 05:32:12 twilightnode nnrpd[485731]: 217.146.80.98 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 2 05:32:12 twilightnode nnrpd[485731]: 217.146.80.98 match_user <anonymous> <anonymous>
Aug 2 05:32:12 twilightnode nnrpd[485731]: 217.146.80.98 no_post anonymous-read
Aug 2 05:32:20 twilightnode nnrpd[485734]: Reading access from /usr/local/news/etc/readers.conf
Aug 2 05:32:20 twilightnode nnrpd[485734]: Auth strategy 'localhost' does not match client. Removing.
Aug 2 05:32:20 twilightnode nnrpd[485734]: 213.166.84.52 res <anonymous>
Aug 2 05:32:20 twilightnode nnrpd[485734]: 213.166.84.52 no_match_user <anonymous> <localhost>
Aug 2 05:32:20 twilightnode nnrpd[485734]: 213.166.84.52 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 2 05:32:20 twilightnode nnrpd[485734]: 213.166.84.52 match_user <anonymous> <anonymous>
Aug 2 05:32:20 twilightnode nnrpd[485734]: 213.166.84.52 no_post anonymous-read
Aug 2 07:13:47 twilightnode nnrpd[490529]: Reading access from /usr/local/news/etc/readers.conf
Aug 2 07:13:47 twilightnode nnrpd[490529]: Auth strategy 'localhost' does not match client. Removing.
Aug 2 07:13:47 twilightnode nnrpd[490529]: 56.195.132.66.censys-scanner.com res <anonymous>
Aug 2 07:13:47 twilightnode nnrpd[490529]: 56.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 2 07:13:47 twilightnode nnrpd[490529]: 56.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 2 07:13:47 twilightnode nnrpd[490529]: 56.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 2 07:13:47 twilightnode nnrpd[490529]: 56.195.132.66.censys-scanner.com no_post anonymous-read
Aug 2 07:13:50 twilightnode nnrpd[490530]: Reading access from /usr/local/news/etc/readers.conf
Aug 2 07:13:50 twilightnode nnrpd[490530]: Auth strategy 'localhost' does not match client. Removing.
Aug 2 07:13:50 twilightnode nnrpd[490530]: 56.195.132.66.censys-scanner.com res <anonymous>
Aug 2 07:13:50 twilightnode nnrpd[490530]: 56.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 2 07:13:50 twilightnode nnrpd[490530]: 56.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 2 07:13:50 twilightnode nnrpd[490530]: 56.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 2 07:13:50 twilightnode nnrpd[490530]: 56.195.132.66.censys-scanner.com no_post anonymous-read
Aug 2 07:13:54 twilightnode nnrpd[490531]: Reading access from /usr/local/news/etc/readers.conf
Aug 2 07:13:54 twilightnode nnrpd[490531]: Auth strategy 'localhost' does not match client. Removing.
Aug 2 07:13:54 twilightnode nnrpd[490531]: 56.195.132.66.censys-scanner.com res <anonymous>
Aug 2 07:13:54 twilightnode nnrpd[490531]: 56.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 2 07:13:54 twilightnode nnrpd[490531]: 56.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 2 07:13:54 twilightnode nnrpd[490531]: 56.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 2 07:13:54 twilightnode nnrpd[490531]: 56.195.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6821 69.8%1020.0 KB 70.6%
innd 1464 15.0%211.2 KB 14.6%
innfeed 1256 12.9%187.6 KB 13.0%
nnrpd 215 2.2%22.4 KB 1.6%
controlchan 14 0.1%3.0 KB 0.2%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 6 9771 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15253 48.7%
Negative hits 9252 29.6%
Do not exist 6787 21.7%
Cache misses 0 0.0%
TOTAL: 4 31292 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.010 0.0%5 0.000 2.000 7.000
article cleanup00:00:00.047 0.0%6815 0.000 0.007 0.118
article logging00:00:00.166 0.0%6832 0.000 0.024 0.235
article parse00:00:00.100 0.0%9050 0.000 0.011 0.087
article write00:00:04.092 0.0%4856 0.463 0.843 1.973
data move00:00:00.111 0.0%33580 0.000 0.003 0.031
hisgrep/artcncl00:00:00.007 0.0%5 0.000 1.400 5.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.428 0.0%31435 0.066 0.141 0.542
history sync00:00:00.040 0.0%310 0.000 0.129 1.000
history write00:00:03.312 0.0%6815 0.100 0.486 1.200
idle24:00:03.448100.0%33270 1226.751 2597.038 9526.063
nntp read00:00:00.607 0.0%33001 0.000 0.018 0.083
overview write00:00:02.221 0.0%4856 0.065 0.457 3.000
perl filter00:00:08.254 0.0%6815 0.677 1.211 21.941
python filter00:00:00.530 0.0%6816 0.000 0.078 1.471
site send00:00:00.099 0.0%4831 0.000 0.020 0.286
TOTAL: 1724:00:27.472100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.010 0.0%4964 0.000 0.002 0.125
article prepare00:00:00.000 0.0%16600 0.000 0.000 0.000
article read00:00:00.500 0.0%3069 0.000 0.163 1.200
backlog stats00:00:00.020 0.0%54177 0.000 0.000 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.330 0.0%29217 0.000 0.011 0.040
data write00:00:00.544 0.0%24268 0.000 0.022 0.056
idle23:50:40.750100.0%54177 403.588 1584.450 7237.157
status file00:00:00.136 0.0%237 0.000 0.574 6.000
TOTAL: 923:50:42.290100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:23.996100.0%29 14.250 827.448 10282.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.003 0.0%165 0.000 0.018 0.500
TOTAL: 300:00:23.999100.0%----

Control commands to innd:

CommandNumber
flush 2
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 166

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de001101
TOTAL001101

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 11334 4428 5681 1225 39%46:10:09
2newsfeed.endofthelinebbs.com 1 5090 267 4219 604 5%22:51:42
3news.tebibyte.org 2 5275 107 5134 34 2%23:59:51
4news.sklaffkom.se 33 38 29 5 4 76%05:54:33
5localhost 26 1 1 0 0 100%00:00:00
6news.twilightnode.org 1 0 0 0 0 0%00:00:00
TOTAL: 6 67 21738 4832 15039 1867 22%98:56:15
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.5 MB0.0 KB3.6 MB18.0 MB 80%3.3 KB
2newsfeed.endofthelinebbs.com2.0 MB0.0 KB1.2 MB3.2 MB 63%3.8 KB
3news.tebibyte.org562.6 KB0.0 KB262.2 KB824.8 KB 68%5.8 KB
4news.sklaffkom.se71.4 KB0.0 KB7.7 KB79.1 KB 90%2.4 KB
5localhost0.8 KB0.0 KB0.0 KB0.8 KB100%0.8 KB
6news.twilightnode.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 617.1 MB0.0 KB5.0 MB22.1 MB 77%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 2 03:00:04 - 03:59:59 104 2.1% 0.03656.5 KB 3.7% 0.18
Aug 2 04:00:00 - 04:59:59 103 2.1% 0.03450.0 KB 2.5% 0.12
Aug 2 05:00:00 - 05:59:59 103 2.1% 0.03507.1 KB 2.9% 0.14
Aug 2 06:00:00 - 06:59:59 128 2.6% 0.04519.0 KB 2.9% 0.14
Aug 2 07:00:00 - 07:59:59 124 2.6% 0.03395.1 KB 2.2% 0.11
Aug 2 08:00:00 - 08:59:59 176 3.6% 0.05512.6 KB 2.9% 0.14
Aug 2 09:00:00 - 09:59:59 226 4.7% 0.06581.9 KB 3.3% 0.16
Aug 2 10:00:00 - 10:59:59 223 4.6% 0.06694.2 KB 3.9% 0.19
Aug 2 11:00:00 - 11:59:59 266 5.5% 0.07845.9 KB 4.8% 0.23
Aug 2 12:00:00 - 12:59:59 268 5.5% 0.07887.6 KB 5.0% 0.25
Aug 2 13:00:00 - 13:59:59 194 4.0% 0.05465.6 KB 2.6% 0.13
Aug 2 14:00:00 - 14:59:59 260 5.4% 0.07684.3 KB 3.9% 0.19
Aug 2 15:00:00 - 15:59:59 290 6.0% 0.08807.6 KB 4.6% 0.22
Aug 2 16:00:00 - 16:59:59 300 6.2% 0.08975.5 KB 5.5% 0.27
Aug 2 17:00:00 - 17:59:59 258 5.3% 0.07892.5 KB 5.0% 0.25
Aug 2 18:00:00 - 18:59:59 304 6.3% 0.081.1 MB 6.3% 0.31
Aug 2 19:00:00 - 19:59:59 233 4.8% 0.061.3 MB 7.5% 0.37
Aug 2 20:00:00 - 20:59:59 220 4.5% 0.06715.8 KB 4.0% 0.20
Aug 2 21:00:00 - 21:59:59 288 5.9% 0.08998.2 KB 5.6% 0.28
Aug 2 22:00:00 - 22:59:59 211 4.4% 0.06826.9 KB 4.7% 0.23
Aug 2 23:00:00 - 23:59:59 201 4.1% 0.061.1 MB 6.3% 0.31
Aug 3 00:00:00 - 00:59:59 143 3.0% 0.04564.2 KB 3.2% 0.16
Aug 3 01:00:00 - 01:59:59 138 2.8% 0.04703.1 KB 4.0% 0.20
Aug 3 02:00:00 - 02:59:59 72 1.5% 0.02458.6 KB 2.6% 0.13
Aug 3 03:00:00 - 03:00:04 11 0.2% 2.7530.9 KB 0.2% 7.73
TOTAL: 24:00:00 4844 100.0% 0.0617.3 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1308 1129 0 0 0 0 0 0 179
2newsfeed.endofthelinebbs.com 619 66 0 0 0 0 0 0 553
3news.tebibyte.org 46 43 0 0 0 0 0 0 3
4news.sklaffkom.se 4 4 0 0 0 0 0 0 0
TOTAL: 4 1977 1242 0 0 0 0 0 0 735

Unwanted newsgroups [Top 20]:

NewsgroupCount
pl.misc.elektronika 53
uk.legal.moderated 44
pl.soc.prawo 36
alt.checkmate 35
de.rec.garten 29
hfx.general 26
alt.usage.english 23
de.etc.bahn.bahnpolitik 23
alt.home.repair 22
de.talk.jokes 22
de.test 22
alt.slack 21
fr.rec.moto 21
pl.soc.polityka 21
cn.comp 19
fido7.ukrnews 18
linux.debian.maint.ocaml.maint 16
fr.comp.ia 15
alt.prophecies.nostradamus 14
alt.support.depression.crisis 14
TOTAL: 375 1242

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 573
EMP (phn path) 111
User-issued cancel 24
HTML Multipart 9
Too many newsgroups 1
TOTAL: 5 718

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6754 1101 3718 2 0 15 16%23:59:56
2sklaffkom.se 4990 885 1787 2149 0 0 17%23:59:56
3newsfeed.endofthelinebbs.com 8792 19 4494 12 0 137 0%23:59:55
4weretis.net 477 1 466 0 0 0 0%20:29:22
TOTAL: 4 21013 2006 10465 2163 0 152 9%92:29:09
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.2 MB12.5 KB3.2 MB0.0 KB/s3.0 KB23:59:56
2sklaffkom.se1.6 MB8.9 MB10.5 MB0.1 KB/s3.5 KB23:59:56
3newsfeed.endofthelinebbs.com49.2 KB30.4 KB79.7 KB0.0 KB/s2.6 KB23:59:55
4weretis.net0.8 KB0.0 KB0.8 KB0.0 KB/s0.8 KB20:29:22
TOTAL: 44.8 MB9.0 MB13.8 MB0.0 KB/s3.4 KB92:29:09
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1176.172.132.66.censys-scanner.com 5 00.0 KB 0 0 000:00:06
256.195.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:01
3azpdssldvqxw.stretchoid.com 2 00.0 KB 0 0 000:00:10
4205.210.31.98 1 00.0 KB 0 0 000:00:00
5213.166.84.52 1 00.0 KB 0 0 000:00:00
6217.146.80.98 1 00.0 KB 0 0 000:00:00
7ayana.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
8o061.scanner.modat.io 1 00.0 KB 0 0 000:00:00
9o310.scanner.modat.io 1 00.0 KB 0 0 000:00:00
10shelby.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
11twilightnode.org 1 00.0 KB 0 1 000:00:00
TOTAL: 11 18 00.0 KB 0 1 000:00:23

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 8 00.0 KB 0 0 000:00:07
2unresolved 3 00.0 KB 0 0 000:00:01
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
4*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
5*.stretchoid.com 2 00.0 KB 0 0 000:00:10
6*.org 1 00.0 KB 0 1 000:00:00
TOTAL: 6 18 00.0 KB 0 1 000:00:23

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdssldvqxw.stretchoid.com 0.003 0.016 0.00000:00:10
176.172.132.66.censys-scanner.com 0.041 0.031 0.00000:00:06
shelby.probe.onyphe.net 0.005 0.005 0.00000:00:02
56.195.132.66.censys-scanner.com 0.034 0.050 0.00000:00:01
205.210.31.98 0.004 0.008 0.00000:00:00
ayana.probe.onyphe.net 0.007 0.013 0.00000:00:00
o061.scanner.modat.io 0.006 0.011 0.00000:00:00
213.166.84.52 0.002 0.008 0.00000:00:00
217.146.80.98 0.003 0.012 0.00000:00:00
o310.scanner.modat.io 0.002 0.012 0.00000:00:00
twilightnode.org 0.004 0.030 0.00000:00:00
TOTAL: 11 0.111 0.196 0.00000:00:23

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

SystemConn
176.172.132.66.censys-scanner.com 2
ayana.probe.onyphe.net 2
56.195.132.66.censys-scanner.com 1
azpdssldvqxw.stretchoid.com 1
TOTAL: 4 6

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

CommandCount
EHLO www.censys.io 2
#026#003#001 1
#026#003#003#002c#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
MGLNDD_172.234.115.63_119 1
TOTAL: 5 6

NNRP client timeouts [Top 20]:

SystemConnPeer
176.172.132.66.censys-scanner.com 1 3
213.166.84.52 1 1
56.195.132.66.censys-scanner.com 1 2
TOTAL: 3 3 8