Daily Usenet report

Aug 13 03:00:03 -- Aug 14 03:00:03

Unknown entries from news log file:

First 50 / 76 lines (65.8%)

Aug 13 03:00:54 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 13 03:15:03 twilightnode nnrpd[1236986]: Reading access from /usr/local/news/etc/readers.conf
Aug 13 03:15:03 twilightnode nnrpd[1236986]: Auth strategy 'localhost' does not match client. Removing.
Aug 13 03:15:03 twilightnode nnrpd[1236986]: 50.146.94.167.censys-scanner.com res <anonymous>
Aug 13 03:15:03 twilightnode nnrpd[1236986]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 13 03:15:03 twilightnode nnrpd[1236986]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 13 03:15:03 twilightnode nnrpd[1236986]: 50.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Aug 13 03:15:03 twilightnode nnrpd[1236986]: 50.146.94.167.censys-scanner.com no_post anonymous-read
Aug 13 03:15:06 twilightnode nnrpd[1236987]: Reading access from /usr/local/news/etc/readers.conf
Aug 13 03:15:06 twilightnode nnrpd[1236987]: Auth strategy 'localhost' does not match client. Removing.
Aug 13 03:15:06 twilightnode nnrpd[1236987]: 50.146.94.167.censys-scanner.com res <anonymous>
Aug 13 03:15:06 twilightnode nnrpd[1236987]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 13 03:15:06 twilightnode nnrpd[1236987]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 13 03:15:06 twilightnode nnrpd[1236987]: 50.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Aug 13 03:15:06 twilightnode nnrpd[1236987]: 50.146.94.167.censys-scanner.com no_post anonymous-read
Aug 13 03:15:09 twilightnode nnrpd[1236990]: Reading access from /usr/local/news/etc/readers.conf
Aug 13 03:15:09 twilightnode nnrpd[1236990]: Auth strategy 'localhost' does not match client. Removing.
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com res <anonymous>
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com no_post anonymous-read
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com res <anonymous>
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Aug 13 03:15:09 twilightnode nnrpd[1236990]: 50.146.94.167.censys-scanner.com no_post anonymous-read
Aug 13 04:42:51 twilightnode nnrpd[1240784]: Reading access from /usr/local/news/etc/readers.conf
Aug 13 04:42:51 twilightnode nnrpd[1240784]: Auth strategy 'localhost' does not match client. Removing.
Aug 13 04:42:51 twilightnode nnrpd[1240784]: 194.88.98.101 res <anonymous>
Aug 13 04:42:51 twilightnode nnrpd[1240784]: 194.88.98.101 no_match_user <anonymous> <localhost>
Aug 13 04:42:51 twilightnode nnrpd[1240784]: 194.88.98.101 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 13 04:42:51 twilightnode nnrpd[1240784]: 194.88.98.101 match_user <anonymous> <anonymous>
Aug 13 04:42:51 twilightnode nnrpd[1240784]: 194.88.98.101 no_post anonymous-read
Aug 13 04:43:11 twilightnode nnrpd[1240787]: Reading access from /usr/local/news/etc/readers.conf
Aug 13 04:43:11 twilightnode nnrpd[1240787]: Auth strategy 'localhost' does not match client. Removing.
Aug 13 04:43:11 twilightnode nnrpd[1240787]: 193.124.20.244 res <anonymous>
Aug 13 04:43:11 twilightnode nnrpd[1240787]: 193.124.20.244 no_match_user <anonymous> <localhost>
Aug 13 04:43:11 twilightnode nnrpd[1240787]: 193.124.20.244 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 13 04:43:11 twilightnode nnrpd[1240787]: 193.124.20.244 match_user <anonymous> <anonymous>
Aug 13 04:43:11 twilightnode nnrpd[1240787]: 193.124.20.244 no_post anonymous-read
Aug 13 05:33:45 twilightnode nnrpd[1242831]: Reading access from /usr/local/news/etc/readers.conf
Aug 13 05:33:45 twilightnode nnrpd[1242831]: Auth strategy 'localhost' does not match client. Removing.
Aug 13 05:33:45 twilightnode nnrpd[1242831]: azpdcg18odky.stretchoid.com res <anonymous>
Aug 13 05:33:45 twilightnode nnrpd[1242831]: azpdcg18odky.stretchoid.com no_match_user <anonymous> <localhost>
Aug 13 05:33:45 twilightnode nnrpd[1242831]: azpdcg18odky.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 13 05:33:45 twilightnode nnrpd[1242831]: azpdcg18odky.stretchoid.com match_user <anonymous> <anonymous>
Aug 13 05:33:45 twilightnode nnrpd[1242831]: azpdcg18odky.stretchoid.com no_post anonymous-read
Aug 13 05:33:56 twilightnode nnrpd[1242832]: Reading access from /usr/local/news/etc/readers.conf
Aug 13 05:33:56 twilightnode nnrpd[1242832]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 6457 68.6%977.3 KB 70.0%
innd 1624 17.3%223.9 KB 16.0%
innfeed 1207 12.8%181.4 KB 13.0%
nnrpd 121 1.3%12.5 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 9410 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14109 49.0%
Negative hits 8294 28.8%
Do not exist 6408 22.2%
Cache misses 0 0.0%
TOTAL: 4 28811 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.009 0.0%4 1.000 2.250 3.000
article cleanup00:00:00.037 0.0%6435 0.000 0.006 0.100
article logging00:00:00.225 0.0%6464 0.000 0.035 0.571
article parse00:00:00.089 0.0%8867 0.000 0.010 0.083
article write00:00:04.439 0.0%4743 0.333 0.936 1.800
data move00:00:00.129 0.0%32070 0.000 0.004 0.029
hisgrep/artcncl00:00:00.006 0.0%4 0.000 1.500 2.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.231 0.0%28929 0.028 0.146 3.085
history sync00:00:00.070 0.0%316 0.000 0.222 2.000
history write00:00:02.898 0.0%6435 0.000 0.450 0.864
idle24:00:04.713100.0%31149 1287.163 2773.916 13336.711
nntp read00:00:00.549 0.0%30883 0.000 0.018 0.073
overview write00:00:02.274 0.0%4743 0.137 0.479 1.571
perl filter00:00:09.658 0.0%6435 0.778 1.501 6.147
python filter00:00:00.946 0.0%6435 0.000 0.147 13.520
site send00:00:00.093 0.0%4721 0.000 0.020 0.167
TOTAL: 1724:00:30.366100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.015 0.0%4902 0.000 0.003 0.091
article prepare00:00:00.000 0.0%15336 0.000 0.000 0.000
article read00:00:00.564 0.0%3159 0.000 0.179 1.375
backlog stats00:00:00.040 0.0%53173 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.355 0.0%28470 0.000 0.012 0.083
data write00:00:00.614 0.0%23651 0.000 0.026 0.091
idle23:49:57.291100.0%53173 377.920 1613.550 5719.419
status file00:00:00.166 0.0%217 0.000 0.765 2.000
TOTAL: 923:49:59.045100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:14.293100.0%14 56.500 1020.929 10386.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%86 0.000 0.012 0.021
TOTAL: 300:00:14.294100.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 10907 4133 5620 1154 37%49:44:46
2newsfeed.endofthelinebbs.com 2 4743 555 3664 524 11%23:54:50
3news.tebibyte.org 1 4995 75 4902 18 1%23:59:49
4news.sklaffkom.se 35 92 46 3 43 50%09:37:45
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 67 20737 4809 14189 1739 23%107:17:10
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.6 MB0.0 KB3.9 MB16.6 MB 76%3.2 KB
2newsfeed.endofthelinebbs.com5.1 MB0.0 KB951.0 KB6.0 MB 84%5.7 KB
3news.tebibyte.org241.7 KB0.0 KB47.3 KB289.0 KB 83%3.1 KB
4news.sklaffkom.se158.4 KB0.0 KB94.1 KB252.5 KB 62%2.8 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 518.1 MB0.0 KB5.0 MB23.1 MB 78%3.6 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 13 03:00:03 - 03:59:59 90 1.9% 0.03374.4 KB 2.1% 0.10
Aug 13 04:00:00 - 04:59:59 98 2.1% 0.03599.3 KB 3.3% 0.17
Aug 13 05:00:00 - 05:59:59 68 1.4% 0.02244.6 KB 1.4% 0.07
Aug 13 06:00:00 - 06:59:59 101 2.1% 0.03396.1 KB 2.2% 0.11
Aug 13 07:00:00 - 07:59:59 99 2.1% 0.03273.0 KB 1.5% 0.08
Aug 13 08:00:00 - 08:59:59 161 3.4% 0.04705.8 KB 3.9% 0.20
Aug 13 09:00:00 - 09:59:59 182 3.8% 0.05660.0 KB 3.7% 0.18
Aug 13 10:00:00 - 10:59:59 242 5.1% 0.071.4 MB 8.2% 0.41
Aug 13 11:00:00 - 11:59:59 180 3.8% 0.05578.6 KB 3.2% 0.16
Aug 13 12:00:00 - 12:59:59 208 4.4% 0.06959.0 KB 5.3% 0.27
Aug 13 13:00:00 - 13:59:59 276 5.8% 0.08884.5 KB 4.9% 0.25
Aug 13 14:00:00 - 14:59:59 280 5.9% 0.08971.2 KB 5.4% 0.27
Aug 13 15:00:00 - 15:59:59 317 6.7% 0.09933.2 KB 5.2% 0.26
Aug 13 16:00:00 - 16:59:59 265 5.6% 0.07751.3 KB 4.2% 0.21
Aug 13 17:00:00 - 17:59:59 296 6.3% 0.081.1 MB 6.1% 0.30
Aug 13 18:00:00 - 18:59:59 268 5.7% 0.07871.1 KB 4.8% 0.24
Aug 13 19:00:00 - 19:59:59 363 7.7% 0.101.1 MB 6.4% 0.32
Aug 13 20:00:00 - 20:59:59 240 5.1% 0.07686.4 KB 3.8% 0.19
Aug 13 21:00:00 - 21:59:59 223 4.7% 0.06980.2 KB 5.5% 0.27
Aug 13 22:00:00 - 22:59:59 214 4.5% 0.061.3 MB 7.4% 0.37
Aug 13 23:00:00 - 23:59:59 223 4.7% 0.06910.3 KB 5.1% 0.25
Aug 14 00:00:00 - 00:59:59 148 3.1% 0.04602.3 KB 3.4% 0.17
Aug 14 01:00:00 - 01:59:59 107 2.3% 0.03260.4 KB 1.4% 0.07
Aug 14 02:00:00 - 02:59:59 75 1.6% 0.02268.7 KB 1.5% 0.07
Aug 14 03:00:00 - 03:00:03 9 0.2% 3.008.4 KB 0.0% 2.78
TOTAL: 24:00:00 4733 100.0% 0.0517.5 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1151 1048 0 0 0 0 0 0 103
2newsfeed.endofthelinebbs.com 506 46 0 0 0 0 0 0 460
3news.sklaffkom.se 43 43 0 0 0 0 0 0 0
4news.tebibyte.org 24 24 0 0 0 0 0 0 0
TOTAL: 4 1724 1161 0 0 0 0 0 0 563

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.debian.vote 58
alt.usage.english 45
uk.legal.moderated 36
lada.talk 30
de.talk.jokes 27
hfx.general 25
pl.misc.telefonia.gsm 25
pl.soc.polityka 25
it.cultura.filosofia 24
pl.misc.elektronika 23
de.soc.verkehr 22
eternal-september.talk 21
alt.atheism 20
cn.comp 20
eternal-september.support 20
pl.sci.fizyka 20
pl.soc.prawo 20
aus.politics 18
de.comm.software.gnus 18
it-alt.sport.calcio.juventus 17
TOTAL: 199 1161

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 460
EMP (phn path) 59
Too many newsgroups (meow) 7
User-issued cancel 5
HTML Multipart 3
TOTAL: 5 534

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 4935 984 1614 2113 0 28 19%23:59:57
2news.tebibyte.org 6972 702 4004 5 0 2 10%23:58:51
3newsfeed.endofthelinebbs.com 7987 22 4145 20 0 176 0%23:59:57
4weretis.net 694 0 679 0 0 0 0%22:45:53
TOTAL: 4 20588 1708 10442 2138 0 206 8%94:44:38
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.2 MB400.8 KB2.6 MB0.0 KB/s3.7 KB23:58:51
2sklaffkom.se1.5 MB10.9 MB12.4 MB0.1 KB/s4.1 KB23:59:57
3newsfeed.endofthelinebbs.com73.2 KB75.3 KB148.5 KB0.0 KB/s3.5 KB23:59:57
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:45:53
TOTAL: 43.8 MB11.4 MB15.2 MB0.0 KB/s4.0 KB94:44:38
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
150.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:00
2azpdcg18odky.stretchoid.com 2 00.0 KB 0 0 000:00:10
3193.124.20.244 1 00.0 KB 0 0 000:00:00
4194.88.98.101 1 00.0 KB 0 0 000:00:00
5205.210.31.152 1 00.0 KB 0 0 000:00:01
6o030.scanner.modat.io 1 00.0 KB 0 0 000:00:00
7o031.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 10 00.0 KB 0 0 000:00:14

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:00
2unresolved 3 00.0 KB 0 0 000:00:01
3*.scanner.modat.io 2 00.0 KB 0 0 000:00:01
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
TOTAL: 4 10 00.0 KB 0 0 000:00:14

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcg18odky.stretchoid.com 0.010 0.010 0.00000:00:10
205.210.31.152 0.003 0.014 0.00000:00:01
o031.scanner.modat.io 0.004 0.017 0.00000:00:00
50.146.94.167.censys-scanner.com 0.032 0.031 0.00000:00:00
o030.scanner.modat.io 0.003 0.019 0.00000:00:00
193.124.20.244 0.003 0.005 0.00000:00:00
194.88.98.101 0.005 0.008 0.00000:00:00
TOTAL: 7 0.060 0.104 0.00000:00:14

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

SystemConn
50.146.94.167.censys-scanner.com 1
azpdcg18odky.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
193.124.20.244 1 1
50.146.94.167.censys-scanner.com 1 2
TOTAL: 2 2 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