Daily Usenet report

Jul 14 03:00:05 -- Jul 15 03:00:05

Unknown entries from news log file:

First 50 / 70 lines (71.4%)

Jul 14 03:01:20 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 14 05:07:07 twilightnode nnrpd[152640]: Reading access from /usr/local/news/etc/readers.conf
Jul 14 05:07:07 twilightnode nnrpd[152640]: Auth strategy 'localhost' does not match client. Removing.
Jul 14 05:07:07 twilightnode nnrpd[152640]: azpdsg0jbem4.stretchoid.com res <anonymous>
Jul 14 05:07:07 twilightnode nnrpd[152640]: azpdsg0jbem4.stretchoid.com no_match_user <anonymous> <localhost>
Jul 14 05:07:07 twilightnode nnrpd[152640]: azpdsg0jbem4.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 14 05:07:07 twilightnode nnrpd[152640]: azpdsg0jbem4.stretchoid.com match_user <anonymous> <anonymous>
Jul 14 05:07:07 twilightnode nnrpd[152640]: azpdsg0jbem4.stretchoid.com no_post anonymous-read
Jul 14 05:07:17 twilightnode nnrpd[152641]: Reading access from /usr/local/news/etc/readers.conf
Jul 14 05:07:17 twilightnode nnrpd[152641]: Auth strategy 'localhost' does not match client. Removing.
Jul 14 05:07:17 twilightnode nnrpd[152641]: azpdsg0jbem4.stretchoid.com res <anonymous>
Jul 14 05:07:17 twilightnode nnrpd[152641]: azpdsg0jbem4.stretchoid.com no_match_user <anonymous> <localhost>
Jul 14 05:07:17 twilightnode nnrpd[152641]: azpdsg0jbem4.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 14 05:07:17 twilightnode nnrpd[152641]: azpdsg0jbem4.stretchoid.com match_user <anonymous> <anonymous>
Jul 14 05:07:17 twilightnode nnrpd[152641]: azpdsg0jbem4.stretchoid.com no_post anonymous-read
Jul 14 07:21:43 twilightnode nnrpd[159757]: Reading access from /usr/local/news/etc/readers.conf
Jul 14 07:21:43 twilightnode nnrpd[159757]: Auth strategy 'localhost' does not match client. Removing.
Jul 14 07:21:43 twilightnode nnrpd[159757]: 69.5.169.233 res <anonymous>
Jul 14 07:21:43 twilightnode nnrpd[159757]: 69.5.169.233 no_match_user <anonymous> <localhost>
Jul 14 07:21:43 twilightnode nnrpd[159757]: 69.5.169.233 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 14 07:21:43 twilightnode nnrpd[159757]: 69.5.169.233 match_user <anonymous> <anonymous>
Jul 14 07:21:43 twilightnode nnrpd[159757]: 69.5.169.233 no_post anonymous-read
Jul 14 07:21:50 twilightnode nnrpd[159758]: Reading access from /usr/local/news/etc/readers.conf
Jul 14 07:21:50 twilightnode nnrpd[159758]: Auth strategy 'localhost' does not match client. Removing.
Jul 14 07:21:50 twilightnode nnrpd[159758]: 193.124.20.253 res <anonymous>
Jul 14 07:21:50 twilightnode nnrpd[159758]: 193.124.20.253 no_match_user <anonymous> <localhost>
Jul 14 07:21:50 twilightnode nnrpd[159758]: 193.124.20.253 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 14 07:21:50 twilightnode nnrpd[159758]: 193.124.20.253 match_user <anonymous> <anonymous>
Jul 14 07:21:50 twilightnode nnrpd[159758]: 193.124.20.253 no_post anonymous-read
Jul 14 14:06:57 twilightnode nnrpd[177377]: Reading access from /usr/local/news/etc/readers.conf
Jul 14 14:06:57 twilightnode nnrpd[177377]: Auth strategy 'localhost' does not match client. Removing.
Jul 14 14:06:57 twilightnode nnrpd[177377]: azpdss5nqloe.stretchoid.com res <anonymous>
Jul 14 14:06:57 twilightnode nnrpd[177377]: azpdss5nqloe.stretchoid.com no_match_user <anonymous> <localhost>
Jul 14 14:06:57 twilightnode nnrpd[177377]: azpdss5nqloe.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 14 14:06:57 twilightnode nnrpd[177377]: azpdss5nqloe.stretchoid.com match_user <anonymous> <anonymous>
Jul 14 14:06:57 twilightnode nnrpd[177377]: azpdss5nqloe.stretchoid.com no_post anonymous-read
Jul 14 14:07:07 twilightnode nnrpd[177378]: Reading access from /usr/local/news/etc/readers.conf
Jul 14 14:07:07 twilightnode nnrpd[177378]: Auth strategy 'localhost' does not match client. Removing.
Jul 14 14:07:07 twilightnode nnrpd[177378]: azpdss5nqloe.stretchoid.com res <anonymous>
Jul 14 14:07:07 twilightnode nnrpd[177378]: azpdss5nqloe.stretchoid.com no_match_user <anonymous> <localhost>
Jul 14 14:07:07 twilightnode nnrpd[177378]: azpdss5nqloe.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 14 14:07:07 twilightnode nnrpd[177378]: azpdss5nqloe.stretchoid.com match_user <anonymous> <anonymous>
Jul 14 14:07:07 twilightnode nnrpd[177378]: azpdss5nqloe.stretchoid.com no_post anonymous-read
Jul 14 21:47:45 twilightnode nnrpd[197531]: Reading access from /usr/local/news/etc/readers.conf
Jul 14 21:47:45 twilightnode nnrpd[197531]: Auth strategy 'localhost' does not match client. Removing.
Jul 14 21:47:45 twilightnode nnrpd[197531]: o001.scanner.modat.io res <anonymous>
Jul 14 21:47:45 twilightnode nnrpd[197531]: o001.scanner.modat.io no_match_user <anonymous> <localhost>
Jul 14 21:47:45 twilightnode nnrpd[197531]: o001.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 14 21:47:45 twilightnode nnrpd[197531]: o001.scanner.modat.io match_user <anonymous> <anonymous>
Jul 14 21:47:45 twilightnode nnrpd[197531]: o001.scanner.modat.io no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6047 69.8%910.8 KB 70.4%
innfeed 1293 14.9%185.6 KB 14.4%
innd 1205 13.9%182.4 KB 14.1%
nnrpd 107 1.2%11.1 KB 0.9%
controlchan 15 0.2%3.2 KB 0.2%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 6 8668 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13507 49.5%
Negative hits 7811 28.6%
Do not exist 5960 21.8%
Cache misses 4 0.0%
TOTAL: 4 27282 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.001 0.0%1 1.000 1.000 1.000
article cleanup00:00:00.164 0.0%6000 0.000 0.027 0.974
article logging00:00:00.182 0.0%6007 0.000 0.030 0.204
article parse00:00:00.190 0.0%9731 0.000 0.020 0.522
article write00:00:04.829 0.0%4301 0.286 1.123 8.909
data move00:00:00.166 0.0%31225 0.000 0.005 0.161
hisgrep/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.423 0.0%27454 0.106 0.234 1.160
history sync00:00:00.184 0.0%410 0.000 0.449 40.667
history write00:00:04.056 0.0%6000 0.000 0.676 13.185
idle23:40:25.198 99.9%30474 610.761 2796.653 13338.978
nntp read00:00:00.732 0.0%30131 0.000 0.024 0.321
overview write00:00:02.509 0.0%4301 0.192 0.583 5.615
perl filter00:00:14.561 0.0%6000 0.720 2.427 51.183
python filter00:00:01.027 0.0%6000 0.000 0.171 3.157
site send00:00:00.083 0.0%4295 0.000 0.019 0.143
TOTAL: 1723:41:00.305100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.059 0.0%4652 0.000 0.013 0.667
article prepare00:00:00.000 0.0%11828 0.000 0.000 0.000
article read00:00:00.652 0.0%2165 0.000 0.301 7.000
backlog stats00:00:00.097 0.0%47322 0.000 0.002 0.134
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.525 0.0%25172 0.000 0.021 0.358
data write00:00:00.467 0.0%20792 0.000 0.022 0.102
idle23:41:03.862100.0%47322 501.500 1801.781 6009.050
status file00:00:00.142 0.0%252 0.000 0.563 7.000
TOTAL: 923:41:05.804100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:35.262100.0%13 64.000 2712.462 10733.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%40 0.000 0.025 0.059
TOTAL: 300:00:35.263100.0%----

