Daily Usenet report

Jun 20 03:00:06 -- Jun 21 03:00:04

Unknown entries from news log file:

First 50 / 202 lines (24.8%)

Jun 20 03:01:19 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 20 03:05:44 twilightnode nnrpd[2282518]: Reading access from /usr/local/news/etc/readers.conf
Jun 20 03:05:44 twilightnode nnrpd[2282518]: Auth strategy 'localhost' does not match client. Removing.
Jun 20 03:05:44 twilightnode nnrpd[2282518]: 172-234-237-248.ip.linodeusercontent.com res <anonymous>
Jun 20 03:05:44 twilightnode nnrpd[2282518]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 20 03:05:44 twilightnode nnrpd[2282518]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 20 03:05:44 twilightnode nnrpd[2282518]: 172-234-237-248.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 20 03:05:44 twilightnode nnrpd[2282518]: 172-234-237-248.ip.linodeusercontent.com no_post anonymous-read
Jun 20 03:05:57 twilightnode nnrpd[2282527]: Reading access from /usr/local/news/etc/readers.conf
Jun 20 03:05:57 twilightnode nnrpd[2282527]: Auth strategy 'localhost' does not match client. Removing.
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com res <anonymous>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com no_post anonymous-read
Jun 20 03:05:57 twilightnode nnrpd[2282526]: Reading access from /usr/local/news/etc/readers.conf
Jun 20 03:05:57 twilightnode nnrpd[2282526]: Auth strategy 'localhost' does not match client. Removing.
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com res <anonymous>
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com no_post anonymous-read
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com res <anonymous>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com res <anonymous>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 20 03:05:57 twilightnode nnrpd[2282527]: 172-234-237-248.ip.linodeusercontent.com no_post anonymous-read
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 20 03:05:57 twilightnode nnrpd[2282526]: 172-234-237-248.ip.linodeusercontent.com no_post anonymous-read
Jun 20 03:06:29 twilightnode nnrpd[2282557]: Reading access from /usr/local/news/etc/readers.conf
Jun 20 03:06:29 twilightnode nnrpd[2282557]: Auth strategy 'localhost' does not match client. Removing.
Jun 20 03:06:29 twilightnode nnrpd[2282557]: 172-234-237-248.ip.linodeusercontent.com res <anonymous>
Jun 20 03:06:29 twilightnode nnrpd[2282557]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 20 03:06:29 twilightnode nnrpd[2282557]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 20 03:06:29 twilightnode nnrpd[2282557]: 172-234-237-248.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 20 03:06:29 twilightnode nnrpd[2282557]: 172-234-237-248.ip.linodeusercontent.com no_post anonymous-read
Jun 20 03:06:29 twilightnode nnrpd[2282556]: Reading access from /usr/local/news/etc/readers.conf
Jun 20 03:06:29 twilightnode nnrpd[2282556]: Auth strategy 'localhost' does not match client. Removing.
Jun 20 03:06:29 twilightnode nnrpd[2282556]: 172-234-237-248.ip.linodeusercontent.com res <anonymous>
Jun 20 03:06:29 twilightnode nnrpd[2282556]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 20 03:06:29 twilightnode nnrpd[2282556]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 20 03:06:29 twilightnode nnrpd[2282556]: 172-234-237-248.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 20 03:06:29 twilightnode nnrpd[2282556]: 172-234-237-248.ip.linodeusercontent.com no_post anonymous-read
Jun 20 03:06:29 twilightnode nnrpd[2282558]: Reading access from /usr/local/news/etc/readers.conf
Jun 20 03:06:29 twilightnode nnrpd[2282558]: Auth strategy 'localhost' does not match client. Removing.
Jun 20 03:06:29 twilightnode nnrpd[2282558]: 172-234-237-248.ip.linodeusercontent.com res <anonymous>
Jun 20 03:06:29 twilightnode nnrpd[2282558]: 172-234-237-248.ip.linodeusercontent.com no_match_user <anonymous> <localhost>

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 21413 72.0%3.0 MB 67.7%
inn 6399 21.5%1.1 MB 24.7%
innd 1619 5.4%307.5 KB 6.8%
nnrpd 303 1.0%34.6 KB 0.8%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 29736 100.0%4.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 9866 41.8%
Negative hits 7384 31.3%
Do not exist 6341 26.9%
Cache misses 4 0.0%
TOTAL: 4 23595 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.009 0.0%1 9.000 9.000 9.000
article cleanup00:00:00.083 0.0%6357 0.000 0.013 0.068
article logging00:00:00.281 0.0%6398 0.000 0.044 0.280
article parse00:00:00.103 0.0%8001 0.000 0.013 0.150
article write00:00:04.716 0.0%4031 0.333 1.170 10.758
data move00:00:00.086 0.0%24597 0.000 0.003 0.050
hisgrep/artcncl00:00:00.001 0.0%1 1.000 1.000 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.256 0.0%23654 0.114 0.264 2.978
history sync00:00:00.064 0.0%410 0.000 0.156 1.667
history write00:00:04.199 0.0%6357 0.231 0.661 4.444
idle24:00:36.750100.0%24662 1940.751 3504.856 17658.059
nntp read00:00:00.956 0.0%24277 0.000 0.039 1.459
overview write00:00:02.226 0.0%4031 0.091 0.552 3.800
perl filter00:00:11.616 0.0%6357 1.042 1.827 12.190
python filter00:00:00.983 0.0%6357 0.000 0.155 1.095
site send00:00:00.121 0.0%4031 0.000 0.030 0.235
TOTAL: 1724:01:08.450100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.029 0.0%6802 0.000 0.004 0.078
article prepare00:00:00.000 0.0%23684 0.000 0.000 0.000
article read00:00:00.712 0.0%4022 0.000 0.177 0.933
backlog stats00:00:00.050 0.0%46556 0.000 0.001 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.350 0.0%23155 0.000 0.015 0.039
data write00:00:00.601 0.0%19199 0.000 0.031 0.076
idle23:59:37.338100.0%46556 1108.657 1855.343 2892.053
status file00:00:06.710 0.0%8672 0.250 0.774 5.667
TOTAL: 923:59:45.790100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:14.258100.0%43 195.000 1726.930 9742.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.005 0.0%204 0.000 0.025 0.500
readart00:00:00.005 0.0%3 1.000 1.667 3.000
TOTAL: 400:01:14.268100.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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 2 12324 3814 6791 1719 30%51:40:03
2newsfeed.endofthelinebbs.com 2 4640 125 3927 588 2%23:45:56
3news.sklaffkom.se 43 136 61 34 41 44%12:37:53
4localhost 25 0 0 0 0 0%00:00:00
5news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 5 73 17100 4000 10752 2348 23%88:13:52
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.0 MB0.0 KB5.0 MB16.0 MB 68%3.0 KB
2newsfeed.endofthelinebbs.com334.4 KB0.0 KB2.7 MB3.0 MB 10%4.4 KB
3news.sklaffkom.se148.9 KB0.0 KB91.8 KB240.7 KB 61%2.4 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 511.5 MB0.0 KB7.8 MB19.2 MB 59%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 20 03:00:06 - 03:59:59 110 2.7% 0.03362.4 KB 3.1% 0.10
Jun 20 04:00:00 - 04:59:59 104 2.6% 0.03300.3 KB 2.6% 0.08
Jun 20 05:00:00 - 05:59:59 99 2.5% 0.03354.4 KB 3.0% 0.10
Jun 20 06:00:00 - 06:59:59 83 2.1% 0.02264.2 KB 2.3% 0.07
Jun 20 07:00:00 - 07:59:59 122 3.0% 0.03265.8 KB 2.3% 0.07
Jun 20 08:00:00 - 08:59:59 122 3.0% 0.03275.6 KB 2.4% 0.08
Jun 20 09:00:00 - 09:59:59 192 4.8% 0.05501.5 KB 4.3% 0.14
Jun 20 10:00:00 - 10:59:59 208 5.2% 0.06459.2 KB 3.9% 0.13
Jun 20 11:00:00 - 11:59:59 170 4.2% 0.05635.6 KB 5.4% 0.18
Jun 20 12:00:00 - 12:59:59 181 4.5% 0.05414.0 KB 3.5% 0.11
Jun 20 13:00:00 - 13:59:59 192 4.8% 0.05529.9 KB 4.5% 0.15
Jun 20 14:00:00 - 14:59:59 216 5.4% 0.06618.5 KB 5.3% 0.17
Jun 20 15:00:00 - 15:59:59 240 6.0% 0.07721.5 KB 6.2% 0.20
Jun 20 16:00:00 - 16:59:59 227 5.6% 0.06690.4 KB 5.9% 0.19
Jun 20 17:00:00 - 17:59:59 243 6.0% 0.07584.8 KB 5.0% 0.16
Jun 20 18:00:00 - 18:59:59 186 4.6% 0.05614.8 KB 5.3% 0.17
Jun 20 19:00:00 - 19:59:59 211 5.2% 0.06679.7 KB 5.8% 0.19
Jun 20 20:00:00 - 20:59:59 173 4.3% 0.05497.0 KB 4.3% 0.14
Jun 20 21:00:00 - 21:59:59 194 4.8% 0.05497.2 KB 4.3% 0.14
Jun 20 22:00:00 - 22:59:59 154 3.8% 0.04388.6 KB 3.3% 0.11
Jun 20 23:00:00 - 23:59:59 221 5.5% 0.06781.0 KB 6.7% 0.22
Jun 21 00:00:00 - 00:59:59 129 3.2% 0.04368.5 KB 3.2% 0.10
Jun 21 01:00:00 - 01:59:59 135 3.3% 0.04451.8 KB 3.9% 0.13
Jun 21 02:00:00 - 02:59:59 110 2.7% 0.03424.8 KB 3.6% 0.12
Jun 21 03:00:00 - 03:00:04 9 0.2% 2.257.3 KB 0.1% 1.82
TOTAL: 23:59:58 4031 100.0% 0.0511.4 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1729 1238 0 0 0 0 0 0 491
2newsfeed.endofthelinebbs.com 598 202 0 0 0 0 0 0 396
3news.sklaffkom.se 41 41 0 0 0 0 0 0 0
TOTAL: 3 2368 1481 0 0 0 0 0 0 887

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 83
de.comm.provider.usenet 69
de.alt.folklore.ddr 66
fido7.ru.military 51
linux.kernel.git 37
fido7.ukrnews 31
de.rec.fahrrad 30
de.alt.talk.unmut 28
fr.sci.maths 28
nl.politiek 25
bln.test 23
lada.talk 23
it.hobby.cucina 22
linux.gentoo.dev 22
uk.tech.digital-tv 21
cn.comp 19
eternal-september.talk 19
fido7.ru.home 19
it.discussioni.misteri 17
de.comp.hardware.netzwerke.misc 16
TOTAL: 235 1481

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 363
EMP (phl) 247
EMP (phn path) 201
User-issued cancel 30
HTML Multipart 3
Binary: misplaced binary 1
Too many newsgroups 1
TOTAL: 7 846

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4032 4031 0 0 0 0 99%23:59:54
2sklaffkom.se 4299 865 2122 1038 0 1 20%23:59:27
3newsfeed.endofthelinebbs.com 2779 0 0 0 0 6659 0%23:59:53
4weretis.net 232 0 205 0 0 0 0%16:45:00
5sklaff.dev 0 0 0 0 0 4025 0%23:59:53
6thebunker.nu 0 0 0 0 0 4031 0%23:59:54
7usenet.blueworldhosting.com 0 0 0 0 0 3684 0%23:59:54
TOTAL: 7 11342 4896 2327 1038 0 18400 43%160:43:55
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se11.4 MB0.0 KB11.4 MB0.1 KB/s2.9 KB23:59:54
2sklaffkom.se1.4 MB3.5 MB4.9 MB0.1 KB/s2.6 KB23:59:27
3newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
4sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
5thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
6usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
7weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s016:45:00
TOTAL: 712.8 MB3.5 MB16.3 MB0.0 KB/s2.8 KB160:43:55
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1valhalla.ausics.net 5 33.1 KB 3 0 000:00:28
TOTAL: 1 5 33.1 KB 3 0 000:00:28

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.ausics.net 6 33.1 KB 3 0 000:00:37
2*.ip.linodeusercontent.com 7 00.0 KB 0 0 000:00:04
3*.66.censys-scanner.com 6 00.0 KB 0 0 000:00:16
4unresolved 3 00.0 KB 0 0 000:00:15
5*.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
TOTAL: 5 23 33.1 KB 3 0 000:01:14

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
valhalla.ausics.net 0.019 0.061 0.00000:00:28
43.98.199.114 0.014 0.028 0.00000:00:15
169.186.132.66.censys-scanner.com 0.041 0.040 0.00000:00:11
ns3.ausics.net 0.003 0.013 0.00000:00:09
221.172.132.66.censys-scanner.com 0.045 0.031 0.00000:00:04
172-234-237-248.ip.linodeusercontent.com 0.171 0.072 0.00000:00:04
172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 0.003 0.019 0.00000:00:00
TOTAL: 7 0.296 0.264 0.00000:01:14

