Daily Usenet report

Aug 3 03:00:04 -- Aug 4 03:00:05

Unknown entries from news log file:

First 50 / 90 lines (55.6%)

Aug 3 03:01:19 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 3 03:03:00 twilightnode nnrpd[544297]: Reading access from /usr/local/news/etc/readers.conf
Aug 3 03:03:00 twilightnode nnrpd[544297]: Auth strategy 'localhost' does not match client. Removing.
Aug 3 03:03:00 twilightnode nnrpd[544297]: 46.172.132.66.censys-scanner.com res <anonymous>
Aug 3 03:03:00 twilightnode nnrpd[544297]: 46.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 3 03:03:00 twilightnode nnrpd[544297]: 46.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 3 03:03:00 twilightnode nnrpd[544297]: 46.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 3 03:03:00 twilightnode nnrpd[544297]: 46.172.132.66.censys-scanner.com no_post anonymous-read
Aug 3 03:03:04 twilightnode nnrpd[544299]: Reading access from /usr/local/news/etc/readers.conf
Aug 3 03:03:04 twilightnode nnrpd[544299]: Auth strategy 'localhost' does not match client. Removing.
Aug 3 03:03:04 twilightnode nnrpd[544299]: 46.172.132.66.censys-scanner.com res <anonymous>
Aug 3 03:03:04 twilightnode nnrpd[544299]: 46.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 3 03:03:04 twilightnode nnrpd[544299]: 46.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 3 03:03:04 twilightnode nnrpd[544299]: 46.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 3 03:03:04 twilightnode nnrpd[544299]: 46.172.132.66.censys-scanner.com no_post anonymous-read
Aug 3 03:03:07 twilightnode nnrpd[544300]: Reading access from /usr/local/news/etc/readers.conf
Aug 3 03:03:07 twilightnode nnrpd[544300]: Auth strategy 'localhost' does not match client. Removing.
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com res <anonymous>
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com no_post anonymous-read
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com res <anonymous>
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 3 03:03:07 twilightnode nnrpd[544300]: 46.172.132.66.censys-scanner.com no_post anonymous-read
Aug 3 05:50:51 twilightnode nnrpd[551435]: Reading access from /usr/local/news/etc/readers.conf
Aug 3 05:50:51 twilightnode nnrpd[551435]: Auth strategy 'localhost' does not match client. Removing.
Aug 3 05:50:51 twilightnode nnrpd[551435]: r3-186-ba.monitoring.internet-measurement.com res <anonymous>
Aug 3 05:50:51 twilightnode nnrpd[551435]: r3-186-ba.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Aug 3 05:50:51 twilightnode nnrpd[551435]: r3-186-ba.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 3 05:50:51 twilightnode nnrpd[551435]: r3-186-ba.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Aug 3 05:50:51 twilightnode nnrpd[551435]: r3-186-ba.monitoring.internet-measurement.com no_post anonymous-read
Aug 3 06:18:50 twilightnode nnrpd[552540]: Reading access from /usr/local/news/etc/readers.conf
Aug 3 06:18:50 twilightnode nnrpd[552540]: Auth strategy 'localhost' does not match client. Removing.
Aug 3 06:18:50 twilightnode nnrpd[552540]: 217.146.80.107 res <anonymous>
Aug 3 06:18:50 twilightnode nnrpd[552540]: 217.146.80.107 no_match_user <anonymous> <localhost>
Aug 3 06:18:50 twilightnode nnrpd[552540]: 217.146.80.107 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 3 06:18:50 twilightnode nnrpd[552540]: 217.146.80.107 match_user <anonymous> <anonymous>
Aug 3 06:18:50 twilightnode nnrpd[552540]: 217.146.80.107 no_post anonymous-read
Aug 3 06:19:11 twilightnode nnrpd[552543]: Reading access from /usr/local/news/etc/readers.conf
Aug 3 06:19:11 twilightnode nnrpd[552543]: Auth strategy 'localhost' does not match client. Removing.
Aug 3 06:19:11 twilightnode nnrpd[552543]: 31.14.254.98 res <anonymous>
Aug 3 06:19:11 twilightnode nnrpd[552543]: 31.14.254.98 no_match_user <anonymous> <localhost>
Aug 3 06:19:11 twilightnode nnrpd[552543]: 31.14.254.98 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 3 06:19:11 twilightnode nnrpd[552543]: 31.14.254.98 match_user <anonymous> <anonymous>
Aug 3 06:19:11 twilightnode nnrpd[552543]: 31.14.254.98 no_post anonymous-read
Aug 3 13:42:35 twilightnode nnrpd[571908]: Reading access from /usr/local/news/etc/readers.conf
Aug 3 13:42:35 twilightnode nnrpd[571908]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7388 71.6%1.1 MB 72.3%
innd 1624 15.7%234.8 KB 15.3%
innfeed 1164 11.3%173.8 KB 11.4%
nnrpd 145 1.4%14.9 KB 1.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 10322 100.0%1.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15595 47.2%
Negative hits 10071 30.5%
Do not exist 7347 22.2%
Cache misses 36 0.1%
TOTAL: 4 33049 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.011 0.0%5 1.000 2.200 3.500
article cleanup00:00:00.052 0.0%7364 0.000 0.007 0.167
article logging00:00:00.248 0.0%7386 0.000 0.034 0.163
article parse00:00:00.128 0.0%13281 0.000 0.010 0.111
article write00:00:05.401 0.0%5147 0.400 1.049 5.364
data move00:00:00.133 0.0%40362 0.000 0.003 0.042
hisgrep/artcncl00:00:00.006 0.0%5 0.500 1.200 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.750 0.0%33121 0.062 0.143 0.547
history sync00:00:00.043 0.0%343 0.000 0.125 2.500
history write00:00:03.748 0.0%7364 0.172 0.509 1.323
idle24:00:06.122100.0%38491 216.994 2244.840 15794.605
nntp read00:00:00.736 0.0%38202 0.000 0.019 0.088
overview write00:00:02.360 0.0%5147 0.065 0.459 2.214
perl filter00:00:08.770 0.0%7364 0.641 1.191 16.632
python filter00:00:00.586 0.0%7364 0.000 0.080 2.158
site send00:00:00.092 0.0%5123 0.000 0.018 0.182
TOTAL: 1724:00:33.186100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.022 0.0%5285 0.000 0.004 0.071
article prepare00:00:00.001 0.0%18168 0.000 0.000 0.008
article read00:00:00.602 0.0%3449 0.000 0.175 0.810
backlog stats00:00:00.023 0.0%55601 0.000 0.000 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.399 0.0%30480 0.000 0.013 0.049
data write00:00:00.612 0.0%25233 0.000 0.024 0.065
idle23:59:22.444100.0%55601 446.072 1553.253 7233.711
status file00:00:00.128 0.0%192 0.000 0.667 5.000
TOTAL: 923:59:24.231100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:15.371100.0%18 64.000 853.944 10370.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%107 0.000 0.009 0.059
TOTAL: 300:00:15.372100.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 11944 4269 6222 1453 35%48:24:37
2newsfeed.endofthelinebbs.com 3 5909 612 4571 726 10%26:05:57
3news.tebibyte.org 13 8552 185 8292 75 2%36:08:59
4news.sklaffkom.se 40 87 63 9 15 72%09:36:05
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 85 26492 5129 19094 2269 19%120:15:38
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.1 MB0.0 KB5.2 MB20.3 MB 74%3.6 KB
2newsfeed.endofthelinebbs.com7.2 MB0.0 KB1.4 MB8.6 MB 84%6.6 KB
3news.tebibyte.org861.1 KB0.0 KB190.9 KB1.0 MB 81%4.0 KB
4news.sklaffkom.se200.0 KB0.0 KB26.6 KB226.6 KB 88%2.9 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 523.4 MB0.0 KB6.8 MB30.2 MB 77%4.2 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 3 03:00:04 - 03:59:59 96 1.9% 0.03359.1 KB 1.1% 0.10
Aug 3 04:00:00 - 04:59:59 117 2.3% 0.03605.9 KB 1.9% 0.17
Aug 3 05:00:00 - 05:59:59 81 1.6% 0.02386.4 KB 1.2% 0.11
Aug 3 06:00:00 - 06:59:59 106 2.1% 0.03335.1 KB 1.0% 0.09
Aug 3 07:00:00 - 07:59:59 127 2.5% 0.04646.0 KB 2.0% 0.18
Aug 3 08:00:00 - 08:59:59 181 3.5% 0.051.5 MB 4.8% 0.43
Aug 3 09:00:00 - 09:59:59 249 4.8% 0.07879.1 KB 2.7% 0.24
Aug 3 10:00:00 - 10:59:59 262 5.1% 0.071.1 MB 3.4% 0.30
Aug 3 11:00:00 - 11:59:59 236 4.6% 0.07791.0 KB 2.4% 0.22
Aug 3 12:00:00 - 12:59:59 240 4.7% 0.071.2 MB 3.7% 0.33
Aug 3 13:00:00 - 13:59:59 242 4.7% 0.07871.9 KB 2.7% 0.24
Aug 3 14:00:00 - 14:59:59 178 3.5% 0.05509.3 KB 1.6% 0.14
Aug 3 15:00:00 - 15:59:59 311 6.0% 0.091.0 MB 3.2% 0.29
Aug 3 16:00:00 - 16:59:59 387 7.5% 0.111.4 MB 4.6% 0.41
Aug 3 17:00:00 - 17:59:59 325 6.3% 0.091.7 MB 5.3% 0.47
Aug 3 18:00:00 - 18:59:59 253 4.9% 0.071.2 MB 3.7% 0.33
Aug 3 19:00:00 - 19:59:59 289 5.6% 0.081.1 MB 3.5% 0.32
Aug 3 20:00:00 - 20:59:59 272 5.3% 0.081010.3 KB 3.1% 0.28
Aug 3 21:00:00 - 21:59:59 261 5.1% 0.071.1 MB 3.4% 0.30
Aug 3 22:00:00 - 22:59:59 232 4.5% 0.061.1 MB 3.5% 0.31
Aug 3 23:00:00 - 23:59:59 192 3.7% 0.05934.1 KB 2.9% 0.26
Aug 4 00:00:00 - 00:59:59 151 2.9% 0.04602.1 KB 1.9% 0.17
Aug 4 01:00:00 - 01:59:59 239 4.6% 0.0711.1 MB 35.0% 3.14
Aug 4 02:00:00 - 02:59:59 110 2.1% 0.03503.2 KB 1.6% 0.14
Aug 4 03:00:00 - 03:00:05 10 0.2% 2.0011.4 KB 0.0% 2.28
TOTAL: 24:00:01 5147 100.0% 0.0631.6 MB 100.0% 0.37
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1461 1250 0 0 0 0 0 0 211
2newsfeed.endofthelinebbs.com 714 104 0 0 0 0 0 0 610
3news.tebibyte.org 49 49 0 0 0 0 0 0 0
4news.sklaffkom.se 17 15 0 0 0 0 0 0 2
TOTAL: 4 2241 1418 0 0 0 0 0 0 823

