Daily Usenet report

Aug 14 03:00:03 -- Aug 15 03:00:03

Unknown entries from news log file:

First 50 / 95 lines (52.6%)

Aug 14 03:00:55 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 14 03:32:20 twilightnode nnrpd[1304328]: Reading access from /usr/local/news/etc/readers.conf
Aug 14 03:32:20 twilightnode nnrpd[1304328]: Auth strategy 'localhost' does not match client. Removing.
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com res <anonymous>
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com no_post anonymous-read
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com res <anonymous>
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 14 03:32:20 twilightnode nnrpd[1304328]: 106.195.132.66.censys-scanner.com no_post anonymous-read
Aug 14 05:12:03 twilightnode nnrpd[1308545]: Reading access from /usr/local/news/etc/readers.conf
Aug 14 05:12:03 twilightnode nnrpd[1308545]: Auth strategy 'localhost' does not match client. Removing.
Aug 14 05:12:03 twilightnode nnrpd[1308545]: 204.76.203.7.ptr.pfcloud.network res <anonymous>
Aug 14 05:12:03 twilightnode nnrpd[1308545]: 204.76.203.7.ptr.pfcloud.network no_match_user <anonymous> <localhost>
Aug 14 05:12:03 twilightnode nnrpd[1308545]: 204.76.203.7.ptr.pfcloud.network no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 14 05:12:03 twilightnode nnrpd[1308545]: 204.76.203.7.ptr.pfcloud.network match_user <anonymous> <anonymous>
Aug 14 05:12:03 twilightnode nnrpd[1308545]: 204.76.203.7.ptr.pfcloud.network no_post anonymous-read
Aug 14 05:14:20 twilightnode nnrpd[1308556]: Reading access from /usr/local/news/etc/readers.conf
Aug 14 05:14:20 twilightnode nnrpd[1308556]: Auth strategy 'localhost' does not match client. Removing.
Aug 14 05:14:20 twilightnode nnrpd[1308556]: 204.76.203.7.ptr.pfcloud.network res <anonymous>
Aug 14 05:14:20 twilightnode nnrpd[1308556]: 204.76.203.7.ptr.pfcloud.network no_match_user <anonymous> <localhost>
Aug 14 05:14:20 twilightnode nnrpd[1308556]: 204.76.203.7.ptr.pfcloud.network no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 14 05:14:20 twilightnode nnrpd[1308556]: 204.76.203.7.ptr.pfcloud.network match_user <anonymous> <anonymous>
Aug 14 05:14:20 twilightnode nnrpd[1308556]: 204.76.203.7.ptr.pfcloud.network no_post anonymous-read
Aug 14 08:07:12 twilightnode nnrpd[1316400]: Reading access from /usr/local/news/etc/readers.conf
Aug 14 08:07:12 twilightnode nnrpd[1316400]: Auth strategy 'localhost' does not match client. Removing.
Aug 14 08:07:12 twilightnode nnrpd[1316400]: 193.124.20.249 res <anonymous>
Aug 14 08:07:12 twilightnode nnrpd[1316400]: 193.124.20.249 no_match_user <anonymous> <localhost>
Aug 14 08:07:12 twilightnode nnrpd[1316400]: 193.124.20.249 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 14 08:07:12 twilightnode nnrpd[1316400]: 193.124.20.249 match_user <anonymous> <anonymous>
Aug 14 08:07:12 twilightnode nnrpd[1316400]: 193.124.20.249 no_post anonymous-read
Aug 14 08:07:25 twilightnode nnrpd[1316402]: Reading access from /usr/local/news/etc/readers.conf
Aug 14 08:07:25 twilightnode nnrpd[1316402]: Auth strategy 'localhost' does not match client. Removing.
Aug 14 08:07:25 twilightnode nnrpd[1316402]: 194.88.98.104 res <anonymous>
Aug 14 08:07:25 twilightnode nnrpd[1316402]: 194.88.98.104 no_match_user <anonymous> <localhost>
Aug 14 08:07:25 twilightnode nnrpd[1316402]: 194.88.98.104 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 14 08:07:25 twilightnode nnrpd[1316402]: 194.88.98.104 match_user <anonymous> <anonymous>
Aug 14 08:07:25 twilightnode nnrpd[1316402]: 194.88.98.104 no_post anonymous-read
Aug 14 11:36:35 twilightnode nnrpd[1325407]: Reading access from /usr/local/news/etc/readers.conf
Aug 14 11:36:35 twilightnode nnrpd[1325407]: Auth strategy 'localhost' does not match client. Removing.
Aug 14 11:36:35 twilightnode nnrpd[1325407]: 198.235.24.107 res <anonymous>
Aug 14 11:36:35 twilightnode nnrpd[1325407]: 198.235.24.107 no_match_user <anonymous> <localhost>
Aug 14 11:36:35 twilightnode nnrpd[1325407]: 198.235.24.107 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 14 11:36:35 twilightnode nnrpd[1325407]: 198.235.24.107 match_user <anonymous> <anonymous>
Aug 14 11:36:35 twilightnode nnrpd[1325407]: 198.235.24.107 no_post anonymous-read
Aug 14 14:16:04 twilightnode nnrpd[1332456]: Reading access from /usr/local/news/etc/readers.conf
Aug 14 14:16:04 twilightnode nnrpd[1332456]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 6389 68.2%969.7 KB 69.6%
innd 1563 16.7%218.6 KB 15.7%
innfeed 1252 13.4%187.6 KB 13.5%
nnrpd 157 1.7%16.8 KB 1.2%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 9362 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13889 48.7%
Negative hits 8318 29.2%
Do not exist 6315 22.1%
Cache misses 0 0.0%
TOTAL: 4 28522 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.003 0.0%1 3.000 3.000 3.000
article cleanup00:00:00.063 0.0%6333 0.000 0.010 0.167
article logging00:00:00.566 0.0%6371 0.000 0.089 7.814
article parse00:00:00.085 0.0%8320 0.000 0.010 0.125
article write00:00:04.821 0.0%4715 0.529 1.022 2.000
data move00:00:00.179 0.0%31116 0.000 0.006 0.084
hisgrep/artcncl00:00:00.003 0.0%1 3.000 3.000 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.417 0.0%28593 0.081 0.189 1.073
history sync00:00:00.079 0.0%325 0.000 0.243 5.000
history write00:00:03.216 0.0%6333 0.071 0.508 0.938
idle24:00:01.918100.0%30473 1363.080 2835.360 12005.660
nntp read00:00:00.603 0.0%30191 0.000 0.020 0.114
overview write00:00:02.315 0.0%4715 0.200 0.491 2.700
perl filter00:00:10.745 0.0%6333 0.739 1.697 9.975
python filter00:00:00.755 0.0%6333 0.000 0.119 0.591
site send00:00:00.126 0.0%4687 0.000 0.027 0.250
TOTAL: 1724:00:30.894100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.013 0.0%4788 0.000 0.003 0.143
article prepare00:00:00.000 0.0%16984 0.000 0.000 0.000
article read00:00:00.698 0.0%3283 0.000 0.213 1.200
backlog stats00:00:00.055 0.0%53759 0.000 0.001 0.016
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.378 0.0%28729 0.000 0.013 0.074
data write00:00:00.621 0.0%23946 0.000 0.026 0.087
idle23:50:03.120100.0%53759 389.836 1596.070 5273.421
status file00:00:00.173 0.0%238 0.000 0.727 6.000
TOTAL: 923:50:05.058100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:22.632100.0%21 164.500 1077.714 4979.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%113 0.000 0.000 0.000
TOTAL: 300:00:22.632100.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 3 10880 4126 5686 1068 37%50:00:17
2newsfeed.endofthelinebbs.com 1 5110 540 4057 513 10%26:15:52
3news.tebibyte.org 1 5148 112 5011 25 2%24:00:04
4news.sklaffkom.se 46 103 55 9 39 53%10:55:21
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 76 21241 4833 14763 1645 22%111:11:34
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.2 MB0.0 KB3.4 MB14.6 MB 76%2.9 KB
2newsfeed.endofthelinebbs.com4.8 MB0.0 KB1.1 MB5.9 MB 81%5.7 KB
3news.tebibyte.org328.5 KB0.0 KB41.5 KB370.0 KB 88%2.7 KB
4news.sklaffkom.se183.2 KB0.0 KB92.1 KB275.2 KB 66%2.9 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 516.5 MB0.0 KB4.6 MB21.1 MB 78%3.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 14 03:00:03 - 03:59:59 120 2.5% 0.03637.5 KB 3.9% 0.18
Aug 14 04:00:00 - 04:59:59 78 1.6% 0.02273.0 KB 1.7% 0.08
Aug 14 05:00:00 - 05:59:59 97 2.0% 0.03343.0 KB 2.1% 0.10
Aug 14 06:00:00 - 06:59:59 126 2.7% 0.04320.8 KB 2.0% 0.09
Aug 14 07:00:00 - 07:59:59 115 2.4% 0.03382.7 KB 2.3% 0.11
Aug 14 08:00:00 - 08:59:59 190 4.0% 0.05617.1 KB 3.8% 0.17
Aug 14 09:00:00 - 09:59:59 220 4.6% 0.06979.9 KB 6.0% 0.27
Aug 14 10:00:00 - 10:59:59 261 5.5% 0.07765.8 KB 4.7% 0.21
Aug 14 11:00:00 - 11:59:59 260 5.5% 0.07707.3 KB 4.3% 0.20
Aug 14 12:00:00 - 12:59:59 215 4.5% 0.06630.2 KB 3.9% 0.18
Aug 14 13:00:00 - 13:59:59 246 5.2% 0.07837.1 KB 5.1% 0.23
Aug 14 14:00:00 - 14:59:59 218 4.6% 0.06634.1 KB 3.9% 0.18
Aug 14 15:00:00 - 15:59:59 242 5.1% 0.07960.7 KB 5.9% 0.27
Aug 14 16:00:00 - 16:59:59 292 6.2% 0.08939.5 KB 5.7% 0.26
Aug 14 17:00:00 - 17:59:59 263 5.6% 0.07850.1 KB 5.2% 0.24
Aug 14 18:00:00 - 18:59:59 253 5.3% 0.07810.5 KB 5.0% 0.23
Aug 14 19:00:00 - 19:59:59 281 5.9% 0.08980.7 KB 6.0% 0.27
Aug 14 20:00:00 - 20:59:59 242 5.1% 0.07774.1 KB 4.7% 0.22
Aug 14 21:00:00 - 21:59:59 265 5.6% 0.071.1 MB 6.9% 0.31
Aug 14 22:00:00 - 22:59:59 199 4.2% 0.06830.1 KB 5.1% 0.23
Aug 14 23:00:00 - 23:59:59 164 3.5% 0.05611.6 KB 3.7% 0.17
Aug 15 00:00:00 - 00:59:59 137 2.9% 0.04427.3 KB 2.6% 0.12
Aug 15 01:00:00 - 01:59:59 139 2.9% 0.04586.2 KB 3.6% 0.16
Aug 15 02:00:00 - 02:59:59 99 2.1% 0.03305.8 KB 1.9% 0.08
Aug 15 03:00:00 - 03:00:03 10 0.2% 3.3325.4 KB 0.2% 8.48
TOTAL: 24:00:00 4732 100.0% 0.0516.0 MB 100.0% 0.19
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1078 1025 0 0 0 0 0 0 53
2newsfeed.endofthelinebbs.com 508 54 0 0 0 0 0 0 454
3news.sklaffkom.se 40 40 0 0 0 0 0 0 0
4news.tebibyte.org 31 30 0 0 0 0 0 0 1
TOTAL: 4 1657 1149 0 0 0 0 0 0 508

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 74
pl.soc.polityka 41
hfx.general 32
alt.atheism 30
spline.eisfair 28
muc.lists.freebsd.bugs 27
pl.misc.elektronika 26
de.alt.talk.unmut 23
linux.debian.devel 23
linux.debian.vote 22
cn.comp 20
alt.bestjobsusa.computer.jobs 18
alt.drwho.creative 18
eternal-september.talk 18
alt.checkmate 17
de.comp.security.misc 17
uk.media.radio.archers 17
it-alt.sport.calcio.juventus 16
pl.sci.fizyka 15
alt.food.fast-food 13
TOTAL: 222 1149

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 455
Too many newsgroups (meow) 6
EMP (phn path) 5
HTML Multipart 2
User-issued cancel 2
TOTAL: 5 470

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 37
newsfeed.endofthelinebbs.com 1
TOTAL: 2 38
TOTAL: 1 38

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 4988 1001 1476 2227 0 2 20%23:59:56
2news.tebibyte.org 6820 996 3700 2 0 12 14%23:59:56
3newsfeed.endofthelinebbs.com 8031 30 4168 15 0 106 0%23:59:55
4weretis.net 640 0 635 0 0 0 0%22:36:31
TOTAL: 4 20479 2027 9979 2244 0 120 9%94:36:18
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.1 MB152.2 KB3.3 MB0.0 KB/s3.4 KB23:59:56
2sklaffkom.se1.6 MB10.0 MB11.6 MB0.1 KB/s3.7 KB23:59:56
3newsfeed.endofthelinebbs.com99.9 KB36.4 KB136.3 KB0.0 KB/s3.0 KB23:59:55
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:36:31
TOTAL: 44.8 MB10.2 MB15.0 MB0.0 KB/s3.6 KB94:36:18
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
143.195.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:05
2204.76.203.7.ptr.pfcloud.network 2 00.0 KB 0 0 000:00:02
3106.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4193.124.20.249 1 00.0 KB 0 0 000:00:00
5194.88.98.104 1 00.0 KB 0 0 000:00:00
6198.235.24.107 1 00.0 KB 0 0 000:00:00
764.89.162.15 1 00.0 KB 0 0 000:00:09
8griffin.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
9osborn.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
TOTAL: 9 12 00.0 KB 0 0 000:00:22

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:05
2unresolved 4 00.0 KB 0 0 000:00:11
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
4*.ptr.pfcloud.network 2 00.0 KB 0 0 000:00:02
TOTAL: 4 12 00.0 KB 0 0 000:00:22

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
64.89.162.15 0.004 0.015 0.00000:00:09
43.195.132.66.censys-scanner.com 0.035 0.024 0.00000:00:05
griffin.probe.onyphe.net 0.004 0.003 0.00000:00:02
204.76.203.7.ptr.pfcloud.network 0.007 0.018 0.00000:00:02
osborn.probe.onyphe.net 0.006 0.013 0.00000:00:00
106.195.132.66.censys-scanner.com 0.025 0.013 0.00000:00:00
198.235.24.107 0.005 0.013 0.00000:00:00
193.124.20.249 0.002 0.015 0.00000:00:00
194.88.98.104 0.003 0.006 0.00000:00:00
TOTAL: 9 0.091 0.120 0.00000:00:22

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

SystemConn
204.76.203.7.ptr.pfcloud.network 5
osborn.probe.onyphe.net 3
106.195.132.66.censys-scanner.com 1
43.195.132.66.censys-scanner.com 1
64.89.162.15 1
TOTAL: 5 11

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

CommandCount
EHLO www.censys.io 2
#014H7 1
#026#003#003#002c#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
Accept-Encoding: gzip 1
Connection: close 1
GET http://azenv.net/ HTTP/1.1 1
Host: azenv.net 1
SSH-2.0-OpenSSH_10.2 1
User-Agent: Go-http-client/1.1 1
TOTAL: 10 11

NNRP client timeouts [Top 20]:

SystemConnPeer
194.88.98.104 1 1
43.195.132.66.censys-scanner.com 1 2
TOTAL: 2 2 6
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