Daily Usenet report

Aug 8 03:00:04 -- Aug 9 03:00:02

Unknown entries from news log file:

First 50 / 102 lines (49.0%)

Aug 8 03:04:29 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 8 04:03:08 twilightnode nnrpd[897697]: Reading access from /usr/local/news/etc/readers.conf
Aug 8 04:03:08 twilightnode nnrpd[897697]: Auth strategy 'localhost' does not match client. Removing.
Aug 8 04:03:08 twilightnode nnrpd[897697]: 123.58.200.21 res <anonymous>
Aug 8 04:03:08 twilightnode nnrpd[897697]: 123.58.200.21 no_match_user <anonymous> <localhost>
Aug 8 04:03:08 twilightnode nnrpd[897697]: 123.58.200.21 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 8 04:03:08 twilightnode nnrpd[897697]: 123.58.200.21 match_user <anonymous> <anonymous>
Aug 8 04:03:08 twilightnode nnrpd[897697]: 123.58.200.21 no_post anonymous-read
Aug 8 04:03:09 twilightnode nnrpd[897698]: Reading access from /usr/local/news/etc/readers.conf
Aug 8 04:03:09 twilightnode nnrpd[897698]: Auth strategy 'localhost' does not match client. Removing.
Aug 8 04:03:09 twilightnode nnrpd[897698]: 123.58.200.21 res <anonymous>
Aug 8 04:03:09 twilightnode nnrpd[897698]: 123.58.200.21 no_match_user <anonymous> <localhost>
Aug 8 04:03:09 twilightnode nnrpd[897698]: 123.58.200.21 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 8 04:03:09 twilightnode nnrpd[897698]: 123.58.200.21 match_user <anonymous> <anonymous>
Aug 8 04:03:09 twilightnode nnrpd[897698]: 123.58.200.21 no_post anonymous-read
Aug 8 04:03:19 twilightnode nnrpd[897705]: Reading access from /usr/local/news/etc/readers.conf
Aug 8 04:03:19 twilightnode nnrpd[897705]: Auth strategy 'localhost' does not match client. Removing.
Aug 8 04:03:19 twilightnode nnrpd[897705]: 123.58.200.21 res <anonymous>
Aug 8 04:03:19 twilightnode nnrpd[897705]: 123.58.200.21 no_match_user <anonymous> <localhost>
Aug 8 04:03:19 twilightnode nnrpd[897705]: 123.58.200.21 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 8 04:03:19 twilightnode nnrpd[897705]: 123.58.200.21 match_user <anonymous> <anonymous>
Aug 8 04:03:19 twilightnode nnrpd[897705]: 123.58.200.21 no_post anonymous-read
Aug 8 05:20:07 twilightnode nnrpd[901349]: Reading access from /usr/local/news/etc/readers.conf
Aug 8 05:20:07 twilightnode nnrpd[901349]: Auth strategy 'localhost' does not match client. Removing.
Aug 8 05:20:07 twilightnode nnrpd[901349]: o015.scanner.modat.io res <anonymous>
Aug 8 05:20:07 twilightnode nnrpd[901349]: o015.scanner.modat.io no_match_user <anonymous> <localhost>
Aug 8 05:20:07 twilightnode nnrpd[901349]: o015.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 8 05:20:07 twilightnode nnrpd[901349]: o015.scanner.modat.io match_user <anonymous> <anonymous>
Aug 8 05:20:07 twilightnode nnrpd[901349]: o015.scanner.modat.io no_post anonymous-read
Aug 8 08:01:37 twilightnode nnrpd[908696]: Reading access from /usr/local/news/etc/readers.conf
Aug 8 08:01:37 twilightnode nnrpd[908696]: Auth strategy 'localhost' does not match client. Removing.
Aug 8 08:01:37 twilightnode nnrpd[908696]: c-5eea5b77-74736162.cust.telenor.se res <anonymous>
Aug 8 08:01:37 twilightnode nnrpd[908696]: c-5eea5b77-74736162.cust.telenor.se no_match_user <anonymous> <localhost>
Aug 8 08:01:37 twilightnode nnrpd[908696]: c-5eea5b77-74736162.cust.telenor.se no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 8 08:01:37 twilightnode nnrpd[908696]: c-5eea5b77-74736162.cust.telenor.se match_user <anonymous> <anonymous>
Aug 8 08:01:37 twilightnode nnrpd[908696]: c-5eea5b77-74736162.cust.telenor.se no_post anonymous-read
Aug 8 11:09:18 twilightnode nnrpd[916917]: Reading access from /usr/local/news/etc/readers.conf
Aug 8 11:09:18 twilightnode nnrpd[916917]: Auth strategy 'localhost' does not match client. Removing.
Aug 8 11:09:18 twilightnode nnrpd[916917]: 119.154.45.199.censys-scanner.com res <anonymous>
Aug 8 11:09:18 twilightnode nnrpd[916917]: 119.154.45.199.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 8 11:09:18 twilightnode nnrpd[916917]: 119.154.45.199.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 8 11:09:18 twilightnode nnrpd[916917]: 119.154.45.199.censys-scanner.com match_user <anonymous> <anonymous>
Aug 8 11:09:18 twilightnode nnrpd[916917]: 119.154.45.199.censys-scanner.com no_post anonymous-read
Aug 8 11:09:23 twilightnode nnrpd[916973]: Reading access from /usr/local/news/etc/readers.conf
Aug 8 11:09:23 twilightnode nnrpd[916973]: Auth strategy 'localhost' does not match client. Removing.
Aug 8 11:09:23 twilightnode nnrpd[916973]: 119.154.45.199.censys-scanner.com res <anonymous>
Aug 8 11:09:23 twilightnode nnrpd[916973]: 119.154.45.199.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 8 11:09:23 twilightnode nnrpd[916973]: 119.154.45.199.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 8 11:09:23 twilightnode nnrpd[916973]: 119.154.45.199.censys-scanner.com match_user <anonymous> <anonymous>
Aug 8 11:09:23 twilightnode nnrpd[916973]: 119.154.45.199.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 5993 68.4%908.0 KB 69.3%
innd 1309 14.9%191.9 KB 14.7%
innfeed 1302 14.9%193.0 KB 14.7%
nnrpd 158 1.8%16.7 KB 1.3%
TOTAL: 4 8762 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13106 48.5%
Negative hits 8023 29.7%
Do not exist 5908 21.8%
Cache misses 2 0.0%
TOTAL: 4 27039 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.006 0.0%3 1.000 2.000 3.000
article cleanup00:00:00.046 0.0%5936 0.000 0.008 0.333
article logging00:00:00.221 0.0%5996 0.000 0.037 0.636
article parse00:00:00.084 0.0%7347 0.000 0.011 0.083
article write00:00:03.832 0.0%4395 0.520 0.872 1.818
data move00:00:00.080 0.0%28379 0.000 0.003 0.033
hisgrep/artcncl00:00:00.004 0.0%3 0.000 1.333 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.306 0.0%27139 0.058 0.122 0.246
history sync00:00:00.044 0.0%308 0.000 0.143 1.000
history write00:00:02.315 0.0%5936 0.118 0.390 0.854
idle24:00:04.517100.0%28369 1546.598 3045.737 15004.150
nntp read00:00:00.547 0.0%28092 0.000 0.019 0.081
overview write00:00:02.047 0.0%4395 0.125 0.466 1.722
perl filter00:00:08.214 0.0%5936 0.590 1.384 5.969
python filter00:00:00.517 0.0%5936 0.000 0.087 0.531
site send00:00:00.074 0.0%4366 0.000 0.017 0.125
TOTAL: 1724:00:25.854100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.011 0.0%4477 0.000 0.002 0.048
article prepare00:00:00.000 0.0%16996 0.000 0.000 0.000
article read00:00:00.516 0.0%2888 0.000 0.179 2.000
backlog stats00:00:00.043 0.0%50062 0.000 0.001 0.017
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.320 0.0%26739 0.000 0.012 0.043
data write00:00:00.575 0.0%22256 0.000 0.026 0.067
idle23:50:21.315100.0%50062 355.022 1714.301 5415.811
status file00:00:00.242 0.0%275 0.000 0.880 16.250
TOTAL: 923:50:23.022100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:37.706100.0%20 81.500 1885.300 10412.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%135 0.000 0.007 0.021
TOTAL: 300:00:37.707100.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 2 11310 4235 6000 1075 37%52:57:53
2newsfeed.endofthelinebbs.com 1 4715 296 3951 468 6%27:40:49
3news.tebibyte.org 2 4237 63 4142 32 1%23:59:49
4news.sklaffkom.se 40 108 18 40 50 16%11:07:15
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 70 20370 4612 14133 1625 22%115:45:46
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.2 MB0.0 KB3.0 MB14.3 MB 78%2.8 KB
2newsfeed.endofthelinebbs.com2.1 MB0.0 KB1.6 MB3.7 MB 57%4.9 KB
3news.tebibyte.org158.3 KB0.0 KB184.2 KB342.5 KB 46%3.6 KB
4news.sklaffkom.se47.8 KB0.0 KB124.6 KB172.4 KB 27%2.5 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 513.6 MB0.0 KB4.9 MB18.5 MB 73%3.0 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 8 03:00:04 - 03:59:59 76 1.7% 0.02371.8 KB 3.0% 0.10
Aug 8 04:00:00 - 04:59:59 75 1.7% 0.02193.6 KB 1.5% 0.05
Aug 8 05:00:00 - 05:59:59 87 2.0% 0.02489.6 KB 3.9% 0.14
Aug 8 06:00:00 - 06:59:59 100 2.3% 0.03298.2 KB 2.4% 0.08
Aug 8 07:00:00 - 07:59:59 166 3.8% 0.05437.1 KB 3.5% 0.12
Aug 8 08:00:00 - 08:59:59 168 3.8% 0.05552.3 KB 4.4% 0.15
Aug 8 09:00:00 - 09:59:59 231 5.3% 0.06669.1 KB 5.3% 0.19
Aug 8 10:00:00 - 10:59:59 305 6.9% 0.08794.1 KB 6.3% 0.22
Aug 8 11:00:00 - 11:59:59 249 5.7% 0.07655.5 KB 5.2% 0.18
Aug 8 12:00:00 - 12:59:59 215 4.9% 0.06499.3 KB 4.0% 0.14
Aug 8 13:00:00 - 13:59:59 264 6.0% 0.07687.4 KB 5.5% 0.19
Aug 8 14:00:00 - 14:59:59 199 4.5% 0.06553.8 KB 4.4% 0.15
Aug 8 15:00:00 - 15:59:59 306 7.0% 0.09732.6 KB 5.8% 0.20
Aug 8 16:00:00 - 16:59:59 218 5.0% 0.06613.0 KB 4.9% 0.17
Aug 8 17:00:00 - 17:59:59 219 5.0% 0.06641.8 KB 5.1% 0.18
Aug 8 18:00:00 - 18:59:59 222 5.1% 0.06727.4 KB 5.8% 0.20
Aug 8 19:00:00 - 19:59:59 188 4.3% 0.05473.7 KB 3.8% 0.13
Aug 8 20:00:00 - 20:59:59 149 3.4% 0.04360.5 KB 2.9% 0.10
Aug 8 21:00:00 - 21:59:59 189 4.3% 0.05541.6 KB 4.3% 0.15
Aug 8 22:00:00 - 22:59:59 193 4.4% 0.05570.5 KB 4.5% 0.16
Aug 8 23:00:00 - 23:59:59 180 4.1% 0.05538.1 KB 4.3% 0.15
Aug 9 00:00:00 - 00:59:59 172 3.9% 0.05579.9 KB 4.6% 0.16
Aug 9 01:00:00 - 01:59:59 93 2.1% 0.03253.9 KB 2.0% 0.07
Aug 9 02:00:00 - 02:59:59 118 2.7% 0.03304.4 KB 2.4% 0.08
Aug 9 03:00:00 - 03:00:02 10 0.2% 5.0024.2 KB 0.2% 12.10
TOTAL: 23:59:58 4392 100.0% 0.0512.3 MB 100.0% 0.15
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1072 884 0 0 0 0 0 0 188
2newsfeed.endofthelinebbs.com 452 33 0 0 0 0 0 0 419
3news.sklaffkom.se 50 50 0 0 0 0 0 0 0
4news.tebibyte.org 27 20 0 0 0 0 0 0 7
TOTAL: 4 1601 987 0 0 0 0 0 0 614

