Daily Usenet report

Jun 27 03:00:05 -- Jun 28 03:00:06

Unknown entries from news log file:

First 50 / 83 lines (60.2%)

Jun 27 03:01:22 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 27 08:11:18 twilightnode nnrpd[2754124]: Reading access from /usr/local/news/etc/readers.conf
Jun 27 08:11:18 twilightnode nnrpd[2754124]: Auth strategy 'localhost' does not match client. Removing.
Jun 27 08:11:18 twilightnode nnrpd[2754124]: azpdsszdcz7i.stretchoid.com res <anonymous>
Jun 27 08:11:18 twilightnode nnrpd[2754124]: azpdsszdcz7i.stretchoid.com no_match_user <anonymous> <localhost>
Jun 27 08:11:18 twilightnode nnrpd[2754124]: azpdsszdcz7i.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 27 08:11:18 twilightnode nnrpd[2754124]: azpdsszdcz7i.stretchoid.com match_user <anonymous> <anonymous>
Jun 27 08:11:18 twilightnode nnrpd[2754124]: azpdsszdcz7i.stretchoid.com no_post anonymous-read
Jun 27 08:11:28 twilightnode nnrpd[2754125]: Reading access from /usr/local/news/etc/readers.conf
Jun 27 08:11:28 twilightnode nnrpd[2754125]: Auth strategy 'localhost' does not match client. Removing.
Jun 27 08:11:28 twilightnode nnrpd[2754125]: azpdsszdcz7i.stretchoid.com res <anonymous>
Jun 27 08:11:28 twilightnode nnrpd[2754125]: azpdsszdcz7i.stretchoid.com no_match_user <anonymous> <localhost>
Jun 27 08:11:28 twilightnode nnrpd[2754125]: azpdsszdcz7i.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 27 08:11:28 twilightnode nnrpd[2754125]: azpdsszdcz7i.stretchoid.com match_user <anonymous> <anonymous>
Jun 27 08:11:28 twilightnode nnrpd[2754125]: azpdsszdcz7i.stretchoid.com no_post anonymous-read
Jun 27 08:33:20 twilightnode nnrpd[2754964]: Reading access from /usr/local/news/etc/readers.conf
Jun 27 08:33:20 twilightnode nnrpd[2754964]: Auth strategy 'localhost' does not match client. Removing.
Jun 27 08:33:20 twilightnode nnrpd[2754964]: 89.21.67.145 res <anonymous>
Jun 27 08:33:20 twilightnode nnrpd[2754964]: 89.21.67.145 no_match_user <anonymous> <localhost>
Jun 27 08:33:20 twilightnode nnrpd[2754964]: 89.21.67.145 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 27 08:33:20 twilightnode nnrpd[2754964]: 89.21.67.145 match_user <anonymous> <anonymous>
Jun 27 08:33:20 twilightnode nnrpd[2754964]: 89.21.67.145 no_post anonymous-read
Jun 27 08:33:33 twilightnode nnrpd[2754968]: Reading access from /usr/local/news/etc/readers.conf
Jun 27 08:33:33 twilightnode nnrpd[2754968]: Auth strategy 'localhost' does not match client. Removing.
Jun 27 08:33:33 twilightnode nnrpd[2754968]: 193.176.29.3 res <anonymous>
Jun 27 08:33:33 twilightnode nnrpd[2754968]: 193.176.29.3 no_match_user <anonymous> <localhost>
Jun 27 08:33:33 twilightnode nnrpd[2754968]: 193.176.29.3 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 27 08:33:33 twilightnode nnrpd[2754968]: 193.176.29.3 match_user <anonymous> <anonymous>
Jun 27 08:33:33 twilightnode nnrpd[2754968]: 193.176.29.3 no_post anonymous-read
Jun 27 08:40:45 twilightnode nnrpd[2755387]: Reading access from /usr/local/news/etc/readers.conf
Jun 27 08:40:45 twilightnode nnrpd[2755387]: Auth strategy 'localhost' does not match client. Removing.
Jun 27 08:40:45 twilightnode nnrpd[2755387]: 45-79-207-129.ip.linodeusercontent.com res <anonymous>
Jun 27 08:40:45 twilightnode nnrpd[2755387]: 45-79-207-129.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 27 08:40:45 twilightnode nnrpd[2755387]: 45-79-207-129.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 27 08:40:45 twilightnode nnrpd[2755387]: 45-79-207-129.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 27 08:40:45 twilightnode nnrpd[2755387]: 45-79-207-129.ip.linodeusercontent.com no_post anonymous-read
Jun 27 11:09:47 twilightnode nnrpd[2761519]: Reading access from /usr/local/news/etc/readers.conf
Jun 27 11:09:47 twilightnode nnrpd[2761519]: Auth strategy 'localhost' does not match client. Removing.
Jun 27 11:09:47 twilightnode nnrpd[2761519]: r5-250-fa.monitoring.internet-measurement.com res <anonymous>
Jun 27 11:09:47 twilightnode nnrpd[2761519]: r5-250-fa.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Jun 27 11:09:47 twilightnode nnrpd[2761519]: r5-250-fa.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 27 11:09:47 twilightnode nnrpd[2761519]: r5-250-fa.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Jun 27 11:09:47 twilightnode nnrpd[2761519]: r5-250-fa.monitoring.internet-measurement.com no_post anonymous-read
Jun 27 13:42:16 twilightnode nnrpd[2768113]: Reading access from /usr/local/news/etc/readers.conf
Jun 27 13:42:16 twilightnode nnrpd[2768113]: Auth strategy 'localhost' does not match client. Removing.
Jun 27 13:42:16 twilightnode nnrpd[2768113]: 147.185.132.55 res <anonymous>
Jun 27 13:42:16 twilightnode nnrpd[2768113]: 147.185.132.55 no_match_user <anonymous> <localhost>
Jun 27 13:42:16 twilightnode nnrpd[2768113]: 147.185.132.55 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 27 13:42:16 twilightnode nnrpd[2768113]: 147.185.132.55 match_user <anonymous> <anonymous>
Jun 27 13:42:16 twilightnode nnrpd[2768113]: 147.185.132.55 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 39015 82.1%5.3 MB 77.6%
inn 6478 13.6%1.2 MB 17.3%
innd 1890 4.0%345.0 KB 4.9%
nnrpd 129 0.3%14.8 KB 0.2%
controlchan 10 0.0%1.7 KB 0.0%
TOTAL: 5 47522 100.0%6.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15607 51.6%
Negative hits 8176 27.0%
Do not exist 6428 21.3%
Cache misses 19 0.1%
TOTAL: 4 30230 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%0 0.000 0.000 0.000
article cleanup00:00:00.075 0.0%6461 0.000 0.012 0.091
article logging00:00:00.216 0.0%6475 0.000 0.033 0.375
article parse00:00:00.102 0.0%9151 0.000 0.011 0.143
article write00:00:04.720 0.0%4248 0.489 1.111 9.722
data move00:00:00.085 0.0%30835 0.000 0.003 0.024
hisgrep/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.202 0.0%30299 0.073 0.205 1.961
history sync00:00:00.071 0.0%412 0.000 0.172 3.000
history write00:00:03.801 0.0%6443 0.167 0.590 1.514
idle24:00:29.386100.0%31133 1020.702 2776.134 20688.931
nntp read00:00:00.676 0.0%30765 0.000 0.022 0.122
overview write00:00:02.245 0.0%4248 0.133 0.528 2.923
perl filter00:00:09.433 0.0%6443 0.663 1.464 22.000
python filter00:00:00.689 0.0%6443 0.000 0.107 1.667
site send00:00:00.117 0.0%4256 0.000 0.027 0.231
TOTAL: 1724:00:57.818100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.037 0.0%9473 0.000 0.004 0.109
article prepare00:00:00.002 0.0%25776 0.000 0.000 0.005
article read00:00:00.620 0.0%4227 0.000 0.147 1.083
backlog stats00:00:00.060 0.0%65485 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.509 0.0%31603 0.000 0.016 0.068
data write00:00:00.718 0.0%27468 0.000 0.026 0.067
idle23:49:39.355100.0%65485 1001.972 1309.908 2190.814
status file00:00:10.422 0.0%16024 0.439 0.650 2.667
TOTAL: 923:49:51.723100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:17.235100.0%14 45.000 1231.071 10401.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%42 0.000 0.024 0.500
TOTAL: 300:00:17.236100.0%----

