Daily Usenet report

Aug 1 03:00:05 -- Aug 2 03:00:04

Unknown entries from news log file:

First 50 / 98 lines (51.0%)

Aug 1 03:01:23 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 1 05:34:23 twilightnode nnrpd[419409]: Reading access from /usr/local/news/etc/readers.conf
Aug 1 05:34:23 twilightnode nnrpd[419409]: Auth strategy 'localhost' does not match client. Removing.
Aug 1 05:34:23 twilightnode nnrpd[419409]: 194.88.98.93 res <anonymous>
Aug 1 05:34:23 twilightnode nnrpd[419409]: 194.88.98.93 no_match_user <anonymous> <localhost>
Aug 1 05:34:23 twilightnode nnrpd[419409]: 194.88.98.93 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 1 05:34:23 twilightnode nnrpd[419409]: 194.88.98.93 match_user <anonymous> <anonymous>
Aug 1 05:34:23 twilightnode nnrpd[419409]: 194.88.98.93 no_post anonymous-read
Aug 1 05:34:48 twilightnode nnrpd[419410]: Reading access from /usr/local/news/etc/readers.conf
Aug 1 05:34:48 twilightnode nnrpd[419410]: Auth strategy 'localhost' does not match client. Removing.
Aug 1 05:34:48 twilightnode nnrpd[419410]: 81.19.219.251 res <anonymous>
Aug 1 05:34:48 twilightnode nnrpd[419410]: 81.19.219.251 no_match_user <anonymous> <localhost>
Aug 1 05:34:48 twilightnode nnrpd[419410]: 81.19.219.251 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 1 05:34:48 twilightnode nnrpd[419410]: 81.19.219.251 match_user <anonymous> <anonymous>
Aug 1 05:34:48 twilightnode nnrpd[419410]: 81.19.219.251 no_post anonymous-read
Aug 1 06:08:24 twilightnode nnrpd[420763]: Reading access from /usr/local/news/etc/readers.conf
Aug 1 06:08:24 twilightnode nnrpd[420763]: Auth strategy 'localhost' does not match client. Removing.
Aug 1 06:08:24 twilightnode nnrpd[420763]: azpdcsl65d57.stretchoid.com res <anonymous>
Aug 1 06:08:24 twilightnode nnrpd[420763]: azpdcsl65d57.stretchoid.com no_match_user <anonymous> <localhost>
Aug 1 06:08:24 twilightnode nnrpd[420763]: azpdcsl65d57.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 1 06:08:24 twilightnode nnrpd[420763]: azpdcsl65d57.stretchoid.com match_user <anonymous> <anonymous>
Aug 1 06:08:24 twilightnode nnrpd[420763]: azpdcsl65d57.stretchoid.com no_post anonymous-read
Aug 1 06:08:34 twilightnode nnrpd[420771]: Reading access from /usr/local/news/etc/readers.conf
Aug 1 06:08:34 twilightnode nnrpd[420771]: Auth strategy 'localhost' does not match client. Removing.
Aug 1 06:08:34 twilightnode nnrpd[420771]: azpdcsl65d57.stretchoid.com res <anonymous>
Aug 1 06:08:34 twilightnode nnrpd[420771]: azpdcsl65d57.stretchoid.com no_match_user <anonymous> <localhost>
Aug 1 06:08:34 twilightnode nnrpd[420771]: azpdcsl65d57.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 1 06:08:34 twilightnode nnrpd[420771]: azpdcsl65d57.stretchoid.com match_user <anonymous> <anonymous>
Aug 1 06:08:34 twilightnode nnrpd[420771]: azpdcsl65d57.stretchoid.com no_post anonymous-read
Aug 1 12:41:14 twilightnode pgpverify[439150]: pgpverify: verification failed
Aug 1 19:36:43 twilightnode nnrpd[456899]: Reading access from /usr/local/news/etc/readers.conf
Aug 1 19:36:43 twilightnode nnrpd[456899]: Auth strategy 'localhost' does not match client. Removing.
Aug 1 19:36:43 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com res <anonymous>
Aug 1 19:36:43 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 1 19:36:43 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 1 19:36:43 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 1 19:36:43 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com no_post anonymous-read
Aug 1 19:36:44 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com res <anonymous>
Aug 1 19:36:44 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 1 19:36:44 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 1 19:36:44 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 1 19:36:44 twilightnode nnrpd[456899]: 67.195.132.66.censys-scanner.com no_post anonymous-read
Aug 1 20:41:00 twilightnode nnrpd[459791]: Reading access from /usr/local/news/etc/readers.conf
Aug 1 20:41:00 twilightnode nnrpd[459791]: Auth strategy 'localhost' does not match client. Removing.
Aug 1 20:41:00 twilightnode nnrpd[459791]: 134.122.53.30 res <anonymous>
Aug 1 20:41:00 twilightnode nnrpd[459791]: 134.122.53.30 no_match_user <anonymous> <localhost>
Aug 1 20:41:00 twilightnode nnrpd[459791]: 134.122.53.30 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 1 20:41:00 twilightnode nnrpd[459791]: 134.122.53.30 match_user <anonymous> <anonymous>
Aug 1 20:41:00 twilightnode nnrpd[459791]: 134.122.53.30 no_post anonymous-read
Aug 1 20:41:00 twilightnode nnrpd[459792]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
inn 7136 68.5%1.1 MB 69.6%
innd 1741 16.7%247.5 KB 16.0%
innfeed 1307 12.6%195.8 KB 12.7%
nnrpd 188 1.8%18.0 KB 1.2%
controlchan 38 0.4%7.9 KB 0.5%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 6 10411 100.0%1.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15578 47.9%
Negative hits 9888 30.4%
Do not exist 7060 21.7%
Cache misses 0 0.0%
TOTAL: 4 32526 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.010 0.0%7 0.000 1.429 2.000
article cleanup00:00:00.066 0.0%7082 0.000 0.009 0.280
article logging00:00:00.200 0.0%7130 0.000 0.028 0.182
article parse00:00:00.107 0.0%10113 0.000 0.011 0.061
article write00:00:04.322 0.0%5249 0.350 0.823 1.754
data move00:00:00.196 0.0%34936 0.000 0.006 0.226
hisgrep/artcncl00:00:00.005 0.0%7 0.000 0.714 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.823 0.0%32612 0.082 0.148 0.434
history sync00:00:00.049 0.0%310 0.000 0.158 1.000
history write00:00:03.639 0.0%7082 0.182 0.514 1.000
idle23:59:59.552100.0%34554 794.389 2500.421 22229.630
nntp read00:00:00.641 0.0%34280 0.000 0.019 0.073
overview write00:00:02.191 0.0%5249 0.075 0.417 2.000
perl filter00:00:09.339 0.0%7082 0.374 1.319 10.045
python filter00:00:00.960 0.0%7082 0.000 0.136 10.967
site send00:00:00.114 0.0%5253 0.000 0.022 0.087
TOTAL: 1724:00:26.214100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.021 0.0%5650 0.000 0.004 0.059
article prepare00:00:00.003 0.0%19668 0.000 0.000 0.007
article read00:00:00.544 0.0%3256 0.000 0.167 1.714
backlog stats00:00:00.037 0.0%56923 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.390 0.0%31243 0.000 0.012 0.055
data write00:00:00.681 0.0%26077 0.000 0.026 0.085
idle23:58:30.566100.0%56923 340.327 1516.269 4890.919
status file00:00:00.152 0.0%264 0.000 0.576 4.000
TOTAL: 923:58:32.394100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:17.494100.0%26 16.000 672.846 10431.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%112 0.000 0.000 0.000
TOTAL: 300:00:17.494100.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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
control@eternal-september.org001001
control@usenet-fr.news.eu.org01211013
TOTAL01221014

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 32 12680 4824 6679 1177 38%49:41:37
2newsfeed.endofthelinebbs.com 15 6002 383 4947 672 6%27:21:48
3news.tebibyte.org 2 4996 53 4912 31 1%23:59:35
4news.sklaffkom.se 36 58 19 9 30 32%07:28:16
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 110 23736 5279 16547 1910 22%108:31:16
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net16.6 MB0.0 KB3.6 MB20.1 MB 82%3.4 KB
2newsfeed.endofthelinebbs.com3.8 MB0.0 KB1.7 MB5.5 MB 69%5.3 KB
3news.tebibyte.org144.1 KB0.0 KB72.8 KB216.9 KB 66%2.6 KB
4news.sklaffkom.se55.7 KB0.0 KB75.1 KB130.8 KB 42%2.7 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 520.6 MB0.0 KB5.4 MB26.0 MB 79%3.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 1 03:00:05 - 03:59:59 103 2.0% 0.03491.9 KB 2.4% 0.14
Aug 1 04:00:00 - 04:59:59 110 2.1% 0.03337.5 KB 1.6% 0.09
Aug 1 05:00:00 - 05:59:59 100 1.9% 0.03432.2 KB 2.1% 0.12
Aug 1 06:00:00 - 06:59:59 117 2.2% 0.03602.1 KB 2.9% 0.17
Aug 1 07:00:00 - 07:59:59 146 2.8% 0.04564.7 KB 2.7% 0.16
Aug 1 08:00:00 - 08:59:59 252 4.8% 0.071.5 MB 7.2% 0.41
Aug 1 09:00:00 - 09:59:59 261 5.0% 0.071001.0 KB 4.9% 0.28
Aug 1 10:00:00 - 10:59:59 238 4.5% 0.07516.3 KB 2.5% 0.14
Aug 1 11:00:00 - 11:59:59 245 4.7% 0.07696.5 KB 3.4% 0.19
Aug 1 12:00:00 - 12:59:59 237 4.5% 0.07778.0 KB 3.8% 0.22
Aug 1 13:00:00 - 13:59:59 224 4.3% 0.06967.5 KB 4.7% 0.27
Aug 1 14:00:00 - 14:59:59 209 4.0% 0.06542.1 KB 2.6% 0.15
Aug 1 15:00:00 - 15:59:59 309 5.9% 0.09952.4 KB 4.6% 0.26
Aug 1 16:00:00 - 16:59:59 351 6.7% 0.101.2 MB 5.7% 0.33
Aug 1 17:00:00 - 17:59:59 272 5.2% 0.08756.1 KB 3.7% 0.21
Aug 1 18:00:00 - 18:59:59 238 4.5% 0.07780.2 KB 3.8% 0.22
Aug 1 19:00:00 - 19:59:59 275 5.2% 0.081.4 MB 7.1% 0.41
Aug 1 20:00:00 - 20:59:59 256 4.9% 0.07739.8 KB 3.6% 0.21
Aug 1 21:00:00 - 21:59:59 240 4.6% 0.07791.6 KB 3.8% 0.22
Aug 1 22:00:00 - 22:59:59 210 4.0% 0.06625.2 KB 3.0% 0.17
Aug 1 23:00:00 - 23:59:59 278 5.3% 0.081.0 MB 5.0% 0.29
Aug 2 00:00:00 - 00:59:59 326 6.2% 0.092.7 MB 13.6% 0.78
Aug 2 01:00:00 - 01:59:59 139 2.6% 0.04459.2 KB 2.2% 0.13
Aug 2 02:00:00 - 02:59:59 109 2.1% 0.03564.9 KB 2.7% 0.16
Aug 2 03:00:00 - 03:00:04 12 0.2% 3.0029.0 KB 0.1% 7.25
TOTAL: 23:59:59 5257 100.0% 0.0620.1 MB 100.0% 0.24
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1204 864 0 0 0 0 0 0 340
2newsfeed.endofthelinebbs.com 602 78 0 0 0 0 0 0 524
3news.tebibyte.org 43 38 0 0 0 0 0 0 5
4news.sklaffkom.se 30 29 0 0 0 0 0 0 1
TOTAL: 4 1879 1009 0 0 0 0 0 0 870