Unwanted newsgroups [Top 20]:

NewsgroupCount
de.alt.talk.unmut 63
eternal-september.talk 44
alt.usage.english 34
hfx.general 30
pl.soc.prawo 27
uk.legal.moderated 23
uk.net.news.moderation 22
muc.lists.freebsd.multimedia 21
pl.sci.fizyka 21
alt.atheism 20
bln.test 20
de.comp.hardware.misc 20
de.talk.jokes 20
fr.sci.physique 18
it.hobby.cucina 17
linux.debian.user 17
alt.recovery.aa 16
eternal-september.test 16
francom.esoterisme 15
alt.checkmate 14
TOTAL: 180 987

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 443
EMP (phn path) 74
Too many newsgroups 13
User-issued cancel 11
HTML Multipart 10
Too many newsgroups (meow) 3
TOTAL: 6 554

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 5985 1404 2961 0 0 5 23%23:59:55
2sklaffkom.se 4550 863 1549 1953 0 1 18%23:59:55
3newsfeed.endofthelinebbs.com 7918 28 4105 18 0 134 0%23:59:51
4weretis.net 301 0 292 0 0 0 0%17:56:58
TOTAL: 4 18754 2295 8907 1971 0 140 12%89:56:39
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.5 MB0.0 KB3.5 MB0.0 KB/s2.6 KB23:59:55
2sklaffkom.se1.4 MB6.3 MB7.6 MB0.1 KB/s2.8 KB23:59:55
3newsfeed.endofthelinebbs.com89.1 KB39.6 KB128.6 KB0.0 KB/s2.8 KB23:59:51
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s017:56:58
TOTAL: 45.0 MB6.3 MB11.3 MB0.0 KB/s2.7 KB89:56:39
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1119.154.45.199.censys-scanner.com 3 00.0 KB 0 0 000:00:03
2123.58.200.21 3 00.0 KB 0 0 000:00:11
385.195.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:06
4azpdsg14kxu1.stretchoid.com 2 00.0 KB 0 0 000:00:10
5c-5eea5b77-74736162.cust.telenor.se 1 00.0 KB 0 0 000:00:05
6o015.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 6 13 00.0 KB 0 0 000:00:37

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.199.censys-scanner.com 3 00.0 KB 0 0 000:00:03
2*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:06
3unresolved 3 00.0 KB 0 0 000:00:11
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
5*.cust.telenor.se 1 00.0 KB 0 0 000:00:05
6*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 6 13 00.0 KB 0 0 000:00:37

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
123.58.200.21 0.011 0.028 0.00000:00:11
azpdsg14kxu1.stretchoid.com 0.007 0.012 0.00000:00:10
85.195.132.66.censys-scanner.com 0.042 0.031 0.00000:00:06
c-5eea5b77-74736162.cust.telenor.se 0.005 0.007 0.00000:00:05
119.154.45.199.censys-scanner.com 0.037 0.024 0.00000:00:03
o015.scanner.modat.io 0.007 0.007 0.00000:00:00
TOTAL: 6 0.109 0.109 0.00000:00:37

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

SystemConn
123.58.200.21 2
119.154.45.199.censys-scanner.com 1
85.195.132.66.censys-scanner.com 1
azpdsg14kxu1.stretchoid.com 1
TOTAL: 4 5

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

CommandCount
EHLO www.censys.io 2
#026#003#001 1
?#024 1
MGLNDD_172.234.115.63_119 1
TOTAL: 4 5

NNRP client timeouts [Top 20]:

SystemConnPeer
119.154.45.199.censys-scanner.com 1 2
85.195.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