Control commands to innd:

CommandNumber
flush 3
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 167

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de080008
TOTAL080008

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 15 13460 4144 7875 1441 30%59:09:52
2newsfeed.endofthelinebbs.com 4 4656 223 3828 605 4%23:51:44
3news.sklaffkom.se 40 119 63 26 30 52%11:49:29
4news.tebibyte.org 10 2586 45 2493 48 1%14:16:25
5localhost 25 0 0 0 0 0%00:00:00
6news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 6 95 20821 4475 14222 2124 21%109:17:30
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.8 MB0.0 KB5.1 MB17.0 MB 69%3.1 KB
2newsfeed.endofthelinebbs.com1.9 MB26.3 KB3.0 MB4.9 MB 38%6.1 KB
3news.sklaffkom.se607.6 KB0.0 KB57.5 KB665.1 KB 91%7.2 KB
4news.tebibyte.org183.5 KB0.0 KB200.0 KB383.5 KB 47%4.1 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
6news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 614.5 MB26.3 KB8.4 MB22.9 MB 63%3.6 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 27 03:00:05 - 03:59:59 84 2.0% 0.02376.4 KB 2.6% 0.10
Jun 27 04:00:00 - 04:59:59 89 2.1% 0.02360.5 KB 2.5% 0.10
Jun 27 05:00:00 - 05:59:59 83 2.0% 0.02228.5 KB 1.6% 0.06
Jun 27 06:00:00 - 06:59:59 127 3.0% 0.04339.5 KB 2.4% 0.09
Jun 27 07:00:00 - 07:59:59 111 2.6% 0.03254.6 KB 1.8% 0.07
Jun 27 08:00:00 - 08:59:59 137 3.2% 0.04326.6 KB 2.3% 0.09
Jun 27 09:00:00 - 09:59:59 187 4.4% 0.05482.3 KB 3.4% 0.13
Jun 27 10:00:00 - 10:59:59 227 5.3% 0.06555.6 KB 3.9% 0.15
Jun 27 11:00:00 - 11:59:59 258 6.1% 0.07767.8 KB 5.4% 0.21
Jun 27 12:00:00 - 12:59:59 232 5.5% 0.06633.2 KB 4.4% 0.18
Jun 27 13:00:00 - 13:59:59 193 4.5% 0.051003.5 KB 7.0% 0.28
Jun 27 14:00:00 - 14:59:59 211 5.0% 0.06540.5 KB 3.8% 0.15
Jun 27 15:00:00 - 15:59:59 230 5.4% 0.06627.2 KB 4.4% 0.17
Jun 27 16:00:00 - 16:59:59 227 5.3% 0.06808.6 KB 5.7% 0.22
Jun 27 17:00:00 - 17:59:59 205 4.8% 0.061.2 MB 8.9% 0.35
Jun 27 18:00:00 - 18:59:59 200 4.7% 0.06498.3 KB 3.5% 0.14
Jun 27 19:00:00 - 19:59:59 237 5.6% 0.07813.1 KB 5.7% 0.23
Jun 27 20:00:00 - 20:59:59 229 5.4% 0.06635.1 KB 4.5% 0.18
Jun 27 21:00:00 - 21:59:59 194 4.6% 0.05580.1 KB 4.1% 0.16
Jun 27 22:00:00 - 22:59:59 166 3.9% 0.05597.8 KB 4.2% 0.17
Jun 27 23:00:00 - 23:59:59 176 4.1% 0.05758.2 KB 5.3% 0.21
Jun 28 00:00:00 - 00:59:59 148 3.5% 0.04624.1 KB 4.4% 0.17
Jun 28 01:00:00 - 01:59:59 138 3.2% 0.04549.5 KB 3.9% 0.15
Jun 28 02:00:00 - 02:59:59 148 3.5% 0.04585.4 KB 4.1% 0.16
Jun 28 03:00:00 - 03:00:06 14 0.3% 2.3342.7 KB 0.3% 7.11
TOTAL: 24:00:01 4251 100.0% 0.0513.9 MB 100.0% 0.17
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1457 1079 0 0 0 0 0 0 378
2newsfeed.endofthelinebbs.com 597 52 0 18 0 0 0 0 527
3news.tebibyte.org 143 11 0 0 0 0 0 0 132
4news.sklaffkom.se 30 27 0 0 0 0 0 0 3
TOTAL: 4 2227 1169 0 18 0 0 0 0 1040

