Daily Usenet report

Aug 20 03:00:04 -- Aug 21 03:00:04

Unknown entries from news log file:

First 50 / 11554 lines (0.4%)

Aug 20 03:01:00 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 20 03:02:00 twilightnode nnrpd[1754487]: Reading access from /usr/local/news/etc/readers.conf
Aug 20 03:02:00 twilightnode nnrpd[1754487]: Auth strategy 'localhost' does not match client. Removing.
Aug 20 03:02:00 twilightnode nnrpd[1754487]: usenet.blueworldhosting.com res <anonymous>
Aug 20 03:02:00 twilightnode nnrpd[1754487]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 20 03:02:00 twilightnode nnrpd[1754487]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 20 03:02:00 twilightnode nnrpd[1754487]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 20 03:02:00 twilightnode nnrpd[1754487]: usenet.blueworldhosting.com no_post anonymous-read
Aug 20 03:02:00 twilightnode nnrpd[1754487]: usenet.blueworldhosting.com noperm ihave without permission
Aug 20 03:02:01 twilightnode nnrpd[1754494]: Reading access from /usr/local/news/etc/readers.conf
Aug 20 03:02:01 twilightnode nnrpd[1754494]: Auth strategy 'localhost' does not match client. Removing.
Aug 20 03:02:01 twilightnode nnrpd[1754494]: usenet.blueworldhosting.com res <anonymous>
Aug 20 03:02:01 twilightnode nnrpd[1754494]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 20 03:02:01 twilightnode nnrpd[1754494]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 20 03:02:01 twilightnode nnrpd[1754494]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 20 03:02:01 twilightnode nnrpd[1754494]: usenet.blueworldhosting.com no_post anonymous-read
Aug 20 03:02:01 twilightnode nnrpd[1754494]: usenet.blueworldhosting.com noperm ihave without permission
Aug 20 03:04:00 twilightnode nnrpd[1754514]: Reading access from /usr/local/news/etc/readers.conf
Aug 20 03:04:00 twilightnode nnrpd[1754514]: Auth strategy 'localhost' does not match client. Removing.
Aug 20 03:04:00 twilightnode nnrpd[1754514]: usenet.blueworldhosting.com res <anonymous>
Aug 20 03:04:00 twilightnode nnrpd[1754514]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 20 03:04:00 twilightnode nnrpd[1754514]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 20 03:04:00 twilightnode nnrpd[1754514]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 20 03:04:00 twilightnode nnrpd[1754514]: usenet.blueworldhosting.com no_post anonymous-read
Aug 20 03:04:00 twilightnode nnrpd[1754515]: Reading access from /usr/local/news/etc/readers.conf
Aug 20 03:04:00 twilightnode nnrpd[1754515]: Auth strategy 'localhost' does not match client. Removing.
Aug 20 03:04:00 twilightnode nnrpd[1754515]: usenet.blueworldhosting.com res <anonymous>
Aug 20 03:04:00 twilightnode nnrpd[1754515]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 20 03:04:00 twilightnode nnrpd[1754515]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 20 03:04:00 twilightnode nnrpd[1754515]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 20 03:04:00 twilightnode nnrpd[1754515]: usenet.blueworldhosting.com no_post anonymous-read
Aug 20 03:04:00 twilightnode nnrpd[1754514]: usenet.blueworldhosting.com noperm ihave without permission
Aug 20 03:04:00 twilightnode nnrpd[1754515]: usenet.blueworldhosting.com noperm ihave without permission
Aug 20 03:06:00 twilightnode nnrpd[1754802]: Reading access from /usr/local/news/etc/readers.conf
Aug 20 03:06:00 twilightnode nnrpd[1754801]: Reading access from /usr/local/news/etc/readers.conf
Aug 20 03:06:00 twilightnode nnrpd[1754802]: Auth strategy 'localhost' does not match client. Removing.
Aug 20 03:06:00 twilightnode nnrpd[1754802]: usenet.blueworldhosting.com res <anonymous>
Aug 20 03:06:00 twilightnode nnrpd[1754802]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 20 03:06:00 twilightnode nnrpd[1754802]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 20 03:06:00 twilightnode nnrpd[1754802]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 20 03:06:00 twilightnode nnrpd[1754802]: usenet.blueworldhosting.com no_post anonymous-read
Aug 20 03:06:00 twilightnode nnrpd[1754801]: Auth strategy 'localhost' does not match client. Removing.
Aug 20 03:06:00 twilightnode nnrpd[1754801]: usenet.blueworldhosting.com res <anonymous>
Aug 20 03:06:00 twilightnode nnrpd[1754801]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 20 03:06:00 twilightnode nnrpd[1754801]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 20 03:06:00 twilightnode nnrpd[1754801]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 20 03:06:00 twilightnode nnrpd[1754801]: usenet.blueworldhosting.com no_post anonymous-read
Aug 20 03:06:01 twilightnode nnrpd[1754801]: usenet.blueworldhosting.com noperm ihave without permission
Aug 20 03:06:01 twilightnode nnrpd[1754802]: usenet.blueworldhosting.com noperm ihave without permission
Aug 20 03:08:00 twilightnode nnrpd[1754823]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 15893 62.6%1.7 MB 54.1%
inn 6710 26.4%1.0 MB 33.8%
innd 1551 6.1%201.4 KB 6.4%
innfeed 1244 4.9%180.2 KB 5.7%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 5 25400 100.0%3.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13463 46.9%
Negative hits 8611 30.0%
Do not exist 6626 23.1%
Cache misses 0 0.0%
TOTAL: 4 28700 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.009 0.0%5 1.000 1.800 2.500
article cleanup00:00:00.065 0.0%6646 0.000 0.010 0.051
article logging00:00:00.225 0.0%6712 0.000 0.034 0.200
article parse00:00:00.092 0.0%8723 0.000 0.011 0.143
article write00:00:04.668 0.0%4865 0.528 0.960 1.692
data move00:00:00.118 0.0%31485 0.000 0.004 0.047
hisgrep/artcncl00:00:00.004 0.0%5 0.000 0.800 1.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.830 0.0%28798 0.060 0.133 0.236
history sync00:00:00.045 0.0%329 0.000 0.137 1.000
history write00:00:02.943 0.0%6646 0.125 0.443 0.913
idle23:59:55.753100.0%33546 1549.563 2575.441 7594.835
nntp read00:00:00.617 0.0%30433 0.000 0.020 0.081
overview write00:00:02.420 0.0%4865 0.211 0.497 1.769
perl filter00:00:12.657 0.0%6646 0.872 1.904 8.320
python filter00:00:00.830 0.0%6646 0.000 0.125 0.760
site send00:00:00.226 0.0%9700 0.000 0.023 0.100
TOTAL: 1724:00:24.502100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.010 0.0%5013 0.000 0.002 0.059
article prepare00:00:00.003 0.0%21660 0.000 0.000 0.006
article read00:00:00.635 0.0%3390 0.000 0.187 1.250
backlog stats00:00:00.027 0.0%54146 0.000 0.000 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.417 0.0%29594 0.000 0.014 0.055
data write00:00:00.693 0.0%24631 0.000 0.028 0.120
idle23:51:24.183100.0%54146 395.897 1586.159 4861.685
status file00:00:00.163 0.0%235 0.000 0.694 3.000
TOTAL: 923:51:26.131100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:17:58.653100.0%4326 124.333 249.342 2113.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.227 0.0%10137 0.000 0.022 0.286
TOTAL: 300:17:58.880100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 11277 4212 5770 1295 37%48:21:32
2newsfeed.endofthelinebbs.com 2 5193 484 4222 487 9%26:03:06
3news.tebibyte.org 2 5185 55 5109 21 1%23:59:09
4news.sklaffkom.se 31 61 53 4 4 86%07:05:21
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 64 21716 4804 15105 1807 22%105:29:08
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.8 MB0.0 KB4.8 MB16.6 MB 70%3.1 KB
2newsfeed.endofthelinebbs.com4.2 MB0.0 KB841.1 KB5.1 MB 83%5.3 KB
3news.tebibyte.org257.4 KB0.0 KB157.2 KB414.6 KB 62%5.5 KB
4news.sklaffkom.se154.9 KB0.0 KB4.5 KB159.4 KB 97%2.8 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 516.4 MB0.0 KB5.8 MB22.3 MB 73%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 20 03:00:04 - 03:59:59 107 2.2% 0.03444.0 KB 2.6% 0.12
Aug 20 04:00:00 - 04:59:59 125 2.6% 0.03401.4 KB 2.4% 0.11
Aug 20 05:00:00 - 05:59:59 86 1.8% 0.02321.1 KB 1.9% 0.09
Aug 20 06:00:00 - 06:59:59 117 2.4% 0.03391.6 KB 2.3% 0.11
Aug 20 07:00:00 - 07:59:59 144 3.0% 0.04305.2 KB 1.8% 0.08
Aug 20 08:00:00 - 08:59:59 151 3.1% 0.04463.0 KB 2.7% 0.13
Aug 20 09:00:00 - 09:59:59 207 4.3% 0.06665.0 KB 3.9% 0.18
Aug 20 10:00:00 - 10:59:59 224 4.6% 0.06617.0 KB 3.7% 0.17
Aug 20 11:00:00 - 11:59:59 215 4.4% 0.06626.2 KB 3.7% 0.17
Aug 20 12:00:00 - 12:59:59 241 5.0% 0.07638.3 KB 3.8% 0.18
Aug 20 13:00:00 - 13:59:59 293 6.0% 0.08997.1 KB 5.9% 0.28
Aug 20 14:00:00 - 14:59:59 224 4.6% 0.06769.7 KB 4.6% 0.21
Aug 20 15:00:00 - 15:59:59 269 5.5% 0.07908.7 KB 5.4% 0.25
Aug 20 16:00:00 - 16:59:59 247 5.1% 0.071010.8 KB 6.0% 0.28
Aug 20 17:00:00 - 17:59:59 279 5.7% 0.08884.0 KB 5.2% 0.25
Aug 20 18:00:00 - 18:59:59 263 5.4% 0.07848.5 KB 5.0% 0.24
Aug 20 19:00:00 - 19:59:59 320 6.6% 0.091.2 MB 7.4% 0.35
Aug 20 20:00:00 - 20:59:59 256 5.3% 0.07998.8 KB 5.9% 0.28
Aug 20 21:00:00 - 21:59:59 245 5.0% 0.07888.3 KB 5.3% 0.25
Aug 20 22:00:00 - 22:59:59 193 4.0% 0.05777.8 KB 4.6% 0.22
Aug 20 23:00:00 - 23:59:59 206 4.2% 0.061.1 MB 6.8% 0.32
Aug 21 00:00:00 - 00:59:59 201 4.1% 0.06653.5 KB 3.9% 0.18
Aug 21 01:00:00 - 01:59:59 107 2.2% 0.03428.0 KB 2.5% 0.12
Aug 21 02:00:00 - 02:59:59 133 2.7% 0.04413.1 KB 2.5% 0.11
Aug 21 03:00:00 - 03:00:04 9 0.2% 2.258.0 KB 0.0% 2.01
TOTAL: 24:00:00 4862 100.0% 0.0616.5 MB 100.0% 0.20
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1360 1127 0 0 0 0 0 0 233
2newsfeed.endofthelinebbs.com 463 46 0 0 0 0 0 0 417
3news.tebibyte.org 21 21 0 0 0 0 0 0 0
4news.sklaffkom.se 4 4 0 0 0 0 0 0 0
TOTAL: 4 1848 1198 0 0 0 0 0 0 650

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.legal.moderated 106
alt.fan.rush-limbaurgh 66
lada.talk 64
hfx.general 57
bln.test 36
alt.usage.english 34
alt.bestjobsusa.computer.jobs 29
alt.checkmate 29
alt.atheism 24
pl.pregierz 22
pl.soc.prawo 22
cn.comp 20
linux.debian.vote 20
it-alt.sport.calcio.juventus 17
alt.food.fast-food 14
alt.home.repair 14
de.rec.film.misc 14
muc.lists.netbsd.tech.userlevel 14
pl.misc.elektronika 14
wolfsburg.test 14
TOTAL: 188 1198

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 429
EMP (phn path) 132
User-issued cancel 8
HTML Multipart 7
Too many newsgroups 6
Too many newsgroups (meow) 2
TOTAL: 6 584

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 65
newsfeed.endofthelinebbs.com 1
TOTAL: 2 66
TOTAL: 1 66

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6241 2079 2750 3 0 7 33%23:59:56
2sklaffkom.se 5029 1052 1515 2262 0 3 20%23:59:35
3newsfeed.endofthelinebbs.com 8281 25 4328 10 0 194 0%23:59:56
4weretis.net 621 0 610 0 0 0 0%22:11:30
TOTAL: 4 20172 3156 9203 2275 0 204 15%94:10:57
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org5.3 MB218.0 KB5.5 MB0.1 KB/s2.7 KB23:59:56
2sklaffkom.se1.8 MB9.9 MB11.7 MB0.1 KB/s3.6 KB23:59:35
3newsfeed.endofthelinebbs.com73.3 KB31.6 KB104.9 KB0.0 KB/s3.0 KB23:59:56
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:11:30
TOTAL: 47.1 MB10.1 MB17.2 MB0.1 KB/s3.2 KB94:10:57
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1usenet.blueworldhosting.com1440 00.0 KB 0 0 000:17:50
2166.186.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:07
3205.210.31.57 1 00.0 KB 0 0 000:00:00
TOTAL: 31444 00.0 KB 0 0 000:17:58

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.blueworldhosting.com1440 00.0 KB 0 0 000:17:50
2*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:07
3unresolved 1 00.0 KB 0 0 000:00:00
TOTAL: 31444 00.0 KB 0 0 000:17:58

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
usenet.blueworldhosting.com 5.378 9.775 0.00000:17:50
166.186.132.66.censys-scanner.com 0.040 0.017 0.00000:00:07
205.210.31.57 0.003 0.009 0.00000:00:00
TOTAL: 3 5.421 9.801 0.00000:17:58

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

SystemConn
166.186.132.66.censys-scanner.com 1
TOTAL: 1 1

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

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

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