Daily Usenet report

Jul 19 03:00:05 -- Jul 20 03:00:04

Unknown entries from news log file:

First 50 / 62 lines (80.6%)

Jul 19 03:01:26 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 19 07:14:44 twilightnode nnrpd[231634]: Reading access from /usr/local/news/etc/readers.conf
Jul 19 07:14:44 twilightnode nnrpd[231634]: Auth strategy 'localhost' does not match client. Removing.
Jul 19 07:14:44 twilightnode nnrpd[231634]: 213.166.84.35 res <anonymous>
Jul 19 07:14:44 twilightnode nnrpd[231634]: 213.166.84.35 no_match_user <anonymous> <localhost>
Jul 19 07:14:44 twilightnode nnrpd[231634]: 213.166.84.35 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 19 07:14:44 twilightnode nnrpd[231634]: 213.166.84.35 match_user <anonymous> <anonymous>
Jul 19 07:14:44 twilightnode nnrpd[231634]: 213.166.84.35 no_post anonymous-read
Jul 19 07:14:52 twilightnode nnrpd[231635]: Reading access from /usr/local/news/etc/readers.conf
Jul 19 07:14:52 twilightnode nnrpd[231635]: Auth strategy 'localhost' does not match client. Removing.
Jul 19 07:14:52 twilightnode nnrpd[231635]: 31.14.254.93 res <anonymous>
Jul 19 07:14:52 twilightnode nnrpd[231635]: 31.14.254.93 no_match_user <anonymous> <localhost>
Jul 19 07:14:52 twilightnode nnrpd[231635]: 31.14.254.93 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 19 07:14:52 twilightnode nnrpd[231635]: 31.14.254.93 match_user <anonymous> <anonymous>
Jul 19 07:14:52 twilightnode nnrpd[231635]: 31.14.254.93 no_post anonymous-read
Jul 19 08:44:44 twilightnode nnrpd[235509]: Reading access from /usr/local/news/etc/readers.conf
Jul 19 08:44:44 twilightnode nnrpd[235509]: Auth strategy 'localhost' does not match client. Removing.
Jul 19 08:44:44 twilightnode nnrpd[235509]: soda.census.shodan.io res <anonymous>
Jul 19 08:44:44 twilightnode nnrpd[235509]: soda.census.shodan.io no_match_user <anonymous> <localhost>
Jul 19 08:44:44 twilightnode nnrpd[235509]: soda.census.shodan.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 19 08:44:44 twilightnode nnrpd[235509]: soda.census.shodan.io match_user <anonymous> <anonymous>
Jul 19 08:44:44 twilightnode nnrpd[235509]: soda.census.shodan.io no_post anonymous-read
Jul 19 12:58:49 twilightnode nnrpd[246274]: Reading access from /usr/local/news/etc/readers.conf
Jul 19 12:58:49 twilightnode nnrpd[246274]: Auth strategy 'localhost' does not match client. Removing.
Jul 19 12:58:49 twilightnode nnrpd[246274]: azpdesx325il.stretchoid.com res <anonymous>
Jul 19 12:58:49 twilightnode nnrpd[246274]: azpdesx325il.stretchoid.com no_match_user <anonymous> <localhost>
Jul 19 12:58:49 twilightnode nnrpd[246274]: azpdesx325il.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 19 12:58:49 twilightnode nnrpd[246274]: azpdesx325il.stretchoid.com match_user <anonymous> <anonymous>
Jul 19 12:58:49 twilightnode nnrpd[246274]: azpdesx325il.stretchoid.com no_post anonymous-read
Jul 19 12:58:59 twilightnode nnrpd[246277]: Reading access from /usr/local/news/etc/readers.conf
Jul 19 12:58:59 twilightnode nnrpd[246277]: Auth strategy 'localhost' does not match client. Removing.
Jul 19 12:58:59 twilightnode nnrpd[246277]: azpdesx325il.stretchoid.com res <anonymous>
Jul 19 12:58:59 twilightnode nnrpd[246277]: azpdesx325il.stretchoid.com no_match_user <anonymous> <localhost>
Jul 19 12:58:59 twilightnode nnrpd[246277]: azpdesx325il.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 19 12:58:59 twilightnode nnrpd[246277]: azpdesx325il.stretchoid.com match_user <anonymous> <anonymous>
Jul 19 12:58:59 twilightnode nnrpd[246277]: azpdesx325il.stretchoid.com no_post anonymous-read
Jul 19 20:08:22 twilightnode nnrpd[264555]: Reading access from /usr/local/news/etc/readers.conf
Jul 19 20:08:22 twilightnode nnrpd[264555]: Auth strategy 'localhost' does not match client. Removing.
Jul 19 20:08:22 twilightnode nnrpd[264555]: 36.172.132.66.censys-scanner.com res <anonymous>
Jul 19 20:08:22 twilightnode nnrpd[264555]: 36.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 19 20:08:22 twilightnode nnrpd[264555]: 36.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 19 20:08:22 twilightnode nnrpd[264555]: 36.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 19 20:08:22 twilightnode nnrpd[264555]: 36.172.132.66.censys-scanner.com no_post anonymous-read
Jul 19 20:08:25 twilightnode nnrpd[264556]: Reading access from /usr/local/news/etc/readers.conf
Jul 19 20:08:25 twilightnode nnrpd[264556]: Auth strategy 'localhost' does not match client. Removing.
Jul 19 20:08:25 twilightnode nnrpd[264556]: 36.172.132.66.censys-scanner.com res <anonymous>
Jul 19 20:08:25 twilightnode nnrpd[264556]: 36.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 19 20:08:25 twilightnode nnrpd[264556]: 36.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 19 20:08:25 twilightnode nnrpd[264556]: 36.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 19 20:08:25 twilightnode nnrpd[264556]: 36.172.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 5361 68.6%814.2 KB 68.9%
innfeed 1290 16.5%195.5 KB 16.5%
innd 1062 13.6%161.6 KB 13.7%
nnrpd 98 1.3%10.4 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 7812 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12222 47.8%
Negative hits 8041 31.4%
Do not exist 5327 20.8%
Cache misses 3 0.0%
TOTAL: 4 25593 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.011 0.0%7 0.000 1.571 5.000
article cleanup00:00:00.058 0.0%5361 0.000 0.011 0.074
article logging00:00:00.240 0.0%5365 0.000 0.045 0.296
article parse00:00:00.109 0.0%8109 0.000 0.013 0.083
article write00:00:04.064 0.0%4015 0.436 1.012 2.500
data move00:00:00.133 0.0%28949 0.000 0.005 0.102
hisgrep/artcncl00:00:00.001 0.0%7 0.000 0.143 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.299 0.0%25746 0.078 0.167 0.420
history sync00:00:00.052 0.0%408 0.000 0.127 0.667
history write00:00:03.004 0.0%5361 0.211 0.560 1.115
idle24:00:18.721100.0%28494 1102.599 3032.874 16221.649
nntp read00:00:00.653 0.0%28180 0.000 0.023 0.131
overview write00:00:02.470 0.0%4015 0.136 0.615 3.375
perl filter00:00:09.411 0.0%5361 0.865 1.755 9.667
python filter00:00:00.660 0.0%5361 0.000 0.123 0.700
site send00:00:00.135 0.0%4010 0.000 0.034 0.194
TOTAL: 1724:00:44.021100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.010 0.0%4117 0.000 0.002 0.100
article prepare00:00:00.002 0.0%10896 0.000 0.000 0.025
article read00:00:00.496 0.0%1998 0.000 0.248 2.600
backlog stats00:00:00.047 0.0%45093 0.000 0.001 0.010
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.363 0.0%23759 0.000 0.015 0.074
data write00:00:00.664 0.0%19593 0.000 0.034 0.104
idle23:50:51.570100.0%45093 467.370 1903.878 6462.882
status file00:00:00.163 0.0%262 0.000 0.622 4.000
TOTAL: 923:50:53.315100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:15.587100.0%12 44.000 1298.917 10289.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%82 0.000 0.012 0.059
TOTAL: 300:00:15.588100.0%----