Unwanted newsgroups [Top 20]:

NewsgroupCount
fido7.ru.fidonet.today 44
alt.usage.english 43
fido7.ru.news 38
fido7.ru.home 35
muc.lists.freebsd.bugs 35
fr.sci.zetetique 26
pl.soc.prawo 23
de.comm.provider.usenet 22
fr.sci.maths 21
lada.talk 21
de.talk.jokes 20
uk.legal.moderated 20
uk.tech.digital-tv 20
alt.bestjobsusa.computer.jobs 18
de.alt.comm.android 18
de.alt.folklore.ddr 18
pl.biznes.banki 17
linux.debian.changes 16
linux.debian.maint.emacsen 16
pl.pregierz 16
TOTAL: 208 1169

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 592
EMP (phn path) 316
User-issued cancel 87
EMP (phl) 26
Too many newsgroups 5
TOTAL: 5 1026

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4252 4191 0 60 0 0 98%23:59:56
2sklaffkom.se 4536 1077 2013 1154 0 0 23%23:59:52
3news.tebibyte.org 2838 0 0 0 0 7079 0%23:59:56
4newsfeed.endofthelinebbs.com 2445 0 0 0 0 6470 0%23:59:56
5weretis.net 395 0 349 0 0 1 0%20:18:21
6sklaff.dev 0 0 0 0 0 4241 0%23:59:35
7thebunker.nu 0 0 0 0 0 4251 0%23:59:56
8usenet.blueworldhosting.com 0 0 0 0 0 3949 0%23:59:56
TOTAL: 8 14466 5268 2362 1214 0 25991 36%188:17:28
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se13.9 MB83.7 KB13.9 MB0.2 KB/s3.4 KB23:59:56
2sklaffkom.se1.7 MB5.1 MB6.8 MB0.1 KB/s3.1 KB23:59:52
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
4newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
5sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:35
6thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
7usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
8weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s020:18:21
TOTAL: 815.5 MB5.1 MB20.7 MB0.0 KB/s3.3 KB188:17:28
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1azpdsszdcz7i.stretchoid.com 2 00.0 KB 0 0 000:00:10
2147.185.132.55 1 00.0 KB 0 0 000:00:00
3172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
4193.176.29.3 1 00.0 KB 0 0 000:00:00
545-79-207-129.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
659.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
789.21.67.145 1 00.0 KB 0 0 000:00:00
8o332.scanner.modat.io 1 00.0 KB 0 0 000:00:00
9o349.scanner.modat.io 1 00.0 KB 0 0 000:00:00
10r5-250-fa.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 10 11 00.0 KB 0 0 000:00:17

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 00.0 KB 0 0 000:00:01
2*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
5*.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
6*.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
7*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 7 11 00.0 KB 0 0 000:00:17

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdsszdcz7i.stretchoid.com 0.008 0.030 0.00000:00:10
r5-250-fa.monitoring.internet-measurement.com 0.003 0.013 0.00000:00:01
45-79-207-129.ip.linodeusercontent.com 0.005 0.011 0.00000:00:01
59.195.132.66.censys-scanner.com 0.049 0.025 0.00000:00:01
193.176.29.3 0.002 0.013 0.00000:00:00
147.185.132.55 0.003 0.014 0.00000:00:00
o349.scanner.modat.io 0.003 0.014 0.00000:00:00
172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 0.005 0.013 0.00000:00:00
89.21.67.145 0.007 0.009 0.00000:00:00
o332.scanner.modat.io 0.006 0.005 0.00000:00:00
TOTAL: 10 0.091 0.147 0.00000:00:17

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

SystemConn
59.195.132.66.censys-scanner.com 1
azpdsszdcz7i.stretchoid.com 1
TOTAL: 2 2

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

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

NNRP client timeouts [Top 20]:

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