Daily Usenet report

Aug 19 03:00:03 -- Aug 20 03:00:04

Unknown entries from news log file:

First 50 / 11627 lines (0.4%)

Aug 19 03:01:00 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 19 03:02:00 twilightnode nnrpd[1676400]: Reading access from /usr/local/news/etc/readers.conf
Aug 19 03:02:00 twilightnode nnrpd[1676401]: Reading access from /usr/local/news/etc/readers.conf
Aug 19 03:02:00 twilightnode nnrpd[1676400]: Auth strategy 'localhost' does not match client. Removing.
Aug 19 03:02:00 twilightnode nnrpd[1676400]: usenet.blueworldhosting.com res <anonymous>
Aug 19 03:02:00 twilightnode nnrpd[1676400]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 19 03:02:00 twilightnode nnrpd[1676400]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 19 03:02:00 twilightnode nnrpd[1676400]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 19 03:02:00 twilightnode nnrpd[1676400]: usenet.blueworldhosting.com no_post anonymous-read
Aug 19 03:02:00 twilightnode nnrpd[1676401]: Auth strategy 'localhost' does not match client. Removing.
Aug 19 03:02:00 twilightnode nnrpd[1676401]: usenet.blueworldhosting.com res <anonymous>
Aug 19 03:02:00 twilightnode nnrpd[1676401]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 19 03:02:00 twilightnode nnrpd[1676401]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 19 03:02:00 twilightnode nnrpd[1676401]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 19 03:02:00 twilightnode nnrpd[1676401]: usenet.blueworldhosting.com no_post anonymous-read
Aug 19 03:02:00 twilightnode nnrpd[1676400]: usenet.blueworldhosting.com noperm ihave without permission
Aug 19 03:02:00 twilightnode nnrpd[1676401]: usenet.blueworldhosting.com noperm ihave without permission
Aug 19 03:04:00 twilightnode nnrpd[1676411]: Reading access from /usr/local/news/etc/readers.conf
Aug 19 03:04:00 twilightnode nnrpd[1676411]: Auth strategy 'localhost' does not match client. Removing.
Aug 19 03:04:00 twilightnode nnrpd[1676411]: usenet.blueworldhosting.com res <anonymous>
Aug 19 03:04:00 twilightnode nnrpd[1676411]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 19 03:04:00 twilightnode nnrpd[1676411]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 19 03:04:00 twilightnode nnrpd[1676411]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 19 03:04:00 twilightnode nnrpd[1676411]: usenet.blueworldhosting.com no_post anonymous-read
Aug 19 03:04:00 twilightnode nnrpd[1676412]: Reading access from /usr/local/news/etc/readers.conf
Aug 19 03:04:00 twilightnode nnrpd[1676412]: Auth strategy 'localhost' does not match client. Removing.
Aug 19 03:04:00 twilightnode nnrpd[1676412]: usenet.blueworldhosting.com res <anonymous>
Aug 19 03:04:00 twilightnode nnrpd[1676412]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 19 03:04:00 twilightnode nnrpd[1676412]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 19 03:04:00 twilightnode nnrpd[1676412]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 19 03:04:00 twilightnode nnrpd[1676412]: usenet.blueworldhosting.com no_post anonymous-read
Aug 19 03:04:00 twilightnode nnrpd[1676411]: usenet.blueworldhosting.com noperm ihave without permission
Aug 19 03:04:00 twilightnode nnrpd[1676412]: usenet.blueworldhosting.com noperm ihave without permission
Aug 19 03:06:00 twilightnode nnrpd[1676710]: Reading access from /usr/local/news/etc/readers.conf
Aug 19 03:06:00 twilightnode nnrpd[1676710]: Auth strategy 'localhost' does not match client. Removing.
Aug 19 03:06:00 twilightnode nnrpd[1676710]: usenet.blueworldhosting.com res <anonymous>
Aug 19 03:06:00 twilightnode nnrpd[1676710]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 19 03:06:00 twilightnode nnrpd[1676710]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 19 03:06:00 twilightnode nnrpd[1676710]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 19 03:06:00 twilightnode nnrpd[1676710]: usenet.blueworldhosting.com no_post anonymous-read
Aug 19 03:06:00 twilightnode nnrpd[1676711]: Reading access from /usr/local/news/etc/readers.conf
Aug 19 03:06:00 twilightnode nnrpd[1676711]: Auth strategy 'localhost' does not match client. Removing.
Aug 19 03:06:00 twilightnode nnrpd[1676711]: usenet.blueworldhosting.com res <anonymous>
Aug 19 03:06:00 twilightnode nnrpd[1676711]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 19 03:06:00 twilightnode nnrpd[1676711]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 19 03:06:00 twilightnode nnrpd[1676711]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 19 03:06:00 twilightnode nnrpd[1676711]: usenet.blueworldhosting.com no_post anonymous-read
Aug 19 03:06:00 twilightnode nnrpd[1676710]: usenet.blueworldhosting.com noperm ihave without permission
Aug 19 03:06:00 twilightnode nnrpd[1676711]: usenet.blueworldhosting.com noperm ihave without permission
Aug 19 03:08:00 twilightnode nnrpd[1676720]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 16015 63.0%1.7 MB 55.0%
inn 6475 25.5%991.5 KB 32.0%
innd 1611 6.3%210.9 KB 6.8%
innfeed 1332 5.2%190.3 KB 6.2%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 25434 100.0%3.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14630 49.7%
Negative hits 8422 28.6%
Do not exist 6409 21.8%
Cache misses 0 0.0%
TOTAL: 4 29461 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.007 0.0%5 0.000 1.400 2.000
article cleanup00:00:00.068 0.0%6425 0.000 0.011 0.107
article logging00:00:00.228 0.0%6470 0.000 0.035 0.214
article parse00:00:00.156 0.0%10188 0.000 0.015 0.124
article write00:00:04.343 0.0%4828 0.517 0.900 1.583
data move00:00:00.122 0.0%33734 0.000 0.004 0.020
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.792 0.0%29530 0.066 0.128 0.349
history sync00:00:00.049 0.0%353 0.000 0.139 1.000
history write00:00:02.722 0.0%6425 0.118 0.424 0.960
idle24:00:12.992100.0%35945 505.658 2404.034 7902.487
nntp read00:00:00.760 0.0%32727 0.000 0.023 0.070
overview write00:00:02.226 0.0%4828 0.204 0.461 1.867
perl filter00:00:12.537 0.0%6425 0.932 1.951 9.182
python filter00:00:00.939 0.0%6425 0.000 0.146 1.000
site send00:00:00.157 0.0%5650 0.000 0.028 0.273
TOTAL: 1724:00:41.102100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.020 0.0%4864 0.000 0.004 0.200
article prepare00:00:00.002 0.0%15616 0.000 0.000 0.010
article read00:00:00.621 0.0%3126 0.000 0.199 1.000
backlog stats00:00:00.034 0.0%54110 0.000 0.001 0.010
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.399 0.0%28889 0.000 0.014 0.093
data write00:00:00.681 0.0%24067 0.000 0.028 0.083
idle23:50:05.138100.0%54110 528.648 1585.754 4974.785
status file00:00:00.237 0.0%258 0.000 0.919 20.000
TOTAL: 923:50:07.132100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:18:08.347100.0%4341 76.500 250.713 10243.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.198 0.0%10149 0.000 0.020 0.714
TOTAL: 300:18:08.545100.0%----

