Daily Usenet report

Aug 9 03:00:03 -- Aug 10 03:00:03

Unknown entries from news log file:

First 50 / 66 lines (75.8%)

Aug 9 03:00:54 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 9 05:48:42 twilightnode nnrpd[967506]: Reading access from /usr/local/news/etc/readers.conf
Aug 9 05:48:42 twilightnode nnrpd[967506]: Auth strategy 'localhost' does not match client. Removing.
Aug 9 05:48:42 twilightnode nnrpd[967506]: r3-111-6f.monitoring.internet-measurement.com res <anonymous>
Aug 9 05:48:42 twilightnode nnrpd[967506]: r3-111-6f.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Aug 9 05:48:42 twilightnode nnrpd[967506]: r3-111-6f.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 9 05:48:42 twilightnode nnrpd[967506]: r3-111-6f.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Aug 9 05:48:42 twilightnode nnrpd[967506]: r3-111-6f.monitoring.internet-measurement.com no_post anonymous-read
Aug 9 09:11:28 twilightnode nnrpd[976664]: Reading access from /usr/local/news/etc/readers.conf
Aug 9 09:11:28 twilightnode nnrpd[976664]: twilightnode.org res <localhost>
Aug 9 09:11:28 twilightnode nnrpd[976664]: twilightnode.org match_user <localhost> <localhost>
Aug 9 16:38:13 twilightnode nnrpd[996426]: Reading access from /usr/local/news/etc/readers.conf
Aug 9 16:38:13 twilightnode nnrpd[996426]: Auth strategy 'localhost' does not match client. Removing.
Aug 9 16:38:13 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com res <anonymous>
Aug 9 16:38:13 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 9 16:38:13 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 9 16:38:13 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 9 16:38:13 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com no_post anonymous-read
Aug 9 16:38:15 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com res <anonymous>
Aug 9 16:38:15 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 9 16:38:15 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 9 16:38:15 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 9 16:38:15 twilightnode nnrpd[996426]: 231.224.132.66.censys-scanner.com no_post anonymous-read
Aug 9 18:09:32 twilightnode nnrpd[1000587]: Reading access from /usr/local/news/etc/readers.conf
Aug 9 18:09:32 twilightnode nnrpd[1000587]: Auth strategy 'localhost' does not match client. Removing.
Aug 9 18:09:32 twilightnode nnrpd[1000587]: 205.210.31.225 res <anonymous>
Aug 9 18:09:32 twilightnode nnrpd[1000587]: 205.210.31.225 no_match_user <anonymous> <localhost>
Aug 9 18:09:32 twilightnode nnrpd[1000587]: 205.210.31.225 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 9 18:09:32 twilightnode nnrpd[1000587]: 205.210.31.225 match_user <anonymous> <anonymous>
Aug 9 18:09:32 twilightnode nnrpd[1000587]: 205.210.31.225 no_post anonymous-read
Aug 9 18:20:15 twilightnode pgpverify[1001122]: pgpverify: verification failed
Aug 10 00:11:18 twilightnode nnrpd[1019768]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 00:11:18 twilightnode nnrpd[1019768]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 00:11:18 twilightnode nnrpd[1019768]: 43.98.185.242 res <anonymous>
Aug 10 00:11:18 twilightnode nnrpd[1019768]: 43.98.185.242 no_match_user <anonymous> <localhost>
Aug 10 00:11:18 twilightnode nnrpd[1019768]: 43.98.185.242 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 00:11:18 twilightnode nnrpd[1019768]: 43.98.185.242 match_user <anonymous> <anonymous>
Aug 10 00:11:18 twilightnode nnrpd[1019768]: 43.98.185.242 no_post anonymous-read
Aug 10 00:11:19 twilightnode nnrpd[1019769]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 00:11:19 twilightnode nnrpd[1019769]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 00:11:19 twilightnode nnrpd[1019769]: 43.98.185.242 res <anonymous>
Aug 10 00:11:19 twilightnode nnrpd[1019769]: 43.98.185.242 no_match_user <anonymous> <localhost>
Aug 10 00:11:19 twilightnode nnrpd[1019769]: 43.98.185.242 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 00:11:19 twilightnode nnrpd[1019769]: 43.98.185.242 match_user <anonymous> <anonymous>
Aug 10 00:11:19 twilightnode nnrpd[1019769]: 43.98.185.242 no_post anonymous-read
Aug 10 00:11:24 twilightnode nnrpd[1019770]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 00:11:24 twilightnode nnrpd[1019770]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 00:11:24 twilightnode nnrpd[1019770]: 43.98.185.242 res <anonymous>
Aug 10 00:11:24 twilightnode nnrpd[1019770]: 43.98.185.242 no_match_user <anonymous> <localhost>
Aug 10 00:11:24 twilightnode nnrpd[1019770]: 43.98.185.242 no_match_user <anonymous> *,!<anonymous>,!<localhost>

Log entries by program:

