Daily Usenet report

Aug 15 03:00:03 -- Aug 16 03:00:03

Unknown entries from news log file:

First 50 / 6029 lines (0.8%)

Aug 15 03:00:57 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 15 03:16:55 twilightnode nnrpd[1367309]: Reading access from /usr/local/news/etc/readers.conf
Aug 15 03:16:55 twilightnode nnrpd[1367309]: Auth strategy 'localhost' does not match client. Removing.
Aug 15 03:16:55 twilightnode nnrpd[1367309]: valhalla.ausics.net res <anonymous>
Aug 15 03:16:55 twilightnode nnrpd[1367309]: valhalla.ausics.net no_match_user <anonymous> <localhost>
Aug 15 03:16:55 twilightnode nnrpd[1367309]: valhalla.ausics.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 15 03:16:55 twilightnode nnrpd[1367309]: valhalla.ausics.net match_user <anonymous> <anonymous>
Aug 15 03:16:55 twilightnode nnrpd[1367309]: valhalla.ausics.net no_post anonymous-read
Aug 15 04:09:29 twilightnode nnrpd[1369419]: Reading access from /usr/local/news/etc/readers.conf
Aug 15 04:09:29 twilightnode nnrpd[1369419]: Auth strategy 'localhost' does not match client. Removing.
Aug 15 04:09:29 twilightnode nnrpd[1369419]: 194.164.107.6 res <anonymous>
Aug 15 04:09:29 twilightnode nnrpd[1369419]: 194.164.107.6 no_match_user <anonymous> <localhost>
Aug 15 04:09:29 twilightnode nnrpd[1369419]: 194.164.107.6 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 15 04:09:29 twilightnode nnrpd[1369419]: 194.164.107.6 match_user <anonymous> <anonymous>
Aug 15 04:09:29 twilightnode nnrpd[1369419]: 194.164.107.6 no_post anonymous-read
Aug 15 04:53:10 twilightnode nnrpd[1371337]: Reading access from /usr/local/news/etc/readers.conf
Aug 15 04:53:10 twilightnode nnrpd[1371337]: Auth strategy 'localhost' does not match client. Removing.
Aug 15 04:53:10 twilightnode nnrpd[1371337]: 185.216.145.182 res <anonymous>
Aug 15 04:53:10 twilightnode nnrpd[1371337]: 185.216.145.182 no_match_user <anonymous> <localhost>
Aug 15 04:53:10 twilightnode nnrpd[1371337]: 185.216.145.182 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 15 04:53:10 twilightnode nnrpd[1371337]: 185.216.145.182 match_user <anonymous> <anonymous>
Aug 15 04:53:10 twilightnode nnrpd[1371337]: 185.216.145.182 no_post anonymous-read
Aug 15 04:53:32 twilightnode nnrpd[1371340]: Reading access from /usr/local/news/etc/readers.conf
Aug 15 04:53:32 twilightnode nnrpd[1371340]: Auth strategy 'localhost' does not match client. Removing.
Aug 15 04:53:32 twilightnode nnrpd[1371340]: 69.5.169.62 res <anonymous>
Aug 15 04:53:32 twilightnode nnrpd[1371340]: 69.5.169.62 no_match_user <anonymous> <localhost>
Aug 15 04:53:32 twilightnode nnrpd[1371340]: 69.5.169.62 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 15 04:53:32 twilightnode nnrpd[1371340]: 69.5.169.62 match_user <anonymous> <anonymous>
Aug 15 04:53:32 twilightnode nnrpd[1371340]: 69.5.169.62 no_post anonymous-read
Aug 15 14:34:35 twilightnode nnrpd[1396765]: Reading access from /usr/local/news/etc/readers.conf
Aug 15 14:34:35 twilightnode nnrpd[1396765]: Auth strategy 'localhost' does not match client. Removing.
Aug 15 14:34:35 twilightnode nnrpd[1396765]: usenet.blueworldhosting.com res <anonymous>
Aug 15 14:34:35 twilightnode nnrpd[1396765]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 15 14:34:35 twilightnode nnrpd[1396765]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 15 14:34:35 twilightnode nnrpd[1396765]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 15 14:34:35 twilightnode nnrpd[1396765]: usenet.blueworldhosting.com no_post anonymous-read
Aug 15 14:40:00 twilightnode nnrpd[1396947]: Reading access from /usr/local/news/etc/readers.conf
Aug 15 14:40:00 twilightnode nnrpd[1396946]: Reading access from /usr/local/news/etc/readers.conf
Aug 15 14:40:00 twilightnode nnrpd[1396947]: Auth strategy 'localhost' does not match client. Removing.
Aug 15 14:40:00 twilightnode nnrpd[1396947]: usenet.blueworldhosting.com res <anonymous>
Aug 15 14:40:00 twilightnode nnrpd[1396947]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 15 14:40:00 twilightnode nnrpd[1396947]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 15 14:40:00 twilightnode nnrpd[1396947]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 15 14:40:00 twilightnode nnrpd[1396947]: usenet.blueworldhosting.com no_post anonymous-read
Aug 15 14:40:00 twilightnode nnrpd[1396946]: Auth strategy 'localhost' does not match client. Removing.
Aug 15 14:40:00 twilightnode nnrpd[1396946]: usenet.blueworldhosting.com res <anonymous>
Aug 15 14:40:00 twilightnode nnrpd[1396946]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 15 14:40:00 twilightnode nnrpd[1396946]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 15 14:40:00 twilightnode nnrpd[1396946]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 15 14:40:00 twilightnode nnrpd[1396946]: usenet.blueworldhosting.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 8299 48.3%888.1 KB 40.4%
inn 5947 34.6%902.8 KB 41.1%
innd 1564 9.1%213.0 KB 9.7%
innfeed 1331 7.8%187.8 KB 8.5%
controlchan 27 0.2%6.0 KB 0.3%
pgpverify 2 0.0%0.4 KB 0.0%
TOTAL: 6 17170 100.0%2.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13772 50.2%
Negative hits 7746 28.3%
Do not exist 5893 21.5%
Cache misses 0 0.0%
TOTAL: 4 27411 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.003 0.0%4 0.000 0.750 1.000
article cleanup00:00:00.069 0.0%5930 0.000 0.012 0.125
article logging00:00:00.203 0.0%5961 0.000 0.034 0.156
article parse00:00:00.080 0.0%7718 0.000 0.010 0.065
article write00:00:05.246 0.0%4428 0.545 1.185 37.000
data move00:00:00.123 0.0%29525 0.000 0.004 0.041
hisgrep/artcncl00:00:00.003 0.0%4 0.000 0.750 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.805 0.0%27563 0.070 0.211 2.472
history sync00:00:00.081 0.0%316 0.000 0.256 2.667
history write00:00:03.046 0.0%5930 0.067 0.514 1.364
idle23:59:58.599100.0%30602 1348.189 2823.299 11767.157
nntp read00:00:00.618 0.0%28895 0.000 0.021 0.091
overview write00:00:02.275 0.0%4428 0.182 0.514 1.917
perl filter00:00:11.278 0.0%5930 0.911 1.902 18.000
python filter00:00:00.853 0.0%5930 0.022 0.144 0.739
site send00:00:00.095 0.0%4387 0.000 0.022 0.174
TOTAL: 1724:00:28.377100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.015 0.0%4636 0.000 0.003 0.125
article prepare00:00:00.003 0.0%14408 0.000 0.000 0.011
article read00:00:00.658 0.0%2874 0.000 0.229 1.238
backlog stats00:00:00.026 0.0%50537 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.372 0.0%26722 0.000 0.014 0.051
data write00:00:00.612 0.0%22221 0.000 0.028 0.093
idle23:59:52.139100.0%50537 336.657 1709.483 6096.485
status file00:00:00.201 0.0%279 0.000 0.720 8.000
TOTAL: 923:59:54.026100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:09:34.038100.0%2246 80.000 255.582 10536.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.122 0.0%5249 0.000 0.023 0.857
TOTAL: 300:09:34.160100.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
group-admin@isc.org002202
TOTAL002202

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 3 9927 4017 4993 917 40%45:22:56
2newsfeed.endofthelinebbs.com 2 4383 252 3632 499 5%24:07:03
3news.tebibyte.org 2 4826 77 4720 29 1%23:59:00
4news.sklaffkom.se 43 118 62 12 44 52%11:47:25
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 75 19254 4408 13357 1489 22%105:16:24
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.9 MB0.0 KB3.1 MB16.0 MB 80%3.3 KB
2newsfeed.endofthelinebbs.com1.8 MB0.0 KB946.3 KB2.7 MB 66%3.7 KB
3news.sklaffkom.se293.2 KB0.0 KB108.1 KB401.3 KB 73%3.8 KB
4news.tebibyte.org229.2 KB0.0 KB81.2 KB310.4 KB 73%2.9 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 515.2 MB0.0 KB4.2 MB19.4 MB 78%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 15 03:00:03 - 03:59:59 87 2.0% 0.02346.6 KB 2.2% 0.10
Aug 15 04:00:00 - 04:59:59 86 1.9% 0.02231.2 KB 1.5% 0.06
Aug 15 05:00:00 - 05:59:59 75 1.7% 0.02160.4 KB 1.0% 0.04
Aug 15 06:00:00 - 06:59:59 90 2.0% 0.03348.9 KB 2.2% 0.10
Aug 15 07:00:00 - 07:59:59 145 3.3% 0.04364.6 KB 2.3% 0.10
Aug 15 08:00:00 - 08:59:59 175 4.0% 0.05632.3 KB 4.0% 0.18
Aug 15 09:00:00 - 09:59:59 224 5.1% 0.06704.7 KB 4.5% 0.20
Aug 15 10:00:00 - 10:59:59 231 5.2% 0.06573.2 KB 3.7% 0.16
Aug 15 11:00:00 - 11:59:59 232 5.3% 0.06595.2 KB 3.8% 0.17
Aug 15 12:00:00 - 12:59:59 202 4.6% 0.06593.7 KB 3.8% 0.16
Aug 15 13:00:00 - 13:59:59 212 4.8% 0.06539.5 KB 3.4% 0.15
Aug 15 14:00:00 - 14:59:59 211 4.8% 0.06736.8 KB 4.7% 0.20
Aug 15 15:00:00 - 15:59:59 223 5.1% 0.06676.2 KB 4.3% 0.19
Aug 15 16:00:00 - 16:59:59 241 5.5% 0.07803.2 KB 5.1% 0.22
Aug 15 17:00:00 - 17:59:59 270 6.1% 0.071.5 MB 9.8% 0.43
Aug 15 18:00:00 - 18:59:59 241 5.5% 0.07817.9 KB 5.2% 0.23
Aug 15 19:00:00 - 19:59:59 199 4.5% 0.06598.1 KB 3.8% 0.17
Aug 15 20:00:00 - 20:59:59 229 5.2% 0.06708.5 KB 4.5% 0.20
Aug 15 21:00:00 - 21:59:59 238 5.4% 0.07873.3 KB 5.6% 0.24
Aug 15 22:00:00 - 22:59:59 200 4.5% 0.06798.8 KB 5.1% 0.22
Aug 15 23:00:00 - 23:59:59 203 4.6% 0.06773.9 KB 4.9% 0.21
Aug 16 00:00:00 - 00:59:59 171 3.9% 0.051.5 MB 9.7% 0.42
Aug 16 01:00:00 - 01:59:59 104 2.4% 0.03267.2 KB 1.7% 0.07
Aug 16 02:00:00 - 02:59:59 113 2.6% 0.03439.7 KB 2.8% 0.12
Aug 16 03:00:00 - 03:00:03 10 0.2% 3.3317.9 KB 0.1% 5.96
TOTAL: 24:00:00 4412 100.0% 0.0515.3 MB 100.0% 0.18
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 965 861 0 0 0 0 0 0 104
2newsfeed.endofthelinebbs.com 487 66 0 0 0 0 0 0 421
3news.sklaffkom.se 43 43 0 0 0 0 0 0 0
4news.tebibyte.org 40 39 0 0 0 0 0 0 1
TOTAL: 4 1535 1009 0 0 0 0 0 0 526

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 121
alt.checkmate 52
pl.soc.polityka 46
hfx.general 38
alt.atheism 31
linux.debian.devel 22
eternal-september.talk 21
uk.legal.moderated 21
cn.comp 20
alt.support.depression.crisis 18
can.politics 18
francom.esoterisme 17
de.soc.recht.misc 16
de.talk.jokes 16
edm.general 15
eternal-september.test 15
uk.net.news.moderation 15
uk.media.radio.archers 14
it.sport.calcio.inter-fc 13
linux.debian.vote 13
TOTAL: 183 1009

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 421
User-issued cancel 65
HTML Multipart 7
Too many newsgroups (meow) 2
TOTAL: 4 495

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 4647 932 1590 1848 0 48 20%23:59:57
2news.tebibyte.org 6525 766 3597 11 0 6 11%23:59:04
3newsfeed.endofthelinebbs.com 7971 34 4079 17 0 222 0%23:59:52
4weretis.net 367 0 352 0 0 0 0%20:02:02
TOTAL: 4 19510 1732 9618 1876 0 276 8%92:00:55
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.6 MB1005.0 KB3.6 MB0.0 KB/s4.7 KB23:59:04
2sklaffkom.se1.7 MB7.9 MB9.6 MB0.1 KB/s3.5 KB23:59:57
3newsfeed.endofthelinebbs.com209.4 KB42.4 KB251.7 KB0.0 KB/s4.9 KB23:59:52
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s020:02:02
TOTAL: 44.5 MB8.9 MB13.4 MB0.0 KB/s3.8 KB92:00:55
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1usenet.blueworldhosting.com 744 00.0 KB 0 0 000:09:13
2azpdcs8dmkff.stretchoid.com 2 00.0 KB 0 0 000:00:10
3134.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
4185.216.145.182 1 00.0 KB 0 0 000:00:00
5194.164.107.6 1 00.0 KB 0 0 000:00:00
669.5.169.62 1 00.0 KB 0 0 000:00:00
7o047.scanner.modat.io 1 00.0 KB 0 0 000:00:00
8r4-155-9b.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
9r5-4-4.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
10valhalla.ausics.net 1 00.0 KB 0 0 000:00:03
TOTAL: 10 754 00.0 KB 0 0 000:09:33

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.blueworldhosting.com 744 00.0 KB 0 0 000:09:13
2unresolved 3 00.0 KB 0 0 000:00:00
3*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
5*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
6*.ausics.net 1 00.0 KB 0 0 000:00:03
7*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 754 00.0 KB 0 0 000:09:33

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
usenet.blueworldhosting.com 2.691 4.915 0.00000:09:13
azpdcs8dmkff.stretchoid.com 0.008 0.008 0.00000:00:10
valhalla.ausics.net 0.004 0.009 0.00000:00:03
r5-4-4.monitoring.internet-measurement.com 0.006 0.006 0.00000:00:01
r4-155-9b.monitoring.internet-measurement.com 0.006 0.007 0.00000:00:01
134.172.132.66.censys-scanner.com 0.027 0.011 0.00000:00:01
194.164.107.6 0.007 0.010 0.00000:00:00
o047.scanner.modat.io 0.004 0.006 0.00000:00:00
185.216.145.182 0.006 0.012 0.00000:00:00
69.5.169.62 0.004 0.010 0.00000:00:00
TOTAL: 10 2.763 4.994 0.00000:09:33

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

SystemConn
134.172.132.66.censys-scanner.com 1
azpdcs8dmkff.stretchoid.com 1
TOTAL: 2 2

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

CommandCount
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
69.5.169.62 1 1
TOTAL: 1 1 2
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