Unwanted newsgroups [Top 20]:

NewsgroupCount
pl.soc.prawo 50
fido7.ukrnews 45
alt.food.fast-food 41
de.rec.film.misc 39
uk.legal.moderated 39
linux.debian.user.french 36
pl.misc.telefonia.gsm 35
hfx.general 34
it.hobby.cucina 31
pl.misc.elektronika 29
muc.lists.freebsd.bugs 28
de.etc.bahn.bahnpolitik 26
alt.checkmate 25
aus.politics 22
uk.comp.sys.mac 21
alt.bestjobsusa.computer.jobs 20
muc.lists.freebsd.fs 20
free.it.ai.general 19
linux.debian.maint.emacsen 19
pl.misc.samochody 19
TOTAL: 215 1418

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 622
EMP (phn path) 156
User-issued cancel 11
HTML Multipart 6
Too many newsgroups (meow) 6
TOTAL: 5 801

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 20
news.sklaffkom.se 2
TOTAL: 2 22
TOTAL: 1 22

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7023 1132 3987 5 0 6 16%23:59:56
2sklaffkom.se 5238 958 1791 2372 0 2 18%23:59:57
3newsfeed.endofthelinebbs.com 8394 56 4339 22 0 166 0%23:59:56
4weretis.net 903 0 852 0 0 0 0%22:48:13
TOTAL: 4 21558 2146 10969 2399 0 174 9%94:48:02
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.4 MB683.4 KB4.0 MB0.0 KB/s3.6 KB23:59:56
2sklaffkom.se1.6 MB13.2 MB14.8 MB0.2 KB/s4.5 KB23:59:57
3newsfeed.endofthelinebbs.com237.0 KB222.3 KB459.3 KB0.0 KB/s5.9 KB23:59:56
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:48:13
TOTAL: 45.2 MB14.1 MB19.3 MB0.1 KB/s4.3 KB94:48:02
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
146.172.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:01
2azpdsgbfmbi3.stretchoid.com 2 00.0 KB 0 0 000:00:10
3216.226.77.20 1 00.0 KB 0 0 000:00:00
4217.146.80.107 1 00.0 KB 0 0 000:00:00
531.14.254.124 1 00.0 KB 0 0 000:00:00
631.14.254.98 1 00.0 KB 0 0 000:00:00
769.5.169.142 1 00.0 KB 0 0 000:00:00
8ec2-54-241-113-90.us-west-1.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
9r3-186-ba.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 9 12 00.0 KB 0 0 000:00:15

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 5 00.0 KB 0 0 000:00:01
2*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:01
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
5*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 5 12 00.0 KB 0 0 000:00:15

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdsgbfmbi3.stretchoid.com 0.011 0.016 0.00000:00:10
r3-186-ba.monitoring.internet-measurement.com 0.003 0.011 0.00000:00:01
46.172.132.66.censys-scanner.com 0.030 0.024 0.00000:00:01
31.14.254.98 0.004 0.011 0.00000:00:00
216.226.77.20 0.004 0.012 0.00000:00:00
31.14.254.124 0.005 0.052 0.00000:00:00
ec2-54-241-113-90.us-west-1.compute.amazonaws.com 0.006 0.012 0.00000:00:00
69.5.169.142 0.004 0.014 0.00000:00:00
217.146.80.107 0.001 0.013 0.00000:00:00
TOTAL: 9 0.068 0.165 0.00000:00:15

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

SystemConn
46.172.132.66.censys-scanner.com 1
azpdsgbfmbi3.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
31.14.254.124 1 1
31.14.254.98 1 1
46.172.132.66.censys-scanner.com 1 2
TOTAL: 3 3 6
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