Daily Usenet report

Aug 17 03:00:04 -- Aug 18 03:00:04

Unknown entries from news log file:

First 50 / 11568 lines (0.4%)

Aug 17 03:01:02 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 17 03:02:00 twilightnode nnrpd[1546616]: Reading access from /usr/local/news/etc/readers.conf
Aug 17 03:02:00 twilightnode nnrpd[1546615]: Reading access from /usr/local/news/etc/readers.conf
Aug 17 03:02:00 twilightnode nnrpd[1546615]: Auth strategy 'localhost' does not match client. Removing.
Aug 17 03:02:00 twilightnode nnrpd[1546615]: usenet.blueworldhosting.com res <anonymous>
Aug 17 03:02:00 twilightnode nnrpd[1546615]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 17 03:02:00 twilightnode nnrpd[1546615]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 17 03:02:00 twilightnode nnrpd[1546615]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 17 03:02:00 twilightnode nnrpd[1546615]: usenet.blueworldhosting.com no_post anonymous-read
Aug 17 03:02:00 twilightnode nnrpd[1546616]: Auth strategy 'localhost' does not match client. Removing.
Aug 17 03:02:00 twilightnode nnrpd[1546616]: usenet.blueworldhosting.com res <anonymous>
Aug 17 03:02:00 twilightnode nnrpd[1546616]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 17 03:02:00 twilightnode nnrpd[1546616]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 17 03:02:00 twilightnode nnrpd[1546616]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 17 03:02:00 twilightnode nnrpd[1546616]: usenet.blueworldhosting.com no_post anonymous-read
Aug 17 03:02:00 twilightnode nnrpd[1546615]: usenet.blueworldhosting.com noperm ihave without permission
Aug 17 03:02:00 twilightnode nnrpd[1546616]: usenet.blueworldhosting.com noperm ihave without permission
Aug 17 03:04:00 twilightnode nnrpd[1546765]: Reading access from /usr/local/news/etc/readers.conf
Aug 17 03:04:00 twilightnode nnrpd[1546765]: Auth strategy 'localhost' does not match client. Removing.
Aug 17 03:04:00 twilightnode nnrpd[1546765]: usenet.blueworldhosting.com res <anonymous>
Aug 17 03:04:00 twilightnode nnrpd[1546765]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 17 03:04:00 twilightnode nnrpd[1546765]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 17 03:04:00 twilightnode nnrpd[1546765]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 17 03:04:00 twilightnode nnrpd[1546765]: usenet.blueworldhosting.com no_post anonymous-read
Aug 17 03:04:00 twilightnode nnrpd[1546764]: Reading access from /usr/local/news/etc/readers.conf
Aug 17 03:04:00 twilightnode nnrpd[1546764]: Auth strategy 'localhost' does not match client. Removing.
Aug 17 03:04:00 twilightnode nnrpd[1546764]: usenet.blueworldhosting.com res <anonymous>
Aug 17 03:04:00 twilightnode nnrpd[1546764]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 17 03:04:00 twilightnode nnrpd[1546764]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 17 03:04:00 twilightnode nnrpd[1546764]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 17 03:04:00 twilightnode nnrpd[1546764]: usenet.blueworldhosting.com no_post anonymous-read
Aug 17 03:04:00 twilightnode nnrpd[1546764]: usenet.blueworldhosting.com noperm ihave without permission
Aug 17 03:04:00 twilightnode nnrpd[1546765]: usenet.blueworldhosting.com noperm ihave without permission
Aug 17 03:06:00 twilightnode nnrpd[1546912]: Reading access from /usr/local/news/etc/readers.conf
Aug 17 03:06:00 twilightnode nnrpd[1546912]: Auth strategy 'localhost' does not match client. Removing.
Aug 17 03:06:00 twilightnode nnrpd[1546912]: usenet.blueworldhosting.com res <anonymous>
Aug 17 03:06:00 twilightnode nnrpd[1546912]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 17 03:06:00 twilightnode nnrpd[1546912]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 17 03:06:00 twilightnode nnrpd[1546912]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 17 03:06:00 twilightnode nnrpd[1546912]: usenet.blueworldhosting.com no_post anonymous-read
Aug 17 03:06:00 twilightnode nnrpd[1546911]: Reading access from /usr/local/news/etc/readers.conf
Aug 17 03:06:00 twilightnode nnrpd[1546911]: Auth strategy 'localhost' does not match client. Removing.
Aug 17 03:06:00 twilightnode nnrpd[1546911]: usenet.blueworldhosting.com res <anonymous>
Aug 17 03:06:00 twilightnode nnrpd[1546911]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 17 03:06:00 twilightnode nnrpd[1546911]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 17 03:06:00 twilightnode nnrpd[1546911]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 17 03:06:00 twilightnode nnrpd[1546911]: usenet.blueworldhosting.com no_post anonymous-read
Aug 17 03:06:00 twilightnode nnrpd[1546911]: usenet.blueworldhosting.com noperm ihave without permission
Aug 17 03:06:00 twilightnode nnrpd[1546912]: usenet.blueworldhosting.com noperm ihave without permission
Aug 17 03:08:00 twilightnode nnrpd[1546942]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 15914 62.4%1.7 MB 54.5%
inn 6904 27.1%1.0 MB 33.6%
innd 1436 5.6%187.2 KB 6.0%
innfeed 1264 5.0%181.6 KB 5.8%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 5 25520 100.0%3.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15293 48.3%
Negative hits 9481 30.0%
Do not exist 6859 21.7%
Cache misses 0 0.0%
TOTAL: 4 31633 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.005 0.0%1 5.000 5.000 5.000
article cleanup00:00:00.077 0.0%6882 0.000 0.011 0.120
article logging00:00:00.244 0.0%6914 0.000 0.035 0.140
article parse00:00:00.101 0.0%9530 0.000 0.011 0.091
article write00:00:04.748 0.0%5137 0.448 0.924 2.273
data move00:00:00.112 0.0%34306 0.000 0.003 0.034
hisgrep/artcncl00:00:00.004 0.0%1 4.000 4.000 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.328 0.0%31740 0.063 0.136 0.397
history sync00:00:00.062 0.0%313 0.000 0.198 2.000
history write00:00:03.230 0.0%6882 0.219 0.469 2.141
idle23:59:54.554100.0%36570 1164.266 2362.443 6667.789
nntp read00:00:00.617 0.0%33477 0.000 0.018 0.063
overview write00:00:02.506 0.0%5137 0.105 0.488 1.643
perl filter00:00:12.554 0.0%6882 0.657 1.824 8.565
python filter00:00:00.928 0.0%6882 0.000 0.135 1.000
site send00:00:00.116 0.0%5118 0.000 0.023 0.125
TOTAL: 1724:00:24.186100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.009 0.0%5252 0.000 0.002 0.167
article prepare00:00:00.005 0.0%18688 0.000 0.000 0.025
article read00:00:00.601 0.0%3596 0.000 0.167 1.667
backlog stats00:00:00.037 0.0%56992 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.397 0.0%30974 0.000 0.013 0.071
data write00:00:00.674 0.0%25774 0.000 0.026 0.104
idle23:57:52.439100.0%56992 375.662 1513.764 4768.127
status file00:00:00.193 0.0%243 0.000 0.794 6.000
TOTAL: 923:57:54.355100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:17:49.400100.0%4329 89.500 247.032 10665.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.193 0.0%10112 0.000 0.019 0.714
TOTAL: 300:17:49.593100.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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 11384 4452 5709 1223 39%46:30:01
2newsfeed.endofthelinebbs.com 3 6650 508 5625 517 7%31:21:25
3news.tebibyte.org 2 5031 78 4916 37 1%23:59:23
4news.sklaffkom.se 40 92 39 22 31 42%10:09:43
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 74 23157 5077 16272 1808 21%112:00:32
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.6 MB0.0 KB3.7 MB17.3 MB 78%3.1 KB
2newsfeed.endofthelinebbs.com5.1 MB0.0 KB2.4 MB7.5 MB 68%7.5 KB
3news.tebibyte.org246.4 KB0.0 KB82.6 KB329.0 KB 74%2.9 KB
4news.sklaffkom.se112.7 KB0.0 KB66.9 KB179.7 KB 62%2.6 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 519.0 MB0.0 KB6.2 MB25.2 MB 75%3.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 17 03:00:04 - 03:59:59 74 1.4% 0.02308.2 KB 1.6% 0.09
Aug 17 04:00:00 - 04:59:59 121 2.4% 0.03575.6 KB 3.0% 0.16
Aug 17 05:00:00 - 05:59:59 100 1.9% 0.03342.8 KB 1.8% 0.10
Aug 17 06:00:00 - 06:59:59 99 1.9% 0.03439.5 KB 2.3% 0.12
Aug 17 07:00:00 - 07:59:59 106 2.1% 0.03448.1 KB 2.4% 0.12
Aug 17 08:00:00 - 08:59:59 173 3.4% 0.05629.2 KB 3.3% 0.17
Aug 17 09:00:00 - 09:59:59 162 3.2% 0.04573.5 KB 3.0% 0.16
Aug 17 10:00:00 - 10:59:59 269 5.2% 0.071.1 MB 6.1% 0.32
Aug 17 11:00:00 - 11:59:59 264 5.1% 0.07807.9 KB 4.3% 0.22
Aug 17 12:00:00 - 12:59:59 257 5.0% 0.07885.6 KB 4.7% 0.25
Aug 17 13:00:00 - 13:59:59 241 4.7% 0.07702.7 KB 3.7% 0.20
Aug 17 14:00:00 - 14:59:59 272 5.3% 0.08801.9 KB 4.2% 0.22
Aug 17 15:00:00 - 15:59:59 293 5.7% 0.08986.1 KB 5.2% 0.27
Aug 17 16:00:00 - 16:59:59 279 5.4% 0.08963.9 KB 5.1% 0.27
Aug 17 17:00:00 - 17:59:59 283 5.5% 0.08814.0 KB 4.3% 0.23
Aug 17 18:00:00 - 18:59:59 322 6.3% 0.091.2 MB 6.6% 0.35
Aug 17 19:00:00 - 19:59:59 289 5.6% 0.081.1 MB 6.0% 0.31
Aug 17 20:00:00 - 20:59:59 331 6.5% 0.091.1 MB 6.0% 0.32
Aug 17 21:00:00 - 21:59:59 279 5.4% 0.081.0 MB 5.4% 0.28
Aug 17 22:00:00 - 22:59:59 200 3.9% 0.06762.9 KB 4.0% 0.21
Aug 17 23:00:00 - 23:59:59 196 3.8% 0.05832.4 KB 4.4% 0.23
Aug 18 00:00:00 - 00:59:59 221 4.3% 0.061.0 MB 5.5% 0.29
Aug 18 01:00:00 - 01:59:59 166 3.2% 0.05907.0 KB 4.8% 0.25
Aug 18 02:00:00 - 02:59:59 125 2.4% 0.03456.5 KB 2.4% 0.13
Aug 18 03:00:00 - 03:00:04 9 0.2% 2.257.8 KB 0.0% 1.95
TOTAL: 24:00:00 5131 100.0% 0.0618.5 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1263 1206 0 0 0 0 0 0 57
2newsfeed.endofthelinebbs.com 426 58 0 0 0 0 0 0 368
3news.tebibyte.org 53 50 0 0 0 0 0 0 3
4news.sklaffkom.se 31 31 0 0 0 0 0 0 0
TOTAL: 4 1773 1345 0 0 0 0 0 0 428

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.debian.maint.ocaml.maint 155
alt.usage.english 50
uk.net.news.moderation 49
hfx.general 45
lada.talk 30
uk.legal.moderated 29
alt.checkmate 28
aus.politics 28
alt.atheism 25
alt.food.fast-food 24
muc.lists.freebsd.current 24
pl.soc.polityka 24
alt.support.depression.crisis 23
de.talk.jokes 22
linux.debian.user 19
cn.comp 18
fr.sci.physique 17
hispagatos.hacking.news 17
uk.media.radio.archers 17
it-alt.sport.calcio.juventus 16
TOTAL: 213 1345

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 372
HTML Multipart 12
User-issued cancel 12
Too many newsgroups (meow) 2
Too many newsgroups 1
TOTAL: 5 399

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7318 1115 3985 8 0 19 15%23:59:51
2sklaffkom.se 5281 916 1585 2607 0 4 17%23:59:20
3newsfeed.endofthelinebbs.com 8729 12 4602 16 0 143 0%23:59:56
4weretis.net 634 1 615 0 0 0 0%21:29:49
TOTAL: 4 21962 2044 10787 2631 0 166 9%93:28:56
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.4 MB462.8 KB3.8 MB0.0 KB/s3.5 KB23:59:51
2sklaffkom.se1.5 MB11.5 MB13.0 MB0.2 KB/s3.8 KB23:59:20
3newsfeed.endofthelinebbs.com34.8 KB41.6 KB76.4 KB0.0 KB/s2.7 KB23:59:56
4weretis.net1.0 KB0.0 KB1.0 KB0.0 KB/s1.0 KB21:29:49
TOTAL: 44.9 MB12.0 MB16.9 MB0.1 KB/s3.7 KB93:28:56
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1usenet.blueworldhosting.com1440 00.0 KB 0 0 000:17:35
2azpdwsp7exl1.stretchoid.com 2 00.0 KB 0 0 000:00:10
3142.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
4193.124.20.247 1 00.0 KB 0 0 000:00:00
569.5.169.97 1 00.0 KB 0 0 000:00:00
6o047.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 61446 00.0 KB 0 0 000:17:49

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.blueworldhosting.com1440 00.0 KB 0 0 000:17:35
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3unresolved 2 00.0 KB 0 0 000:00:00
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
5*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 51446 00.0 KB 0 0 000:17:49

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
usenet.blueworldhosting.com 5.429 9.424 0.00000:17:35
azpdwsp7exl1.stretchoid.com 0.008 0.014 0.00000:00:10
142.172.132.66.censys-scanner.com 0.025 0.011 0.00000:00:01
69.5.169.97 0.005 0.007 0.00000:00:00
o047.scanner.modat.io 0.004 0.004 0.00000:00:00
193.124.20.247 0.005 0.006 0.00000:00:00
TOTAL: 6 5.476 9.466 0.00000:17:49

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

SystemConn
142.172.132.66.censys-scanner.com 1
azpdwsp7exl1.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.97 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