Curious NNRP explorers [Top 20]:

SystemConn
172-234-237-248.ip.linodeusercontent.com 7
169.186.132.66.censys-scanner.com 3
221.172.132.66.censys-scanner.com 3
43.98.199.114 3
172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 1
ns3.ausics.net 1
TOTAL: 6 18

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

SystemConn
43.98.199.114 4
169.186.132.66.censys-scanner.com 1
221.172.132.66.censys-scanner.com 1
TOTAL: 3 6

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

CommandCount
EHLO www.censys.io 2
#026#003#001 1
?#024 1
?#031?U#015?|?#004#002?"G; Q?(<?F?r#006?_?}?{=<-... 1
?#?' 1
TOTAL: 5 6

NNRP client timeouts [Top 20]:

SystemConnPeer
172-234-237-248.ip.linodeusercontent.com 2 2
169.186.132.66.censys-scanner.com 1 2
221.172.132.66.censys-scanner.com 1 2
TOTAL: 3 4 6

Newsgroup request counts (by hierarchy) [Top 100]:

HierarchyCountPct
1alt 1 33.3%
2aus 1 33.3%
3news 1 33.3%
TOTAL: 3 3100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1alt.os.linux.slackware 1
2aus.cars 1
3news.admin.peering 1
TOTAL: 3 3
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