Control commands to innd:

CommandNumber
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 170
name 21
pause 2
paused 2
reload 4
reserve 2
TOTAL: 11 212

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 11056 4411 5567 1078 39%46:48:44
2newsfeed.endofthelinebbs.com 1 4157 318 3426 413 7%20:41:55
3news.tebibyte.org 1 4946 55 4862 29 1%23:59:53
4news.sklaffkom.se 36 55 29 17 9 52%07:14:11
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 67 20214 4813 13872 1529 23%98:44:43
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.9 MB0.0 KB3.7 MB15.6 MB 76%2.9 KB
2newsfeed.endofthelinebbs.com3.1 MB0.0 KB10.2 MB13.3 MB 23%18.7 KB
3news.tebibyte.org214.4 KB0.0 KB65.3 KB279.8 KB 76%3.3 KB
4news.sklaffkom.se96.9 KB0.0 KB12.7 KB109.6 KB 88%2.9 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 515.3 MB0.0 KB14.0 MB29.3 MB 52%4.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 19 03:00:03 - 03:59:59 86 1.8% 0.02265.3 KB 1.7% 0.07
Aug 19 04:00:00 - 04:59:59 104 2.2% 0.03593.6 KB 3.8% 0.16
Aug 19 05:00:00 - 05:59:59 97 2.0% 0.03299.2 KB 1.9% 0.08
Aug 19 06:00:00 - 06:59:59 104 2.2% 0.03249.9 KB 1.6% 0.07
Aug 19 07:00:00 - 07:59:59 134 2.8% 0.04383.8 KB 2.4% 0.11
Aug 19 08:00:00 - 08:59:59 192 4.0% 0.05542.2 KB 3.5% 0.15
Aug 19 09:00:00 - 09:59:59 233 4.8% 0.06826.7 KB 5.3% 0.23
Aug 19 10:00:00 - 10:59:59 235 4.9% 0.07659.2 KB 4.2% 0.18
Aug 19 11:00:00 - 11:59:59 271 5.6% 0.08806.6 KB 5.1% 0.22
Aug 19 12:00:00 - 12:59:59 276 5.7% 0.08925.2 KB 5.9% 0.26
Aug 19 13:00:00 - 13:59:59 239 4.9% 0.07644.5 KB 4.1% 0.18
Aug 19 14:00:00 - 14:59:59 246 5.1% 0.07706.5 KB 4.5% 0.20
Aug 19 15:00:00 - 15:59:59 314 6.5% 0.09963.3 KB 6.1% 0.27
Aug 19 16:00:00 - 16:59:59 297 6.1% 0.08988.5 KB 6.3% 0.27
Aug 19 17:00:00 - 17:59:59 268 5.5% 0.07914.0 KB 5.8% 0.25
Aug 19 18:00:00 - 18:59:59 278 5.8% 0.08897.1 KB 5.7% 0.25
Aug 19 19:00:00 - 19:59:59 244 5.1% 0.07724.2 KB 4.6% 0.20
Aug 19 20:00:00 - 20:59:59 233 4.8% 0.06788.5 KB 5.0% 0.22
Aug 19 21:00:00 - 21:59:59 235 4.9% 0.07763.9 KB 4.9% 0.21
Aug 19 22:00:00 - 22:59:59 245 5.1% 0.07766.8 KB 4.9% 0.21
Aug 19 23:00:00 - 23:59:59 150 3.1% 0.04763.1 KB 4.9% 0.21
Aug 20 00:00:00 - 00:59:59 150 3.1% 0.04557.5 KB 3.5% 0.15
Aug 20 01:00:00 - 01:59:59 99 2.0% 0.03323.9 KB 2.1% 0.09
Aug 20 02:00:00 - 02:59:59 89 1.8% 0.02341.0 KB 2.2% 0.09
Aug 20 03:00:00 - 03:00:04 11 0.2% 2.7514.5 KB 0.1% 3.62
TOTAL: 24:00:01 4830 100.0% 0.0615.3 MB 100.0% 0.18
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1154 966 0 0 0 0 0 0 188
2newsfeed.endofthelinebbs.com 460 44 0 0 0 0 0 0 416
3news.tebibyte.org 22 22 0 0 0 0 0 0 0
4news.sklaffkom.se 9 9 0 0 0 0 0 0 0
TOTAL: 4 1645 1041 0 0 0 0 0 0 604

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.legal.moderated 47
lada.talk 36
pl.misc.elektronika 35
alt.atheism 34
de.alt.talk.unmut 34
alt.fan.rush-limbaurgh 32
hfx.general 28
uk.net.news.moderation 28
linux.debian.user.french 24
it-alt.sport.calcio.juventus 21
pl.pregierz 21
pl.soc.prawo 21
cn.comp 20
de.soc.recht.strassenverkehr 20
alt.usage.english 19
lada.photo 19
linux.debian.kernel 18
pl.misc.telefonia.gsm 18
alt.prophecies.nostradamus 16
de.alt.folklore.computer 15
TOTAL: 181 1041

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 422
EMP (phn path) 84
User-issued cancel 46
HTML Multipart 6
Too many newsgroups (meow) 1
TOTAL: 5 559

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 4996 1098 1720 1986 0 0 21%23:59:55
2news.tebibyte.org 7069 819 3979 0 0 6 11%23:59:55
3newsfeed.endofthelinebbs.com 8449 20 4429 7 0 92 0%23:59:54
4weretis.net 442 0 429 0 0 0 0%21:19:23
TOTAL: 4 20956 1937 10557 1993 0 98 9%93:19:07
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.3 MB0.0 KB2.3 MB0.0 KB/s2.9 KB23:59:55
2sklaffkom.se1.9 MB7.8 MB9.7 MB0.1 KB/s3.2 KB23:59:55
3newsfeed.endofthelinebbs.com72.9 KB15.1 KB88.0 KB0.0 KB/s3.3 KB23:59:54
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s021:19:23
TOTAL: 44.3 MB7.8 MB12.2 MB0.0 KB/s3.2 KB93:19:07
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1usenet.blueworldhosting.com1440 00.0 KB 0 0 000:17:39
2160.119.76.18 4 00.0 KB 0 0 000:00:10
371.6.236.65 3 00.0 KB 0 0 000:00:00
4azpdcgruy173.stretchoid.com 2 00.0 KB 0 0 000:00:10
5193-176-31-205.infrawat.ch 1 00.0 KB 0 0 000:00:00
6205.210.31.226 1 00.0 KB 0 0 000:00:00
7219.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:05
869-5-169-82.infrawat.ch 1 00.0 KB 0 0 000:00:00
9o001.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 91454 00.0 KB 0 0 000:18:08

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.blueworldhosting.com1440 00.0 KB 0 0 000:17:39
2unresolved 8 00.0 KB 0 0 000:00:11
3*.infrawat.ch 2 00.0 KB 0 0 000:00:00
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:05
6*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 61454 00.0 KB 0 0 000:18:08

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
usenet.blueworldhosting.com 5.385 9.746 0.00000:17:39
160.119.76.18 0.020 0.029 0.00000:00:10
azpdcgruy173.stretchoid.com 0.007 0.014 0.00000:00:10
219.172.132.66.censys-scanner.com 0.024 0.012 0.00000:00:05
71.6.236.65 0.011 0.021 0.00000:00:00
205.210.31.226 0.004 0.005 0.00000:00:00
69-5-169-82.infrawat.ch 0.005 0.002 0.00000:00:00
193-176-31-205.infrawat.ch 0.004 0.004 0.00000:00:00
o001.scanner.modat.io 0.004 0.006 0.00000:00:00
TOTAL: 9 5.464 9.839 0.00000:18:08

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

SystemConn
160.119.76.18 5
71.6.236.65 4
219.172.132.66.censys-scanner.com 1
azpdcgruy173.stretchoid.com 1
TOTAL: 4 11

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

CommandCount
#023#003#023#001#023#002#001 1
#026#003#001 1
#037?#005?#010#032?]^:?#013,6? 1
#037H 1
Accept: */* 1
Connection: close 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63 1
MGLNDD_172.234.115.63_119 1
User-Agent: Mozilla/5.0 1
TOTAL: 11 11

NNRP client timeouts [Top 20]:

SystemConnPeer
69-5-169-82.infrawat.ch 1 1
TOTAL: 1 1 5
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