Daily Usenet report

Jul 6 03:00:06 -- Jul 7 03:00:06

Unknown entries from news log file:

First 50 / 1457 lines (3.4%)

Jul 6 03:01:25 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 6 03:34:51 twilightnode nnrpd[3316174]: Reading access from /usr/local/news/etc/readers.conf
Jul 6 03:34:51 twilightnode nnrpd[3316174]: Auth strategy 'localhost' does not match client. Removing.
Jul 6 03:34:51 twilightnode nnrpd[3316174]: azpdcg0jy5ic.stretchoid.com res <anonymous>
Jul 6 03:34:51 twilightnode nnrpd[3316174]: azpdcg0jy5ic.stretchoid.com no_match_user <anonymous> <localhost>
Jul 6 03:34:51 twilightnode nnrpd[3316174]: azpdcg0jy5ic.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 6 03:34:51 twilightnode nnrpd[3316174]: azpdcg0jy5ic.stretchoid.com match_user <anonymous> <anonymous>
Jul 6 03:34:51 twilightnode nnrpd[3316174]: azpdcg0jy5ic.stretchoid.com no_post anonymous-read
Jul 6 03:35:02 twilightnode nnrpd[3316208]: Reading access from /usr/local/news/etc/readers.conf
Jul 6 03:35:02 twilightnode nnrpd[3316208]: Auth strategy 'localhost' does not match client. Removing.
Jul 6 03:35:02 twilightnode nnrpd[3316208]: azpdcg0jy5ic.stretchoid.com res <anonymous>
Jul 6 03:35:02 twilightnode nnrpd[3316208]: azpdcg0jy5ic.stretchoid.com no_match_user <anonymous> <localhost>
Jul 6 03:35:02 twilightnode nnrpd[3316208]: azpdcg0jy5ic.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 6 03:35:02 twilightnode nnrpd[3316208]: azpdcg0jy5ic.stretchoid.com match_user <anonymous> <anonymous>
Jul 6 03:35:02 twilightnode nnrpd[3316208]: azpdcg0jy5ic.stretchoid.com no_post anonymous-read
Jul 6 04:30:29 twilightnode nnrpd[3318609]: Reading access from /usr/local/news/etc/readers.conf
Jul 6 04:30:29 twilightnode nnrpd[3318609]: Auth strategy 'localhost' does not match client. Removing.
Jul 6 04:30:29 twilightnode nnrpd[3318609]: 216.226.77.20 res <anonymous>
Jul 6 04:30:29 twilightnode nnrpd[3318609]: 216.226.77.20 no_match_user <anonymous> <localhost>
Jul 6 04:30:29 twilightnode nnrpd[3318609]: 216.226.77.20 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 6 04:30:29 twilightnode nnrpd[3318609]: 216.226.77.20 match_user <anonymous> <anonymous>
Jul 6 04:30:29 twilightnode nnrpd[3318609]: 216.226.77.20 no_post anonymous-read
Jul 6 07:27:31 twilightnode nnrpd[3325541]: Reading access from /usr/local/news/etc/readers.conf
Jul 6 07:27:31 twilightnode nnrpd[3325541]: Auth strategy 'localhost' does not match client. Removing.
Jul 6 07:27:31 twilightnode nnrpd[3325541]: 194.50.235.134 res <anonymous>
Jul 6 07:27:31 twilightnode nnrpd[3325541]: 194.50.235.134 no_match_user <anonymous> <localhost>
Jul 6 07:27:31 twilightnode nnrpd[3325541]: 194.50.235.134 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 6 07:27:31 twilightnode nnrpd[3325541]: 194.50.235.134 match_user <anonymous> <anonymous>
Jul 6 07:27:31 twilightnode nnrpd[3325541]: 194.50.235.134 no_post anonymous-read
Jul 6 07:27:38 twilightnode nnrpd[3325542]: Reading access from /usr/local/news/etc/readers.conf
Jul 6 07:27:38 twilightnode nnrpd[3325542]: Auth strategy 'localhost' does not match client. Removing.
Jul 6 07:27:38 twilightnode nnrpd[3325542]: 69.5.169.89 res <anonymous>
Jul 6 07:27:38 twilightnode nnrpd[3325542]: 69.5.169.89 no_match_user <anonymous> <localhost>
Jul 6 07:27:38 twilightnode nnrpd[3325542]: 69.5.169.89 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 6 07:27:38 twilightnode nnrpd[3325542]: 69.5.169.89 match_user <anonymous> <anonymous>
Jul 6 07:27:38 twilightnode nnrpd[3325542]: 69.5.169.89 no_post anonymous-read
Jul 6 09:49:19 twilightnode nnrpd[3331341]: Reading access from /usr/local/news/etc/readers.conf
Jul 6 09:49:19 twilightnode nnrpd[3331341]: Auth strategy 'localhost' does not match client. Removing.
Jul 6 09:49:19 twilightnode nnrpd[3331341]: o312.scanner.modat.io res <anonymous>
Jul 6 09:49:19 twilightnode nnrpd[3331341]: o312.scanner.modat.io no_match_user <anonymous> <localhost>
Jul 6 09:49:19 twilightnode nnrpd[3331341]: o312.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 6 09:49:19 twilightnode nnrpd[3331341]: o312.scanner.modat.io match_user <anonymous> <anonymous>
Jul 6 09:49:19 twilightnode nnrpd[3331341]: o312.scanner.modat.io no_post anonymous-read
Jul 6 10:01:17 twilightnode nnrpd[3332000]: Reading access from /usr/local/news/etc/readers.conf
Jul 6 10:01:17 twilightnode nnrpd[3332000]: Auth strategy 'localhost' does not match client. Removing.
Jul 6 10:01:17 twilightnode nnrpd[3332000]: o348.scanner.modat.io res <anonymous>
Jul 6 10:01:17 twilightnode nnrpd[3332000]: o348.scanner.modat.io no_match_user <anonymous> <localhost>
Jul 6 10:01:17 twilightnode nnrpd[3332000]: o348.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 6 10:01:17 twilightnode nnrpd[3332000]: o348.scanner.modat.io match_user <anonymous> <anonymous>
Jul 6 10:01:17 twilightnode nnrpd[3332000]: o348.scanner.modat.io no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 41205 80.0%5.6 MB 76.4%
inn 6818 13.2%1.2 MB 16.9%
nnrpd 1855 3.6%217.2 KB 2.9%
innd 1638 3.2%282.4 KB 3.8%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 51518 100.0%7.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 16140 50.8%
Negative hits 8832 27.8%
Do not exist 6796 21.4%
Cache misses 33 0.1%
TOTAL: 4 31801 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.002 0.0%1 2.000 2.000 2.000
article cleanup00:00:00.070 0.0%6811 0.000 0.010 0.162
article logging00:00:00.272 0.0%6826 0.000 0.040 0.182
article parse00:00:00.135 0.0%9783 0.000 0.014 0.100
article write00:00:04.792 0.0%4503 0.462 1.064 2.974
data move00:00:00.112 0.0%33228 0.000 0.003 0.052
hisgrep/artcncl00:00:00.001 0.0%1 1.000 1.000 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.968 0.0%31910 0.044 0.156 0.423
history sync00:00:00.063 0.0%412 0.000 0.153 1.000
history write00:00:03.570 0.0%6811 0.171 0.524 1.148
idle24:00:26.342100.0%32593 715.693 2651.684 9536.270
nntp read00:00:00.838 0.0%32045 0.000 0.026 0.083
overview write00:00:02.430 0.0%4503 0.188 0.540 3.167
perl filter00:00:11.467 0.0%6811 0.570 1.684 9.811
python filter00:00:01.042 0.0%6811 0.000 0.153 1.969
site send00:00:00.127 0.0%4503 0.000 0.028 0.188
TOTAL: 1724:00:56.231100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.036 0.0%10357 0.000 0.003 0.062
article prepare00:00:00.001 0.0%8668 0.000 0.000 0.010
article read00:00:00.526 0.0%2167 0.000 0.243 1.667
backlog stats00:00:00.078 0.0%63961 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.493 0.0%28859 0.000 0.017 0.060
data write00:00:00.736 0.0%24475 0.000 0.030 0.088
idle23:59:13.580100.0%63961 938.778 1350.097 1767.368
status file00:00:10.347 0.0%17049 0.467 0.607 1.017
TOTAL: 923:59:25.797100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:12.539100.0%131 6.000 553.733 10924.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.012 0.0%388 0.000 0.031 0.667
TOTAL: 300:01:12.551100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 6 11383 3804 5934 1645 33%47:43:10
2newsfeed.endofthelinebbs.com 2 4780 528 3876 376 11%24:15:05
3news.sklaffkom.se 41 208 99 58 51 47%15:57:39
4news.tebibyte.org 1 10535 88 10248 199 0%40:22:56
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 75 26906 4519 20116 2271 16%128:18:50
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.2 MB0.0 KB4.7 MB15.9 MB 70%3.0 KB
2newsfeed.endofthelinebbs.com6.2 MB0.0 KB702.5 KB6.9 MB 89%7.8 KB
3news.sklaffkom.se411.2 KB0.0 KB109.9 KB521.1 KB 78%3.5 KB
4news.tebibyte.org392.7 KB0.0 KB580.6 KB973.3 KB 40%3.4 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 518.2 MB0.0 KB6.1 MB24.2 MB 74%3.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 6 03:00:06 - 03:59:59 100 2.2% 0.03359.7 KB 1.9% 0.10
Jul 6 04:00:00 - 04:59:59 108 2.4% 0.03443.1 KB 2.4% 0.12
Jul 6 05:00:00 - 05:59:59 118 2.6% 0.03368.7 KB 2.0% 0.10
Jul 6 06:00:00 - 06:59:59 154 3.4% 0.04549.0 KB 2.9% 0.15
Jul 6 07:00:00 - 07:59:59 163 3.6% 0.051.3 MB 7.1% 0.37
Jul 6 08:00:00 - 08:59:59 190 4.2% 0.05763.1 KB 4.1% 0.21
Jul 6 09:00:00 - 09:59:59 221 4.9% 0.06791.4 KB 4.2% 0.22
Jul 6 10:00:00 - 10:59:59 250 5.6% 0.07749.0 KB 4.0% 0.21
Jul 6 11:00:00 - 11:59:59 183 4.1% 0.05641.2 KB 3.4% 0.18
Jul 6 12:00:00 - 12:59:59 220 4.9% 0.06950.2 KB 5.1% 0.26
Jul 6 13:00:00 - 13:59:59 187 4.2% 0.05735.1 KB 3.9% 0.20
Jul 6 14:00:00 - 14:59:59 179 4.0% 0.05552.7 KB 3.0% 0.15
Jul 6 15:00:00 - 15:59:59 296 6.6% 0.081.2 MB 6.5% 0.34
Jul 6 16:00:00 - 16:59:59 240 5.3% 0.071.3 MB 7.0% 0.36
Jul 6 17:00:00 - 17:59:59 267 5.9% 0.071.1 MB 6.2% 0.32
Jul 6 18:00:00 - 18:59:59 225 5.0% 0.06817.1 KB 4.4% 0.23
Jul 6 19:00:00 - 19:59:59 206 4.6% 0.06802.6 KB 4.3% 0.22
Jul 6 20:00:00 - 20:59:59 235 5.2% 0.07836.0 KB 4.5% 0.23
Jul 6 21:00:00 - 21:59:59 242 5.4% 0.071.5 MB 8.1% 0.42
Jul 6 22:00:00 - 22:59:59 168 3.7% 0.05662.0 KB 3.5% 0.18
Jul 6 23:00:00 - 23:59:59 182 4.0% 0.05733.5 KB 3.9% 0.20
Jul 7 00:00:00 - 00:59:59 149 3.3% 0.04629.9 KB 3.4% 0.17
Jul 7 01:00:00 - 01:59:59 100 2.2% 0.03419.4 KB 2.2% 0.12
Jul 7 02:00:00 - 02:59:59 102 2.3% 0.03374.6 KB 2.0% 0.10
Jul 7 03:00:00 - 03:00:06 9 0.2% 1.507.3 KB 0.0% 1.22
TOTAL: 24:00:00 4494 100.0% 0.0518.3 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1678 1293 0 0 0 0 0 0 385
2newsfeed.endofthelinebbs.com 389 75 0 0 0 0 0 0 314
3news.tebibyte.org 205 31 0 0 0 0 0 0 174
4news.sklaffkom.se 52 52 0 0 0 0 0 0 0
TOTAL: 4 2324 1451 0 0 0 0 0 0 873

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 93
fido7.ru.news 76
pl.soc.prawo 71
alt.checkmate 42
pl.misc.elektronika 42
linux.debian.user 41
eternal-september.talk 34
de.alt.fan.aldi 30
it.discussioni.auto 30
muc.lists.freebsd.bugs 29
de.soc.recht.misc 25
fido7.ukrnews 25
de.talk.jokes 24
fr.sci.maths 23
alt.bestjobsusa.computer.jobs 22
alt.prophecies.nostradamus 18
alt.slack 18
dk.politik 18
it-alt.sport.calcio.juventus 18
lada.talk 18
TOTAL: 226 1451

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 477
EMP (md5) 362
User-issued cancel 8
Too many newsgroups 5
Too many newsgroups (meow) 5
HTML Multipart 1
TOTAL: 6 858

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 4781 723 2317 1444 0 256 15%23:59:54
2news.tebibyte.org 2836 0 0 0 0 7320 0%23:59:54
3newsfeed.endofthelinebbs.com 2781 0 0 0 0 6730 0%23:59:54
4weretis.net 734 0 691 0 0 1 0%22:10:36
5chimia.se 0 0 0 0 0 4494 0%23:59:54
6sklaff.dev 0 0 0 0 0 4484 0%23:58:05
7thebunker.nu 0 0 0 0 0 4494 0%23:59:54
8usenet.blueworldhosting.com 0 0 0 0 0 4191 0%23:59:54
TOTAL: 8 11132 723 3008 1444 0 31970 6%190:08:05
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1sklaffkom.se1.2 MB9.6 MB10.7 MB0.1 KB/s5.1 KB23:59:54
2chimia.se0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
4newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
5sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:05
6thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
7usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
8weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:10:36
TOTAL: 81.2 MB9.6 MB10.7 MB0.0 KB/s5.1 KB190:08:05
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1172-235-235-248.ip.linodeusercontent.com 60 00.0 KB 0 0 000:00:25
2prod52client01.academyforinternetresearch.org 60 00.0 KB 0 0 000:00:32
3azpdcg0jy5ic.stretchoid.com 2 00.0 KB 0 0 000:00:11
4194.50.235.134 1 00.0 KB 0 0 000:00:00
5216.226.77.20 1 00.0 KB 0 0 000:00:00
669.5.169.89 1 00.0 KB 0 0 000:00:00
7o312.scanner.modat.io 1 00.0 KB 0 0 000:00:00
8o348.scanner.modat.io 1 00.0 KB 0 0 000:00:00
9r4-50-32.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 9 128 00.0 KB 0 0 000:01:12

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.academyforinternetresearch.org 60 00.0 KB 0 0 000:00:32
2*.ip.linodeusercontent.com 60 00.0 KB 0 0 000:00:25
3unresolved 3 00.0 KB 0 0 000:00:01
4*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
5*.stretchoid.com 2 00.0 KB 0 0 000:00:11
6*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 6 128 00.0 KB 0 0 000:01:12

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
prod52client01.academyforinternetresearch.org 1.468 0.523 0.00000:00:32
172-235-235-248.ip.linodeusercontent.com 1.516 0.506 0.00000:00:25
azpdcg0jy5ic.stretchoid.com 0.011 0.022 0.00000:00:11
r4-50-32.monitoring.internet-measurement.com 0.005 0.014 0.00000:00:01
69.5.169.89 0.002 0.010 0.00000:00:00
194.50.235.134 0.001 0.016 0.00000:00:00
o312.scanner.modat.io 0.003 0.018 0.00000:00:00
216.226.77.20 0.005 0.008 0.00000:00:00
o348.scanner.modat.io 0.004 0.016 0.00000:00:00
TOTAL: 9 3.015 1.133 0.00000:01:12

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

SystemConn
azpdcg0jy5ic.stretchoid.com 1
TOTAL: 1 1

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

CommandCount
MGLNDD_172.234.115.63_119 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
172-235-235-248.ip.linodeusercontent.com 1 1
69.5.169.89 1 1
prod52client01.academyforinternetresearch.org 1 1
TOTAL: 3 3 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