Program nameLines%LinesSize%Size
inn 6140 64.8%926.4 KB 67.2%
innd 1932 20.4%248.4 KB 18.0%
innfeed 1280 13.5%189.0 KB 13.7%
nnrpd 106 1.1%11.3 KB 0.8%
controlchan 14 0.1%3.5 KB 0.3%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 6 9473 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13730 48.6%
Negative hits 8366 29.6%
Do not exist 6103 21.6%
Cache misses 30 0.1%
TOTAL: 4 28229 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.006 0.0%3 2.000 2.000 2.000
article cleanup00:00:00.056 0.0%6119 0.000 0.009 0.100
article logging00:00:00.226 0.0%6141 0.000 0.037 0.250
article parse00:00:00.082 0.0%8060 0.000 0.010 0.094
article write00:00:03.889 0.0%4396 0.459 0.885 2.000
data move00:00:00.071 0.0%29878 0.000 0.002 0.027
hisgrep/artcncl00:00:00.001 0.0%3 0.000 0.333 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.403 0.0%28305 0.064 0.120 0.231
history sync00:00:00.048 0.0%316 0.000 0.152 1.000
history write00:00:02.658 0.0%6119 0.083 0.434 0.844
idle24:00:03.304100.0%29932 1224.045 2886.653 14638.049
nntp read00:00:00.522 0.0%29654 0.000 0.018 0.075
overview write00:00:02.011 0.0%4396 0.152 0.457 1.400
perl filter00:00:08.802 0.0%6115 0.654 1.439 6.037
python filter00:00:00.570 0.0%6116 0.000 0.093 0.578
site send00:00:00.105 0.0%4371 0.000 0.024 0.100
TOTAL: 1724:00:25.754100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.014 0.0%4538 0.000 0.003 0.077
article prepare00:00:00.002 0.0%15900 0.000 0.000 0.013
article read00:00:00.505 0.0%2938 0.000 0.172 1.333
backlog stats00:00:00.042 0.0%50299 0.000 0.001 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.300 0.0%26777 0.000 0.011 0.148
data write00:00:00.555 0.0%22293 0.000 0.025 0.065
idle23:58:25.729100.0%50299 346.597 1715.854 5827.184
status file00:00:00.166 0.0%259 0.000 0.641 3.000
TOTAL: 923:58:27.313100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:26.134100.0%15 3.250 1742.267 5173.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%35 0.000 0.000 0.000
TOTAL: 300:00:26.134100.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@usenet-fr.news.eu.org001101
TOTAL001101

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 7 10592 3989 5411 1192 37%45:47:47
2newsfeed.endofthelinebbs.com 1 3137 125 2712 300 3%17:27:51
3news.tebibyte.org 9 4333 75 4232 26 1%24:07:41
4news.sklaffkom.se 34 68 25 13 30 36%08:10:28
5localhost 26 1 1 0 0 100%00:00:00
6news.twilightnode.org 1 0 0 0 0 0%00:00:00
TOTAL: 6 78 18131 4215 12368 1548 23%95:33:47
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.4 MB0.0 KB4.4 MB17.8 MB 75%3.5 KB
2newsfeed.endofthelinebbs.com677.7 KB0.0 KB457.3 KB1.1 MB 59%2.7 KB
3news.tebibyte.org179.9 KB0.0 KB74.8 KB254.7 KB 70%2.5 KB
4news.sklaffkom.se70.7 KB0.0 KB84.4 KB155.1 KB 45%2.8 KB
5localhost0.8 KB0.0 KB0.0 KB0.8 KB100%0.8 KB
6news.twilightnode.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 614.3 MB0.0 KB5.0 MB19.3 MB 74%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 9 03:00:03 - 03:59:59 96 2.2% 0.03310.2 KB 2.0% 0.09
Aug 9 04:00:00 - 04:59:59 103 2.3% 0.03307.8 KB 2.0% 0.09
Aug 9 05:00:00 - 05:59:59 66 1.5% 0.02175.3 KB 1.1% 0.05
Aug 9 06:00:00 - 06:59:59 84 1.9% 0.02201.4 KB 1.3% 0.06
Aug 9 07:00:00 - 07:59:59 112 2.5% 0.03271.1 KB 1.8% 0.08
Aug 9 08:00:00 - 08:59:59 148 3.4% 0.04418.1 KB 2.7% 0.12
Aug 9 09:00:00 - 09:59:59 182 4.1% 0.05624.5 KB 4.0% 0.17
Aug 9 10:00:00 - 10:59:59 222 5.1% 0.06583.2 KB 3.8% 0.16
Aug 9 11:00:00 - 11:59:59 236 5.4% 0.07708.3 KB 4.6% 0.20
Aug 9 12:00:00 - 12:59:59 192 4.4% 0.05535.4 KB 3.5% 0.15
Aug 9 13:00:00 - 13:59:59 261 5.9% 0.07745.1 KB 4.8% 0.21
Aug 9 14:00:00 - 14:59:59 247 5.6% 0.07583.7 KB 3.8% 0.16
Aug 9 15:00:00 - 15:59:59 235 5.3% 0.07628.2 KB 4.1% 0.17
Aug 9 16:00:00 - 16:59:59 246 5.6% 0.07692.5 KB 4.5% 0.19
Aug 9 17:00:00 - 17:59:59 268 6.1% 0.07965.3 KB 6.3% 0.27
Aug 9 18:00:00 - 18:59:59 228 5.2% 0.06772.8 KB 5.0% 0.21
Aug 9 19:00:00 - 19:59:59 202 4.6% 0.06557.3 KB 3.6% 0.15
Aug 9 20:00:00 - 20:59:59 241 5.5% 0.07638.6 KB 4.1% 0.18
Aug 9 21:00:00 - 21:59:59 211 4.8% 0.06714.2 KB 4.6% 0.20
Aug 9 22:00:00 - 22:59:59 180 4.1% 0.052.0 MB 13.1% 0.56
Aug 9 23:00:00 - 23:59:59 173 3.9% 0.05508.3 KB 3.3% 0.14
Aug 10 00:00:00 - 00:59:59 152 3.5% 0.041.5 MB 9.6% 0.41
Aug 10 01:00:00 - 01:59:59 150 3.4% 0.04521.3 KB 3.4% 0.14
Aug 10 02:00:00 - 02:59:59 151 3.4% 0.04427.2 KB 2.8% 0.12
Aug 10 03:00:00 - 03:00:03 9 0.2% 3.0019.5 KB 0.1% 6.52
TOTAL: 24:00:00 4395 100.0% 0.0515.1 MB 100.0% 0.18
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1240 941 0 0 0 0 0 0 299
2newsfeed.endofthelinebbs.com 403 77 0 0 0 4 0 0 322
3news.tebibyte.org 72 69 0 0 0 0 0 0 3
4news.sklaffkom.se 30 30 0 0 0 0 0 0 0
TOTAL: 4 1745 1117 0 0 0 4 0 0 624

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.legal.moderated 56
de.alt.talk.unmut 51
alt.usage.english 35
de.test 33
alt.checkmate 32
hfx.general 32
de.alt.folklore.computer 29
alt.12step.pedo.derek-mcmurray 26
aus.politics 25
fr.sci.physique 25
bln.test 24
eternal-september.talk 24
alt.atheism 23
alt.privacy.anon-server 23
pl.soc.prawo 23
de.etc.haushalt 19
alt.recovery.aa 18
alt.slack 18
pl.pregierz 18
spline.eisfair 18
TOTAL: 227 1117

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 327
EMP (phn path) 214
User-issued cancel 39
Binary: misplaced binary 11
HTML Multipart 9
Too many newsgroups 2
TOTAL: 6 602

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6121 1035 3318 14 0 16 16%23:59:55
2sklaffkom.se 4509 891 1485 1994 0 0 19%23:59:55
3newsfeed.endofthelinebbs.com 8087 30 4131 17 0 165 0%23:59:55
4weretis.net 328 0 313 1 0 0 0%18:41:58
TOTAL: 4 19045 1956 9247 2026 0 181 10%90:41:43
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.6 MB27.0 KB2.6 MB0.0 KB/s2.6 KB23:59:55
2sklaffkom.se1.4 MB6.5 MB7.9 MB0.1 KB/s2.8 KB23:59:55
3newsfeed.endofthelinebbs.com89.7 KB38.6 KB128.3 KB0.0 KB/s2.7 KB23:59:55
4weretis.net0.0 KB0.8 KB0.8 KB0.0 KB/s0.8 KB18:41:58
TOTAL: 44.1 MB6.5 MB10.6 MB0.0 KB/s2.7 KB90:41:43
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
143.98.185.242 3 00.0 KB 0 0 000:00:15
2205.210.31.225 1 00.0 KB 0 0 000:00:02
3231.224.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
4o061.scanner.modat.io 1 00.0 KB 0 0 000:00:00
5r3-111-6f.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
6r3-131-83.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
7twilightnode.org 1 00.0 KB 0 1 000:00:00
TOTAL: 7 9 00.0 KB 0 1 000:00:26

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 4 00.0 KB 0 0 000:00:18
2*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
4*.org 1 00.0 KB 0 1 000:00:00
5*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 5 9 00.0 KB 0 1 000:00:26

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
43.98.185.242 0.010 0.026 0.00000:00:15
231.224.132.66.censys-scanner.com 0.037 0.017 0.00000:00:03
205.210.31.225 0.004 0.009 0.00000:00:02
r3-131-83.monitoring.internet-measurement.com 0.002 0.013 0.00000:00:01
r3-111-6f.monitoring.internet-measurement.com 0.004 0.014 0.00000:00:01
o061.scanner.modat.io 0.002 0.012 0.00000:00:00
twilightnode.org 0.004 0.009 0.00000:00:00
TOTAL: 7 0.063 0.100 0.00000:00:26

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

SystemConn
43.98.185.242 4
231.224.132.66.censys-scanner.com 1
TOTAL: 2 5

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

CommandCount
#026#003#001 1
.?x}?w6+1?k4?Q yRD??1RD?E#023V]?J?u... 1
?#024 1
?#?' 1
EHLO www.censys.io 1
TOTAL: 5 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