Control commands to innd:

CommandNumber
flush 2
flushlogs 2
go 2
logmode 2
lowmark 1
mode 146
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 165

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 3 9151 3609 4670 872 39%47:08:46
2newsfeed.endofthelinebbs.com 2 3535 199 2985 351 5%22:10:39
3news.tebibyte.org 1 4324 47 4237 40 1%23:59:58
4news.sklaffkom.se 28 58 14 11 33 24%06:37:39
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 59 17068 3869 11903 1296 22%99:57:02
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.8 MB0.0 KB2.7 MB14.5 MB 81%3.3 KB
2newsfeed.endofthelinebbs.com2.5 MB0.0 KB2.6 MB5.1 MB 48%9.5 KB
3news.tebibyte.org208.0 KB0.0 KB108.4 KB316.4 KB 65%3.6 KB
4news.sklaffkom.se35.2 KB0.0 KB73.6 KB108.9 KB 32%2.3 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 514.6 MB0.0 KB5.5 MB20.1 MB 72%4.0 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 19 03:00:05 - 03:59:59 113 2.8% 0.03810.7 KB 4.8% 0.23
Jul 19 04:00:00 - 04:59:59 98 2.4% 0.03333.7 KB 2.0% 0.09
Jul 19 05:00:00 - 05:59:59 89 2.2% 0.02290.3 KB 1.7% 0.08
Jul 19 06:00:00 - 06:59:59 82 2.0% 0.02178.9 KB 1.1% 0.05
Jul 19 07:00:00 - 07:59:59 129 3.2% 0.04559.4 KB 3.3% 0.16
Jul 19 08:00:00 - 08:59:59 198 4.9% 0.06629.8 KB 3.8% 0.17
Jul 19 09:00:00 - 09:59:59 136 3.4% 0.04452.9 KB 2.7% 0.13
Jul 19 10:00:00 - 10:59:59 159 4.0% 0.04330.4 KB 2.0% 0.09
Jul 19 11:00:00 - 11:59:59 189 4.7% 0.05612.3 KB 3.7% 0.17
Jul 19 12:00:00 - 12:59:59 178 4.4% 0.05710.4 KB 4.2% 0.20
Jul 19 13:00:00 - 13:59:59 207 5.2% 0.06571.9 KB 3.4% 0.16
Jul 19 14:00:00 - 14:59:59 254 6.3% 0.071.5 MB 9.3% 0.43
Jul 19 15:00:00 - 15:59:59 213 5.3% 0.06689.6 KB 4.1% 0.19
Jul 19 16:00:00 - 16:59:59 156 3.9% 0.04532.5 KB 3.2% 0.15
Jul 19 17:00:00 - 17:59:59 211 5.3% 0.06897.2 KB 5.4% 0.25
Jul 19 18:00:00 - 18:59:59 217 5.4% 0.061.1 MB 6.6% 0.31
Jul 19 19:00:00 - 19:59:59 201 5.0% 0.06620.8 KB 3.7% 0.17
Jul 19 20:00:00 - 20:59:59 264 6.6% 0.072.5 MB 15.0% 0.70
Jul 19 21:00:00 - 21:59:59 208 5.2% 0.06812.8 KB 4.9% 0.23
Jul 19 22:00:00 - 22:59:59 172 4.3% 0.05669.7 KB 4.0% 0.19
Jul 19 23:00:00 - 23:59:59 160 4.0% 0.04751.7 KB 4.5% 0.21
Jul 20 00:00:00 - 00:59:59 178 4.4% 0.05469.5 KB 2.8% 0.13
Jul 20 01:00:00 - 01:59:59 104 2.6% 0.03389.9 KB 2.3% 0.11
Jul 20 02:00:00 - 02:59:59 81 2.0% 0.02221.7 KB 1.3% 0.06
Jul 20 03:00:00 - 03:00:04 11 0.3% 2.7515.3 KB 0.1% 3.83
TOTAL: 23:59:59 4008 100.0% 0.0516.3 MB 100.0% 0.19
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 894 818 0 0 0 0 0 0 76
2newsfeed.endofthelinebbs.com 393 111 0 0 0 0 0 0 282
3news.sklaffkom.se 33 33 0 0 0 0 0 0 0
4news.tebibyte.org 33 32 0 0 0 0 0 0 1
TOTAL: 4 1353 994 0 0 0 0 0 0 359

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.checkmate 47
uk.legal.moderated 45
alt.usage.english 44
alt.recovery.aa 37
fido7.ukrnews 32
lada.talk 24
pl.misc.samochody 24
pl.soc.prawo 23
de.etc.haushalt 22
de.soc.recht.misc 21
alt.prophecies.nostradamus 19
cn.comp 18
de.talk.jokes 18
eternal-september.talk 18
de.alt.fan.pluesch 15
muc.lists.freebsd.bugs 15
hfx.general 14
linux.debian.kernel 14
pl.soc.polityka 14
aus.politics 13
TOTAL: 196 994

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 318
User-issued cancel 27
Too many newsgroups (meow) 7
HTML Multipart 3
TOTAL: 4 355

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 5853 750 3250 2 0 10 12%23:58:25
2sklaffkom.se 4457 667 2038 1297 0 16 14%23:59:56
3newsfeed.endofthelinebbs.com 6979 9 3740 5 0 113 0%23:59:37
4weretis.net 370 0 368 0 0 0 0%19:25:17
TOTAL: 4 17659 1426 9396 1304 0 139 8%91:23:15
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.5 MB108.0 KB2.6 MB0.0 KB/s3.6 KB23:58:25
2sklaffkom.se1.1 MB7.6 MB8.7 MB0.1 KB/s4.5 KB23:59:56
3newsfeed.endofthelinebbs.com29.1 KB29.0 KB58.1 KB0.0 KB/s4.1 KB23:59:37
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s019:25:17
TOTAL: 43.6 MB7.8 MB11.4 MB0.0 KB/s4.3 KB91:23:15
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
136.172.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:03
2azpdesx325il.stretchoid.com 2 00.0 KB 0 0 000:00:10
3213.166.84.35 1 00.0 KB 0 0 000:00:00
431.14.254.93 1 00.0 KB 0 0 000:00:00
5soda.census.shodan.io 1 00.0 KB 0 0 000:00:00
TOTAL: 5 8 00.0 KB 0 0 000:00:15

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:03
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3unresolved 2 00.0 KB 0 0 000:00:00
4*.census.shodan.io 1 00.0 KB 0 0 000:00:00
TOTAL: 4 8 00.0 KB 0 0 000:00:15

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdesx325il.stretchoid.com 0.008 0.016 0.00000:00:10
36.172.132.66.censys-scanner.com 0.056 0.025 0.00000:00:03
soda.census.shodan.io 0.004 0.009 0.00000:00:00
31.14.254.93 0.001 0.009 0.00000:00:00
213.166.84.35 0.004 0.011 0.00000:00:00
TOTAL: 5 0.073 0.070 0.00000:00:15

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

SystemConn
36.172.132.66.censys-scanner.com 1
azpdesx325il.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.93 1 1
36.172.132.66.censys-scanner.com 1 2
TOTAL: 2 2 4
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