Control commands to innd:

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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
newgroups-request@fido7.org001101
TOTAL001101

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 9958 3684 5200 1074 36%47:28:37
2newsfeed.endofthelinebbs.com 2 4126 359 3332 435 8%22:17:27
3news.tebibyte.org 4 12146 79 12000 67 0%18:15:20
4news.sklaffkom.se 38 86 31 37 18 36%09:51:33
5localhost 24 0 0 0 0 0%00:00:00
TOTAL: 5 72 26316 4153 20569 1594 15%97:52:57
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net10.8 MB0.0 KB3.2 MB14.0 MB 76%3.0 KB
2newsfeed.endofthelinebbs.com6.4 MB0.0 KB4.0 MB10.4 MB 61%13.4 KB
3news.tebibyte.org259.0 KB0.0 KB194.9 KB454.0 KB 57%3.1 KB
4news.sklaffkom.se77.0 KB0.0 KB46.4 KB123.4 KB 62%2.5 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 517.5 MB0.0 KB7.5 MB25.0 MB 69%4.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 14 03:00:05 - 03:59:59 86 2.0% 0.02436.9 KB 2.2% 0.12
Jul 14 04:00:00 - 04:59:59 92 2.1% 0.03313.5 KB 1.6% 0.09
Jul 14 05:00:00 - 05:59:59 56 1.3% 0.02348.4 KB 1.8% 0.10
Jul 14 06:00:00 - 06:59:59 126 2.9% 0.04419.1 KB 2.1% 0.12
Jul 14 07:00:00 - 07:59:59 115 2.7% 0.03365.5 KB 1.9% 0.10
Jul 14 08:00:00 - 08:59:59 131 3.0% 0.04484.2 KB 2.5% 0.13
Jul 14 09:00:00 - 09:59:59 186 4.3% 0.05663.5 KB 3.4% 0.18
Jul 14 10:00:00 - 10:59:59 193 4.5% 0.05598.7 KB 3.1% 0.17
Jul 14 11:00:00 - 11:59:59 194 4.5% 0.05631.0 KB 3.2% 0.18
Jul 14 12:00:00 - 12:59:59 215 5.0% 0.06606.1 KB 3.1% 0.17
Jul 14 13:00:00 - 13:59:59 239 5.5% 0.07719.6 KB 3.7% 0.20
Jul 14 14:00:00 - 14:59:59 236 5.4% 0.07900.9 KB 4.6% 0.25
Jul 14 15:00:00 - 15:59:59 254 5.9% 0.07950.0 KB 4.9% 0.26
Jul 14 16:00:00 - 16:59:59 276 6.4% 0.084.3 MB 22.6% 1.22
Jul 14 17:00:00 - 17:59:59 232 5.4% 0.06669.5 KB 3.4% 0.19
Jul 14 18:00:00 - 18:59:59 265 6.1% 0.07986.6 KB 5.1% 0.27
Jul 14 19:00:00 - 19:59:59 179 4.1% 0.05653.3 KB 3.4% 0.18
Jul 14 20:00:00 - 20:59:59 227 5.2% 0.06872.5 KB 4.5% 0.24
Jul 14 21:00:00 - 21:59:59 226 5.2% 0.06983.0 KB 5.0% 0.27
Jul 14 22:00:00 - 22:59:59 217 5.0% 0.06955.8 KB 4.9% 0.27
Jul 14 23:00:00 - 23:59:59 198 4.6% 0.06699.2 KB 3.6% 0.19
Jul 15 00:00:00 - 00:59:59 182 4.2% 0.05790.8 KB 4.1% 0.22
Jul 15 01:00:00 - 01:59:59 104 2.4% 0.03534.1 KB 2.7% 0.15
Jul 15 02:00:00 - 02:59:59 94 2.2% 0.03507.4 KB 2.6% 0.14
Jul 15 03:00:00 - 03:00:05 9 0.2% 1.807.2 KB 0.0% 1.44
TOTAL: 24:00:00 4332 100.0% 0.0519.0 MB 100.0% 0.23
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1204 1096 0 6 0 0 0 0 102
2newsfeed.endofthelinebbs.com 436 124 0 0 0 0 0 0 312
3news.tebibyte.org 57 44 0 1 0 0 0 0 12
4news.sklaffkom.se 18 17 0 0 0 0 0 0 1
TOTAL: 4 1715 1281 0 7 0 0 0 0 427

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 72
uk.legal.moderated 44
alt.checkmate 43
it.hobby.cucina 41
de.etc.finanz.banken+broker 36
fido7.ru.fidonet.today 34
pl.soc.prawo 32
de.alt.fan.pluesch 29
hfx.general 24
fr.sci.zetetique 22
cn.comp 20
lada.talk 20
it-alt.sport.calcio.juventus 19
bln.test 18
fido7.su.tormoz 18
linux.debian.user 18
fido7.ukrnews 17
aus.politics 16
de.comp.hardware.netzwerke.misc 16
de.etc.haushalt 16
TOTAL: 223 1281

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 309
EMP (phl) 76
User-issued cancel 13
Too many newsgroups (meow) 10
EMP (phn path) 7
Too many newsgroups 3
HTML Multipart 2
TOTAL: 7 420

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6161 863 3423 3 0 169 14%23:36:41
2sklaffkom.se 4932 644 2224 1418 0 2 13%23:36:16
3newsfeed.endofthelinebbs.com 7147 20 3839 6 0 180 0%23:36:16
4weretis.net 506 0 502 0 0 0 0%20:26:54
TOTAL: 4 18746 1527 9988 1427 0 351 8%91:16:07
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.3 MB308.6 KB2.6 MB0.0 KB/s3.1 KB23:36:41
2sklaffkom.se1.1 MB7.4 MB8.5 MB0.1 KB/s4.2 KB23:36:16
3newsfeed.endofthelinebbs.com204.5 KB10.1 KB214.6 KB0.0 KB/s8.3 KB23:36:16
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s020:26:54
TOTAL: 43.6 MB7.7 MB11.3 MB0.0 KB/s3.9 KB91:16:07
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1azpdsg0jbem4.stretchoid.com 2 00.0 KB 0 0 000:00:10
2azpdss5nqloe.stretchoid.com 2 00.0 KB 0 0 000:00:10
3193.124.20.253 1 00.0 KB 0 0 000:00:00
4195-252-55-9-no600.tbcn.telia.com 1 00.0 KB 0 0 000:00:10
5200.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
669.5.169.233 1 00.0 KB 0 0 000:00:00
7o001.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 9 00.0 KB 0 0 000:00:35

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.stretchoid.com 4 00.0 KB 0 0 000:00:21
2unresolved 2 00.0 KB 0 0 000:00:00
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
5*.tbcn.telia.com 1 00.0 KB 0 0 000:00:10
TOTAL: 5 9 00.0 KB 0 0 000:00:35

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdsg0jbem4.stretchoid.com 0.005 0.017 0.00000:00:10
azpdss5nqloe.stretchoid.com 0.006 0.020 0.00000:00:10
195-252-55-9-no600.tbcn.telia.com 0.012 0.014 0.00000:00:10
200.172.132.66.censys-scanner.com 0.034 0.019 0.00000:00:02
193.124.20.253 0.003 0.005 0.00000:00:00
o001.scanner.modat.io 0.002 0.014 0.00000:00:00
69.5.169.233 0.004 0.009 0.00000:00:00
TOTAL: 7 0.066 0.098 0.00000:00:35

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

SystemConn
200.172.132.66.censys-scanner.com 1
azpdsg0jbem4.stretchoid.com 1
azpdss5nqloe.stretchoid.com 1
TOTAL: 3 3

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

CommandCount
MGLNDD_172.234.115.63_119 2
EHLO www.censys.io 1
TOTAL: 2 3

NNRP client timeouts [Top 20]:

SystemConnPeer
193.124.20.253 1 1
TOTAL: 1 1 2
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