Unwanted newsgroups [Top 20]:

NewsgroupCount
pl.soc.prawo 70
de.rec.garten 42
pl.misc.elektronika 33
eternal-september.talk 25
de.talk.jokes 23
de.test 23
alt.prophecies.nostradamus 22
alt.usenet.legends.lester-mosley 22
aus.politics 20
cn.comp 20
fido7.ukrnews 19
hfx.general 19
uk.media.radio.archers 19
uk.net.news.moderation 17
alt.fan.rush-limbaurgh 15
alt.usage.english 15
alt.checkmate 14
fr.comp.ia 14
pl.soc.polityka 14
alt.home.repair 13
TOTAL: 248 1009

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 585
User-issued cancel 177
EMP (phn path) 40
Too many newsgroups 12
HTML Multipart 8
TOTAL: 5 822

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6895 1669 3570 7 0 145 24%23:59:02
2sklaffkom.se 5451 871 2045 2330 0 3 15%23:59:55
3newsfeed.endofthelinebbs.com 9484 39 4890 10 0 269 0%23:58:11
4weretis.net 435 1 423 0 0 4 0%20:49:25
TOTAL: 4 22265 2580 10928 2347 0 421 11%92:46:33
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org4.4 MB556.2 KB4.9 MB0.1 KB/s3.0 KB23:59:02
2sklaffkom.se1.4 MB10.4 MB11.8 MB0.1 KB/s3.8 KB23:59:55
3newsfeed.endofthelinebbs.com228.5 KB23.7 KB252.3 KB0.0 KB/s5.1 KB23:58:11
4weretis.net8.2 KB0.0 KB8.2 KB0.0 KB/s8.2 KB20:49:25
TOTAL: 46.0 MB11.0 MB16.9 MB0.1 KB/s3.5 KB92:46:33
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1134.122.53.30 7 00.0 KB 0 0 000:00:04
2azpdcsl65d57.stretchoid.com 2 00.0 KB 0 0 000:00:10
3193.46.255.147 1 00.0 KB 0 0 000:00:00
4194.88.98.93 1 00.0 KB 0 0 000:00:00
567.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
681.19.219.251 1 00.0 KB 0 0 000:00:00
TOTAL: 6 13 00.0 KB 0 0 000:00:17

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 10 00.0 KB 0 0 000:00:04
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:02
TOTAL: 3 13 00.0 KB 0 0 000:00:17

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcsl65d57.stretchoid.com 0.005 0.019 0.00000:00:10
134.122.53.30 0.019 0.039 0.00000:00:04
67.195.132.66.censys-scanner.com 0.032 0.024 0.00000:00:02
193.46.255.147 0.003 0.014 0.00000:00:00
81.19.219.251 0.004 0.003 0.00000:00:00
194.88.98.93 0.004 0.015 0.00000:00:00
TOTAL: 6 0.067 0.114 0.00000:00:17

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

SystemConn
134.122.53.30 28
193.46.255.147 2
67.195.132.66.censys-scanner.com 1
azpdcsl65d57.stretchoid.com 1
TOTAL: 4 32

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

CommandCount
#026#003#001 2
#001 1
#023#003#023#001#023#002#001 1
$4 1
*1 1
Accept-Encoding: gzip 1
Accept: */* 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
MGLNDD_172.234.115.63_119 1
PING 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
app.name=s:Workbench 1
app.version=s:3.7.44 1
brandId=s:vykon 1
fox a 1 -1 fox hello 1
fox.version=s:1.0 1
hostAddress=s:192.168.1.125 1
hostId=s:Win-99CB-D49D-5442-07BB 1
TOTAL: 31 32

NNRP client timeouts [Top 20]:

SystemConnPeer
134.122.53.30 1 3
81.19.219.251 1 1
TOTAL: 2 2 5
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