Daily Usenet report

Aug 18 03:00:04 -- Aug 19 03:00:03

Unknown entries from news log file:

First 50 / 11657 lines (0.4%)

Aug 18 03:00:59 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 18 03:02:00 twilightnode nnrpd[1611207]: Reading access from /usr/local/news/etc/readers.conf
Aug 18 03:02:00 twilightnode nnrpd[1611208]: Reading access from /usr/local/news/etc/readers.conf
Aug 18 03:02:00 twilightnode nnrpd[1611207]: Auth strategy 'localhost' does not match client. Removing.
Aug 18 03:02:00 twilightnode nnrpd[1611207]: usenet.blueworldhosting.com res <anonymous>
Aug 18 03:02:00 twilightnode nnrpd[1611207]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 18 03:02:00 twilightnode nnrpd[1611207]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 18 03:02:00 twilightnode nnrpd[1611207]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 18 03:02:00 twilightnode nnrpd[1611207]: usenet.blueworldhosting.com no_post anonymous-read
Aug 18 03:02:00 twilightnode nnrpd[1611208]: Auth strategy 'localhost' does not match client. Removing.
Aug 18 03:02:00 twilightnode nnrpd[1611208]: usenet.blueworldhosting.com res <anonymous>
Aug 18 03:02:00 twilightnode nnrpd[1611208]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 18 03:02:00 twilightnode nnrpd[1611208]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 18 03:02:00 twilightnode nnrpd[1611208]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 18 03:02:00 twilightnode nnrpd[1611208]: usenet.blueworldhosting.com no_post anonymous-read
Aug 18 03:02:00 twilightnode nnrpd[1611207]: usenet.blueworldhosting.com noperm ihave without permission
Aug 18 03:02:00 twilightnode nnrpd[1611208]: usenet.blueworldhosting.com noperm ihave without permission
Aug 18 03:04:00 twilightnode nnrpd[1611216]: Reading access from /usr/local/news/etc/readers.conf
Aug 18 03:04:00 twilightnode nnrpd[1611216]: Auth strategy 'localhost' does not match client. Removing.
Aug 18 03:04:00 twilightnode nnrpd[1611216]: usenet.blueworldhosting.com res <anonymous>
Aug 18 03:04:00 twilightnode nnrpd[1611216]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 18 03:04:00 twilightnode nnrpd[1611216]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 18 03:04:00 twilightnode nnrpd[1611216]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 18 03:04:00 twilightnode nnrpd[1611216]: usenet.blueworldhosting.com no_post anonymous-read
Aug 18 03:04:00 twilightnode nnrpd[1611217]: Reading access from /usr/local/news/etc/readers.conf
Aug 18 03:04:00 twilightnode nnrpd[1611217]: Auth strategy 'localhost' does not match client. Removing.
Aug 18 03:04:00 twilightnode nnrpd[1611217]: usenet.blueworldhosting.com res <anonymous>
Aug 18 03:04:00 twilightnode nnrpd[1611217]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 18 03:04:00 twilightnode nnrpd[1611217]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 18 03:04:00 twilightnode nnrpd[1611217]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 18 03:04:00 twilightnode nnrpd[1611217]: usenet.blueworldhosting.com no_post anonymous-read
Aug 18 03:04:00 twilightnode nnrpd[1611216]: usenet.blueworldhosting.com noperm ihave without permission
Aug 18 03:04:00 twilightnode nnrpd[1611217]: usenet.blueworldhosting.com noperm ihave without permission
Aug 18 03:06:00 twilightnode nnrpd[1611502]: Reading access from /usr/local/news/etc/readers.conf
Aug 18 03:06:00 twilightnode nnrpd[1611501]: Reading access from /usr/local/news/etc/readers.conf
Aug 18 03:06:00 twilightnode nnrpd[1611502]: Auth strategy 'localhost' does not match client. Removing.
Aug 18 03:06:00 twilightnode nnrpd[1611502]: usenet.blueworldhosting.com res <anonymous>
Aug 18 03:06:00 twilightnode nnrpd[1611502]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 18 03:06:00 twilightnode nnrpd[1611502]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 18 03:06:00 twilightnode nnrpd[1611502]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 18 03:06:00 twilightnode nnrpd[1611502]: usenet.blueworldhosting.com no_post anonymous-read
Aug 18 03:06:00 twilightnode nnrpd[1611501]: Auth strategy 'localhost' does not match client. Removing.
Aug 18 03:06:00 twilightnode nnrpd[1611501]: usenet.blueworldhosting.com res <anonymous>
Aug 18 03:06:00 twilightnode nnrpd[1611501]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 18 03:06:00 twilightnode nnrpd[1611501]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 18 03:06:00 twilightnode nnrpd[1611501]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 18 03:06:00 twilightnode nnrpd[1611501]: usenet.blueworldhosting.com no_post anonymous-read
Aug 18 03:06:00 twilightnode nnrpd[1611501]: usenet.blueworldhosting.com noperm ihave without permission
Aug 18 03:06:00 twilightnode nnrpd[1611502]: usenet.blueworldhosting.com noperm ihave without permission
Aug 18 03:08:00 twilightnode nnrpd[1611516]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 16071 63.9%1.7 MB 56.2%
inn 6340 25.2%964.9 KB 31.6%
innd 1500 6.0%194.6 KB 6.4%
innfeed 1235 4.9%176.3 KB 5.8%
controlchan 13 0.1%3.3 KB 0.1%
TOTAL: 5 25159 100.0%3.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14066 48.6%
Negative hits 8578 29.7%
Do not exist 6275 21.7%
Cache misses 0 0.0%
TOTAL: 4 28919 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.002 0.0%1 2.000 2.000 2.000
article cleanup00:00:00.093 0.0%6292 0.000 0.015 0.462
article logging00:00:00.280 0.0%6342 0.000 0.044 0.154
article parse00:00:00.123 0.0%8574 0.000 0.014 0.150
article write00:00:04.714 0.0%4726 0.562 0.997 1.826
data move00:00:00.092 0.0%31733 0.000 0.003 0.024
hisgrep/artcncl00:00:00.001 0.0%1 1.000 1.000 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.985 0.0%28996 0.062 0.137 0.263
history sync00:00:00.078 0.0%315 0.000 0.248 1.333
history write00:00:03.008 0.0%6292 0.077 0.478 0.889
idle23:59:54.596100.0%33775 1332.938 2557.945 10002.383
nntp read00:00:00.619 0.0%30657 0.000 0.020 0.066
overview write00:00:02.459 0.0%4726 0.207 0.520 1.600
perl filter00:00:12.462 0.0%6292 0.732 1.981 6.154
python filter00:00:00.905 0.0%6292 0.020 0.144 0.510
site send00:00:00.099 0.0%4704 0.000 0.021 0.100
TOTAL: 1724:00:23.516100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.019 0.0%4911 0.000 0.004 0.091
article prepare00:00:00.002 0.0%15788 0.000 0.000 0.009
article read00:00:00.617 0.0%3134 0.000 0.197 4.000
backlog stats00:00:00.044 0.0%52176 0.000 0.001 0.016
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.365 0.0%28096 0.000 0.013 0.115
data write00:00:00.645 0.0%23354 0.000 0.028 0.111
idle23:59:52.469100.0%52176 367.118 1655.789 6188.155
status file00:00:00.180 0.0%230 0.000 0.783 7.000
TOTAL: 923:59:54.341100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:18:21.691100.0%4350 29.000 253.262 10569.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.216 0.0%10227 0.000 0.021 0.857
TOTAL: 300:18:21.907100.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
bofh-control@killfile.org001001
TOTAL001001

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 10881 4184 5632 1065 38%48:21:45
2newsfeed.endofthelinebbs.com 1 4552 464 3623 465 10%23:07:51
3news.tebibyte.org 1 5072 99 4931 42 1%23:59:57
4news.sklaffkom.se 39 83 38 21 24 45%09:29:08
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 71 20588 4785 14207 1596 23%104:58:41
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.8 MB0.0 KB3.4 MB16.2 MB 78%3.2 KB
2newsfeed.endofthelinebbs.com4.5 MB0.0 KB963.0 KB5.5 MB 82%6.0 KB
3news.tebibyte.org234.7 KB0.0 KB124.3 KB359.0 KB 65%2.5 KB
4news.sklaffkom.se127.5 KB0.0 KB46.8 KB174.3 KB 73%2.8 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 517.7 MB0.0 KB4.5 MB22.2 MB 79%3.6 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 18 03:00:04 - 03:59:59 122 2.6% 0.03666.2 KB 3.6% 0.19
Aug 18 04:00:00 - 04:59:59 76 1.6% 0.02273.5 KB 1.5% 0.08
Aug 18 05:00:00 - 05:59:59 92 1.9% 0.03265.6 KB 1.5% 0.07
Aug 18 06:00:00 - 06:59:59 100 2.1% 0.03290.2 KB 1.6% 0.08
Aug 18 07:00:00 - 07:59:59 125 2.6% 0.03545.7 KB 3.0% 0.15
Aug 18 08:00:00 - 08:59:59 191 4.0% 0.05919.2 KB 5.0% 0.26
Aug 18 09:00:00 - 09:59:59 227 4.8% 0.06807.8 KB 4.4% 0.22
Aug 18 10:00:00 - 10:59:59 191 4.0% 0.05535.9 KB 2.9% 0.15
Aug 18 11:00:00 - 11:59:59 259 5.5% 0.07818.9 KB 4.5% 0.23
Aug 18 12:00:00 - 12:59:59 218 4.6% 0.06723.0 KB 4.0% 0.20
Aug 18 13:00:00 - 13:59:59 202 4.3% 0.06896.1 KB 4.9% 0.25
Aug 18 14:00:00 - 14:59:59 232 4.9% 0.06647.9 KB 3.5% 0.18
Aug 18 15:00:00 - 15:59:59 276 5.8% 0.081000.2 KB 5.5% 0.28
Aug 18 16:00:00 - 16:59:59 234 5.0% 0.07768.8 KB 4.2% 0.21
Aug 18 17:00:00 - 17:59:59 291 6.2% 0.081.5 MB 8.4% 0.42
Aug 18 18:00:00 - 18:59:59 302 6.4% 0.081.2 MB 6.7% 0.34
Aug 18 19:00:00 - 19:59:59 247 5.2% 0.07843.1 KB 4.6% 0.23
Aug 18 20:00:00 - 20:59:59 261 5.5% 0.07870.8 KB 4.8% 0.24
Aug 18 21:00:00 - 21:59:59 274 5.8% 0.08860.5 KB 4.7% 0.24
Aug 18 22:00:00 - 22:59:59 184 3.9% 0.05734.7 KB 4.0% 0.20
Aug 18 23:00:00 - 23:59:59 180 3.8% 0.051.1 MB 5.9% 0.30
Aug 19 00:00:00 - 00:59:59 164 3.5% 0.05660.7 KB 3.6% 0.18
Aug 19 01:00:00 - 01:59:59 163 3.4% 0.05655.7 KB 3.6% 0.18
Aug 19 02:00:00 - 02:59:59 107 2.3% 0.03638.7 KB 3.5% 0.18
Aug 19 03:00:00 - 03:00:03 7 0.1% 2.336.1 KB 0.0% 2.02
TOTAL: 23:59:59 4725 100.0% 0.0517.8 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1102 989 0 0 0 0 0 0 113
2newsfeed.endofthelinebbs.com 461 37 0 0 0 0 0 0 424
3news.tebibyte.org 28 28 0 0 0 0 0 0 0
4news.sklaffkom.se 24 24 0 0 0 0 0 0 0
TOTAL: 4 1615 1078 0 0 0 0 0 0 537

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.net.news.moderation 44
pl.misc.elektronika 42
uk.legal.moderated 39
hfx.general 37
alt.usage.english 32
lada.talk 32
alt.food.fast-food 21
muc.lists.freebsd.bugs 21
cn.comp 20
linux.debian.kernel 20
linux.debian.user 20
pl.pregierz 19
dk.politik 18
linux.debian.changes 17
linux.debian.user.french 17
pl.soc.prawo 17
alt.prophecies.nostradamus 16
de.talk.jokes 16
pt.conversa 16
alt.support.depression.crisis 15
TOTAL: 180 1078

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 470
EMP (phn path) 6
User-issued cancel 6
HTML Multipart 4
Too many newsgroups 1
TOTAL: 5 487

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 4884 979 1665 2060 0 2 20%23:59:56
2news.tebibyte.org 6829 869 3836 3 0 7 12%23:59:40
3newsfeed.endofthelinebbs.com 7997 29 4211 10 0 195 0%23:59:54
4weretis.net 565 0 548 0 0 3 0%21:42:49
TOTAL: 4 20275 1877 10260 2073 0 207 9%93:42:19
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.6 MB621.8 KB3.2 MB0.0 KB/s3.8 KB23:59:40
2sklaffkom.se1.6 MB10.4 MB12.0 MB0.1 KB/s4.0 KB23:59:56
3newsfeed.endofthelinebbs.com98.8 KB32.4 KB131.2 KB0.0 KB/s3.4 KB23:59:54
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s021:42:49
TOTAL: 44.3 MB11.0 MB15.4 MB0.0 KB/s4.0 KB93:42:19
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1usenet.blueworldhosting.com1440 00.0 KB 0 0 000:17:39
2165.154.12.127 3 00.0 KB 0 0 000:00:11
376.155.45.199.censys-scanner.com 3 00.0 KB 0 0 000:00:02
4azpdsgkml0gq.stretchoid.com 2 00.0 KB 0 0 000:00:10
5102.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:09
6147.185.132.94 1 00.0 KB 0 0 000:00:00
7194.88.98.87 1 00.0 KB 0 0 000:00:00
8217.146.80.101 1 00.0 KB 0 0 000:00:00
9217.146.80.121 1 00.0 KB 0 0 000:00:00
1036.255.97.14 1 00.0 KB 0 0 000:00:01
1169.5.169.87 1 00.0 KB 0 0 000:00:00
1295.155.151.66 1 00.0 KB 0 0 000:00:00
13r5-16-10.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
14r5-194-c2.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 141458 00.0 KB 0 0 000:18:21

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.blueworldhosting.com1440 00.0 KB 0 0 000:17:39
2unresolved 10 00.0 KB 0 0 000:00:14
3*.199.censys-scanner.com 3 00.0 KB 0 0 000:00:02
4*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
5*.stretchoid.com 2 00.0 KB 0 0 000:00:10
6*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:09
TOTAL: 61458 00.0 KB 0 0 000:18:21

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
usenet.blueworldhosting.com 5.416 9.752 0.00000:17:39
165.154.12.127 0.016 0.020 0.00000:00:11
azpdsgkml0gq.stretchoid.com 0.008 0.017 0.00000:00:10
102.172.132.66.censys-scanner.com 0.020 0.014 0.00000:00:09
76.155.45.199.censys-scanner.com 0.028 0.021 0.00000:00:02
r5-16-10.monitoring.internet-measurement.com 0.003 0.008 0.00000:00:01
r5-194-c2.monitoring.internet-measurement.com 0.002 0.013 0.00000:00:01
36.255.97.14 0.002 0.008 0.00000:00:01
69.5.169.87 0.006 0.004 0.00000:00:00
217.146.80.121 0.005 0.007 0.00000:00:00
147.185.132.94 0.004 0.003 0.00000:00:00
194.88.98.87 0.004 0.003 0.00000:00:00
217.146.80.101 0.005 0.005 0.00000:00:00
95.155.151.66 0.004 0.006 0.00000:00:00
TOTAL: 14 5.523 9.881 0.00000:18:21

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

SystemConn
36.255.97.14 9
165.154.12.127 2
102.172.132.66.censys-scanner.com 1
76.155.45.199.censys-scanner.com 1
azpdsgkml0gq.stretchoid.com 1
TOTAL: 5 14

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

CommandCount
EHLO www.censys.io 2
#021? 1
#023?S?1?5V#021?RX#023?#005?#0059#004?'?[?4#027?CW)?a%... 1
#026#003#001 1
#026#003#001#005?#001 1
#026?E`@?B? 1
?#024 1
?#024#023#001#023#002#023#003#001 1
?;#015X#032#016#0135WY!#034G/?0?Y2c(:+?X?F#011?#003?e... 1
?L?6f?/?#?w1?27?A#001? 1
MGLNDD_172.234.115.63_119 1
i?O?s'#035X?#007X~?"?[:&#004?#016?#002V)<?T?[6#016$?2#023... 1
z 1
TOTAL: 13 14

NNRP client timeouts [Top 20]:

SystemConnPeer
194.88.98.87 1 1
69.5.169.87 1 1
76.155.45.199.censys-scanner.com 1 2
TOTAL: 3 3 7
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