Daily Usenet report

Jul 18 03:00:05 -- Jul 19 03:00:05

Unknown entries from news log file:

First 50 / 62 lines (80.6%)

Jul 18 03:01:23 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 18 06:01:25 twilightnode nnrpd[166695]: Reading access from /usr/local/news/etc/readers.conf
Jul 18 06:01:25 twilightnode nnrpd[166695]: Auth strategy 'localhost' does not match client. Removing.
Jul 18 06:01:25 twilightnode nnrpd[166695]: azpdesnc4dwz.stretchoid.com res <anonymous>
Jul 18 06:01:25 twilightnode nnrpd[166695]: azpdesnc4dwz.stretchoid.com no_match_user <anonymous> <localhost>
Jul 18 06:01:25 twilightnode nnrpd[166695]: azpdesnc4dwz.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 18 06:01:25 twilightnode nnrpd[166695]: azpdesnc4dwz.stretchoid.com match_user <anonymous> <anonymous>
Jul 18 06:01:25 twilightnode nnrpd[166695]: azpdesnc4dwz.stretchoid.com no_post anonymous-read
Jul 18 06:01:35 twilightnode nnrpd[166696]: Reading access from /usr/local/news/etc/readers.conf
Jul 18 06:01:35 twilightnode nnrpd[166696]: Auth strategy 'localhost' does not match client. Removing.
Jul 18 06:01:35 twilightnode nnrpd[166696]: azpdesnc4dwz.stretchoid.com res <anonymous>
Jul 18 06:01:35 twilightnode nnrpd[166696]: azpdesnc4dwz.stretchoid.com no_match_user <anonymous> <localhost>
Jul 18 06:01:35 twilightnode nnrpd[166696]: azpdesnc4dwz.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 18 06:01:35 twilightnode nnrpd[166696]: azpdesnc4dwz.stretchoid.com match_user <anonymous> <anonymous>
Jul 18 06:01:35 twilightnode nnrpd[166696]: azpdesnc4dwz.stretchoid.com no_post anonymous-read
Jul 18 07:18:09 twilightnode nnrpd[169888]: Reading access from /usr/local/news/etc/readers.conf
Jul 18 07:18:09 twilightnode nnrpd[169888]: Auth strategy 'localhost' does not match client. Removing.
Jul 18 07:18:09 twilightnode nnrpd[169888]: 69.5.169.251 res <anonymous>
Jul 18 07:18:09 twilightnode nnrpd[169888]: 69.5.169.251 no_match_user <anonymous> <localhost>
Jul 18 07:18:09 twilightnode nnrpd[169888]: 69.5.169.251 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 18 07:18:09 twilightnode nnrpd[169888]: 69.5.169.251 match_user <anonymous> <anonymous>
Jul 18 07:18:09 twilightnode nnrpd[169888]: 69.5.169.251 no_post anonymous-read
Jul 18 07:18:15 twilightnode nnrpd[169889]: Reading access from /usr/local/news/etc/readers.conf
Jul 18 07:18:15 twilightnode nnrpd[169889]: Auth strategy 'localhost' does not match client. Removing.
Jul 18 07:18:15 twilightnode nnrpd[169889]: 195.206.182.218 res <anonymous>
Jul 18 07:18:15 twilightnode nnrpd[169889]: 195.206.182.218 no_match_user <anonymous> <localhost>
Jul 18 07:18:15 twilightnode nnrpd[169889]: 195.206.182.218 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 18 07:18:15 twilightnode nnrpd[169889]: 195.206.182.218 match_user <anonymous> <anonymous>
Jul 18 07:18:15 twilightnode nnrpd[169889]: 195.206.182.218 no_post anonymous-read
Jul 18 19:11:26 twilightnode nnrpd[200309]: Reading access from /usr/local/news/etc/readers.conf
Jul 18 19:11:26 twilightnode nnrpd[200309]: Auth strategy 'localhost' does not match client. Removing.
Jul 18 19:11:26 twilightnode nnrpd[200309]: r5-26-1a.monitoring.internet-measurement.com res <anonymous>
Jul 18 19:11:26 twilightnode nnrpd[200309]: r5-26-1a.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Jul 18 19:11:26 twilightnode nnrpd[200309]: r5-26-1a.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 18 19:11:26 twilightnode nnrpd[200309]: r5-26-1a.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Jul 18 19:11:26 twilightnode nnrpd[200309]: r5-26-1a.monitoring.internet-measurement.com no_post anonymous-read
Jul 18 22:09:12 twilightnode nnrpd[207658]: Reading access from /usr/local/news/etc/readers.conf
Jul 18 22:09:12 twilightnode nnrpd[207658]: Auth strategy 'localhost' does not match client. Removing.
Jul 18 22:09:12 twilightnode nnrpd[207658]: 198.235.24.67 res <anonymous>
Jul 18 22:09:12 twilightnode nnrpd[207658]: 198.235.24.67 no_match_user <anonymous> <localhost>
Jul 18 22:09:12 twilightnode nnrpd[207658]: 198.235.24.67 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 18 22:09:12 twilightnode nnrpd[207658]: 198.235.24.67 match_user <anonymous> <anonymous>
Jul 18 22:09:12 twilightnode nnrpd[207658]: 198.235.24.67 no_post anonymous-read
Jul 18 23:33:03 twilightnode nnrpd[211331]: Reading access from /usr/local/news/etc/readers.conf
Jul 18 23:33:03 twilightnode nnrpd[211331]: Auth strategy 'localhost' does not match client. Removing.
Jul 18 23:33:03 twilightnode nnrpd[211331]: 162.186.132.66.censys-scanner.com res <anonymous>
Jul 18 23:33:03 twilightnode nnrpd[211331]: 162.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 18 23:33:03 twilightnode nnrpd[211331]: 162.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 18 23:33:03 twilightnode nnrpd[211331]: 162.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 18 23:33:03 twilightnode nnrpd[211331]: 162.186.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 5443 66.1%823.9 KB 66.8%
innfeed 1403 17.0%203.7 KB 16.5%
innd 1294 15.7%195.4 KB 15.8%
nnrpd 97 1.2%10.0 KB 0.8%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 8238 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12204 48.0%
Negative hits 7809 30.7%
Do not exist 5396 21.2%
Cache misses 1 0.0%
TOTAL: 4 25410 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.013 0.0%8 1.000 1.625 3.000
article cleanup00:00:00.039 0.0%5423 0.000 0.007 0.118
article logging00:00:00.200 0.0%5437 0.000 0.037 0.208
article parse00:00:00.079 0.0%7059 0.000 0.011 0.176
article write00:00:03.774 0.0%3733 0.528 1.011 3.619
data move00:00:00.048 0.0%27350 0.000 0.002 0.023
hisgrep/artcncl00:00:00.004 0.0%8 0.000 0.500 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.051 0.0%25544 0.050 0.159 0.550
history sync00:00:00.042 0.0%336 0.000 0.125 1.000
history write00:00:03.013 0.0%5423 0.250 0.556 1.071
idle24:00:16.307100.0%27182 1588.008 3179.174 14301.857
nntp read00:00:00.540 0.0%26883 0.000 0.020 0.090
overview write00:00:02.309 0.0%3733 0.208 0.619 2.867
perl filter00:00:08.510 0.0%5423 0.871 1.569 17.562
python filter00:00:00.577 0.0%5423 0.000 0.106 1.500
site send00:00:00.101 0.0%3719 0.000 0.027 0.222
TOTAL: 1724:00:39.607100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.019 0.0%3823 0.000 0.005 0.143
article prepare00:00:00.001 0.0%11044 0.000 0.000 0.010
article read00:00:00.473 0.0%1985 0.000 0.238 1.800
backlog stats00:00:00.033 0.0%43114 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.325 0.0%22504 0.000 0.014 0.068
data write00:00:00.636 0.0%18561 0.000 0.034 0.098
idle23:49:58.731100.0%43114 433.154 1990.043 6913.264
status file00:00:00.189 0.0%326 0.000 0.580 3.000
TOTAL: 923:50:00.407100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:16.320100.0%11 58.500 1483.636 10240.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%36 0.000 0.056 0.500
TOTAL: 300:00:16.322100.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 4 9515 3520 4972 1023 36%48:23:35
2newsfeed.endofthelinebbs.com 1 3613 144 2912 557 3%21:26:28
3news.tebibyte.org 1 2141 52 2067 22 2%12:05:19
4news.sklaffkom.se 45 109 21 22 66 19%11:07:02
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 76 15378 3737 9973 1668 24%93:02:24
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net9.9 MB0.0 KB3.1 MB13.0 MB 76%2.9 KB
2newsfeed.endofthelinebbs.com1.6 MB0.0 KB2.2 MB3.8 MB 41%5.5 KB
3news.tebibyte.org205.9 KB0.0 KB67.2 KB273.1 KB 75%3.7 KB
4news.sklaffkom.se43.0 KB0.0 KB128.0 KB171.0 KB 25%2.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 511.7 MB0.0 KB5.5 MB17.2 MB 67%3.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 18 03:00:05 - 03:59:59 112 3.0% 0.03367.1 KB 3.1% 0.10
Jul 18 04:00:00 - 04:59:59 118 3.2% 0.03428.2 KB 3.6% 0.12
Jul 18 05:00:00 - 05:59:59 105 2.8% 0.03306.5 KB 2.6% 0.09
Jul 18 06:00:00 - 06:59:59 74 2.0% 0.02214.6 KB 1.8% 0.06
Jul 18 07:00:00 - 07:59:59 127 3.4% 0.04363.6 KB 3.0% 0.10
Jul 18 08:00:00 - 08:59:59 158 4.2% 0.04658.7 KB 5.5% 0.18
Jul 18 09:00:00 - 09:59:59 188 5.0% 0.05558.0 KB 4.7% 0.16
Jul 18 10:00:00 - 10:59:59 161 4.3% 0.04413.7 KB 3.5% 0.11
Jul 18 11:00:00 - 11:59:59 195 5.2% 0.05632.3 KB 5.3% 0.18
Jul 18 12:00:00 - 12:59:59 167 4.5% 0.05448.0 KB 3.8% 0.12
Jul 18 13:00:00 - 13:59:59 158 4.2% 0.04361.3 KB 3.0% 0.10
Jul 18 14:00:00 - 14:59:59 199 5.3% 0.06657.2 KB 5.5% 0.18
Jul 18 15:00:00 - 15:59:59 228 6.1% 0.06606.1 KB 5.1% 0.17
Jul 18 16:00:00 - 16:59:59 200 5.4% 0.06546.9 KB 4.6% 0.15
Jul 18 17:00:00 - 17:59:59 190 5.1% 0.05635.3 KB 5.3% 0.18
Jul 18 18:00:00 - 18:59:59 216 5.8% 0.06734.4 KB 6.2% 0.20
Jul 18 19:00:00 - 19:59:59 129 3.5% 0.04458.2 KB 3.8% 0.13
Jul 18 20:00:00 - 20:59:59 188 5.0% 0.05505.9 KB 4.2% 0.14
Jul 18 21:00:00 - 21:59:59 202 5.4% 0.06667.1 KB 5.6% 0.19
Jul 18 22:00:00 - 22:59:59 141 3.8% 0.04491.5 KB 4.1% 0.14
Jul 18 23:00:00 - 23:59:59 166 4.4% 0.05811.8 KB 6.8% 0.23
Jul 19 00:00:00 - 00:59:59 112 3.0% 0.03346.6 KB 2.9% 0.10
Jul 19 01:00:00 - 01:59:59 93 2.5% 0.03369.1 KB 3.1% 0.10
Jul 19 02:00:00 - 02:59:59 101 2.7% 0.03334.5 KB 2.8% 0.09
Jul 19 03:00:00 - 03:00:05 10 0.3% 2.0011.9 KB 0.1% 2.39
TOTAL: 24:00:00 3738 100.0% 0.0411.6 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1016 968 0 0 0 0 0 0 48
2newsfeed.endofthelinebbs.com 582 95 0 0 0 0 0 0 487
3news.sklaffkom.se 66 66 0 0 0 0 0 0 0
4news.tebibyte.org 41 41 0 0 0 0 0 0 0
TOTAL: 4 1705 1170 0 0 0 0 0 0 535

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 34
uk.legal.moderated 33
de.talk.jokes 31
alt.checkmate 29
pl.misc.samochody 29
eternal-september.talk 27
muc.lists.freebsd.doc 27
pl.soc.polityka 26
de.soc.recht.misc 24
eternal-september.test 24
muc.lists.freebsd.bugs 24
alt.recovery.aa 21
alt.slack 21
cn.comp 20
de.alt.comm.android 20
de.soc.umwelt 20
pl.soc.prawo 20
uk.tech.digital-tv 20
eternal-september.support 17
lada.talk 16
TOTAL: 213 1170

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 491
User-issued cancel 25
EMP (phl) 2
Too many newsgroups (meow) 2
HTML Multipart 1
TOTAL: 5 521

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 5393 797 2923 2 0 9 14%23:59:52
2sklaffkom.se 4055 768 1753 1204 0 1 18%23:59:56
3newsfeed.endofthelinebbs.com 6869 12 3546 4 0 125 0%23:59:55
4weretis.net 257 0 249 0 0 0 0%16:48:04
TOTAL: 4 16574 1577 8471 1210 0 135 9%88:47:47
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.5 MB81.1 KB2.6 MB0.0 KB/s3.3 KB23:59:52
2sklaffkom.se1.2 MB4.9 MB6.2 MB0.1 KB/s3.2 KB23:59:56
3newsfeed.endofthelinebbs.com29.0 KB4.5 KB33.5 KB0.0 KB/s2.1 KB23:59:55
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s016:48:04
TOTAL: 43.8 MB5.0 MB8.8 MB0.0 KB/s3.2 KB88:47:47
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1azpdesnc4dwz.stretchoid.com 2 00.0 KB 0 0 000:00:10
2162.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
3195.206.182.218 1 00.0 KB 0 0 000:00:00
4198.235.24.67 1 00.0 KB 0 0 000:00:02
569.5.169.251 1 00.0 KB 0 0 000:00:00
6o009.scanner.modat.io 1 00.0 KB 0 0 000:00:00
7r5-26-1a.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 7 8 00.0 KB 0 0 000:00:16

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 00.0 KB 0 0 000:00:02
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
4*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
5*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 5 8 00.0 KB 0 0 000:00:16

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdesnc4dwz.stretchoid.com 0.008 0.017 0.00000:00:10
198.235.24.67 0.003 0.011 0.00000:00:02
r5-26-1a.monitoring.internet-measurement.com 0.004 0.010 0.00000:00:01
162.186.132.66.censys-scanner.com 0.031 0.015 0.00000:00:01
195.206.182.218 0.002 0.007 0.00000:00:00
o009.scanner.modat.io 0.002 0.012 0.00000:00:00
69.5.169.251 0.001 0.014 0.00000:00:00
TOTAL: 7 0.051 0.086 0.00000:00:16

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

SystemConn
162.186.132.66.censys-scanner.com 1
azpdesnc4dwz.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
195.206.182.218 1 1
TOTAL: 1 1 3
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