Daily Usenet report

Aug 5 03:00:05 -- Aug 6 03:00:03

Unknown entries from news log file:

First 50 / 90 lines (55.6%)

Aug 5 03:01:28 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 5 04:45:53 twilightnode nnrpd[676399]: Reading access from /usr/local/news/etc/readers.conf
Aug 5 04:45:53 twilightnode nnrpd[676399]: Auth strategy 'localhost' does not match client. Removing.
Aug 5 04:45:53 twilightnode nnrpd[676399]: 5.252.83.34 res <anonymous>
Aug 5 04:45:53 twilightnode nnrpd[676399]: 5.252.83.34 no_match_user <anonymous> <localhost>
Aug 5 04:45:53 twilightnode nnrpd[676399]: 5.252.83.34 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 5 04:45:53 twilightnode nnrpd[676399]: 5.252.83.34 match_user <anonymous> <anonymous>
Aug 5 04:45:53 twilightnode nnrpd[676399]: 5.252.83.34 no_post anonymous-read
Aug 5 04:45:57 twilightnode nnrpd[676400]: Reading access from /usr/local/news/etc/readers.conf
Aug 5 04:45:57 twilightnode nnrpd[676400]: Auth strategy 'localhost' does not match client. Removing.
Aug 5 04:45:57 twilightnode nnrpd[676400]: 188.240.59.59 res <anonymous>
Aug 5 04:45:57 twilightnode nnrpd[676400]: 188.240.59.59 no_match_user <anonymous> <localhost>
Aug 5 04:45:57 twilightnode nnrpd[676400]: 188.240.59.59 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 5 04:45:57 twilightnode nnrpd[676400]: 188.240.59.59 match_user <anonymous> <anonymous>
Aug 5 04:45:57 twilightnode nnrpd[676400]: 188.240.59.59 no_post anonymous-read
Aug 5 06:34:20 twilightnode nnrpd[680821]: Reading access from /usr/local/news/etc/readers.conf
Aug 5 06:34:20 twilightnode nnrpd[680821]: Auth strategy 'localhost' does not match client. Removing.
Aug 5 06:34:20 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com res <anonymous>
Aug 5 06:34:20 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 5 06:34:20 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 5 06:34:20 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 5 06:34:20 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com no_post anonymous-read
Aug 5 06:34:30 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com res <anonymous>
Aug 5 06:34:30 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 5 06:34:30 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 5 06:34:30 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 5 06:34:30 twilightnode nnrpd[680821]: 124.195.132.66.censys-scanner.com no_post anonymous-read
Aug 5 14:51:03 twilightnode nnrpd[703021]: Reading access from /usr/local/news/etc/readers.conf
Aug 5 14:51:03 twilightnode nnrpd[703021]: Auth strategy 'localhost' does not match client. Removing.
Aug 5 14:51:03 twilightnode nnrpd[703021]: 198.235.24.178 res <anonymous>
Aug 5 14:51:03 twilightnode nnrpd[703021]: 198.235.24.178 no_match_user <anonymous> <localhost>
Aug 5 14:51:03 twilightnode nnrpd[703021]: 198.235.24.178 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 5 14:51:03 twilightnode nnrpd[703021]: 198.235.24.178 match_user <anonymous> <anonymous>
Aug 5 14:51:03 twilightnode nnrpd[703021]: 198.235.24.178 no_post anonymous-read
Aug 5 18:50:40 twilightnode nnrpd[713317]: Reading access from /usr/local/news/etc/readers.conf
Aug 5 18:50:40 twilightnode nnrpd[713317]: Auth strategy 'localhost' does not match client. Removing.
Aug 5 18:50:40 twilightnode nnrpd[713317]: prod-boron-sfo2-47.do.binaryedge.ninja res <anonymous>
Aug 5 18:50:40 twilightnode nnrpd[713317]: prod-boron-sfo2-47.do.binaryedge.ninja no_match_user <anonymous> <localhost>
Aug 5 18:50:40 twilightnode nnrpd[713317]: prod-boron-sfo2-47.do.binaryedge.ninja no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 5 18:50:40 twilightnode nnrpd[713317]: prod-boron-sfo2-47.do.binaryedge.ninja match_user <anonymous> <anonymous>
Aug 5 18:50:40 twilightnode nnrpd[713317]: prod-boron-sfo2-47.do.binaryedge.ninja no_post anonymous-read
Aug 5 21:29:11 twilightnode nnrpd[721374]: Reading access from /usr/local/news/etc/readers.conf
Aug 5 21:29:11 twilightnode nnrpd[721374]: Auth strategy 'localhost' does not match client. Removing.
Aug 5 21:29:11 twilightnode nnrpd[721374]: louisa.probe.onyphe.net res <anonymous>
Aug 5 21:29:11 twilightnode nnrpd[721374]: louisa.probe.onyphe.net no_match_user <anonymous> <localhost>
Aug 5 21:29:11 twilightnode nnrpd[721374]: louisa.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 5 21:29:11 twilightnode nnrpd[721374]: louisa.probe.onyphe.net match_user <anonymous> <anonymous>
Aug 5 21:29:11 twilightnode nnrpd[721374]: louisa.probe.onyphe.net no_post anonymous-read
Aug 5 21:29:12 twilightnode nnrpd[721375]: Reading access from /usr/local/news/etc/readers.conf
Aug 5 21:29:12 twilightnode nnrpd[721375]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 6866 73.1%1.0 MB 73.7%
innfeed 1203 12.8%177.9 KB 12.6%
innd 1180 12.6%176.9 KB 12.5%
nnrpd 149 1.6%15.5 KB 1.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 9399 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14955 49.0%
Negative hits 8679 28.5%
Do not exist 6802 22.3%
Cache misses 63 0.2%
TOTAL: 4 30499 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.004 0.0%2 2.000 2.000 2.000
article cleanup00:00:00.057 0.0%6816 0.000 0.008 0.167
article logging00:00:00.175 0.0%6862 0.000 0.026 0.167
article parse00:00:00.110 0.0%10351 0.000 0.011 0.167
article write00:00:04.580 0.0%5157 0.250 0.888 2.941
data move00:00:00.134 0.0%34088 0.000 0.004 0.045
hisgrep/artcncl00:00:00.004 0.0%2 2.000 2.000 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.522 0.0%30557 0.047 0.148 0.736
history sync00:00:00.040 0.0%313 0.000 0.128 1.000
history write00:00:03.240 0.0%6816 0.000 0.475 0.924
idle24:00:03.650100.0%32599 865.336 2650.500 28579.857
nntp read00:00:00.648 0.0%32355 0.000 0.020 0.093
overview write00:00:02.389 0.0%5157 0.097 0.463 5.375
perl filter00:00:08.532 0.0%6816 0.661 1.252 12.400
python filter00:00:00.497 0.0%6816 0.000 0.073 1.000
site send00:00:00.081 0.0%5139 0.000 0.016 0.143
TOTAL: 1724:00:28.663100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.016 0.0%6293 0.000 0.003 0.118
article prepare00:00:00.001 0.0%18284 0.000 0.000 0.005
article read00:00:00.641 0.0%3549 0.000 0.181 1.143
backlog stats00:00:00.031 0.0%55619 0.000 0.001 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.402 0.0%30109 0.000 0.013 0.057
data write00:00:00.611 0.0%24998 0.000 0.024 0.068
idle23:59:50.870100.0%55619 281.063 1553.262 6693.856
status file00:00:00.123 0.0%210 0.000 0.586 3.000
TOTAL: 923:59:52.695100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:23.027100.0%22 95.000 1046.682 5340.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%85 0.000 0.024 0.143
TOTAL: 300:00:23.029100.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 10552 4164 5158 1230 39%43:04:38
2newsfeed.endofthelinebbs.com 1 5556 696 4482 378 12%28:01:25
3news.tebibyte.org 1 4400 92 4267 41 2%21:38:08
4news.sklaffkom.se 43 95 69 16 10 72%10:25:15
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 73 20603 5021 13923 1659 24%103:09:26
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.1 MB0.0 KB4.1 MB18.2 MB 77%3.5 KB
2newsfeed.endofthelinebbs.com7.2 MB0.0 KB1.5 MB8.7 MB 82%8.3 KB
3news.tebibyte.org260.3 KB0.0 KB353.1 KB613.3 KB 42%4.6 KB
4news.sklaffkom.se185.9 KB0.0 KB20.9 KB206.9 KB 89%2.6 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 521.7 MB0.0 KB6.0 MB27.8 MB 78%4.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 5 03:00:05 - 03:59:59 130 2.5% 0.04482.6 KB 2.2% 0.13
Aug 5 04:00:00 - 04:59:59 99 1.9% 0.03448.2 KB 2.0% 0.12
Aug 5 05:00:00 - 05:59:59 101 2.0% 0.03518.6 KB 2.3% 0.14
Aug 5 06:00:00 - 06:59:59 138 2.7% 0.04499.3 KB 2.2% 0.14
Aug 5 07:00:00 - 07:59:59 130 2.5% 0.04457.9 KB 2.0% 0.13
Aug 5 08:00:00 - 08:59:59 174 3.4% 0.05561.6 KB 2.5% 0.16
Aug 5 09:00:00 - 09:59:59 203 3.9% 0.06770.6 KB 3.4% 0.21
Aug 5 10:00:00 - 10:59:59 297 5.8% 0.081.2 MB 5.6% 0.35
Aug 5 11:00:00 - 11:59:59 263 5.1% 0.07810.1 KB 3.6% 0.23
Aug 5 12:00:00 - 12:59:59 218 4.2% 0.06747.4 KB 3.3% 0.21
Aug 5 13:00:00 - 13:59:59 337 6.5% 0.093.0 MB 13.5% 0.84
Aug 5 14:00:00 - 14:59:59 229 4.4% 0.06643.2 KB 2.9% 0.18
Aug 5 15:00:00 - 15:59:59 246 4.8% 0.07905.1 KB 4.0% 0.25
Aug 5 16:00:00 - 16:59:59 347 6.7% 0.101.6 MB 7.1% 0.44
Aug 5 17:00:00 - 17:59:59 293 5.7% 0.081.2 MB 5.6% 0.35
Aug 5 18:00:00 - 18:59:59 296 5.7% 0.081.3 MB 5.8% 0.36
Aug 5 19:00:00 - 19:59:59 314 6.1% 0.091.2 MB 5.4% 0.34
Aug 5 20:00:00 - 20:59:59 265 5.1% 0.07957.3 KB 4.3% 0.27
Aug 5 21:00:00 - 21:59:59 252 4.9% 0.07910.9 KB 4.1% 0.25
Aug 5 22:00:00 - 22:59:59 228 4.4% 0.06966.1 KB 4.3% 0.27
Aug 5 23:00:00 - 23:59:59 176 3.4% 0.05822.2 KB 3.7% 0.23
Aug 6 00:00:00 - 00:59:59 148 2.9% 0.041002.0 KB 4.5% 0.28
Aug 6 01:00:00 - 01:59:59 99 1.9% 0.03368.6 KB 1.6% 0.10
Aug 6 02:00:00 - 02:59:59 164 3.2% 0.05895.3 KB 4.0% 0.25
Aug 6 03:00:00 - 03:00:03 9 0.2% 3.007.9 KB 0.0% 2.62
TOTAL: 23:59:58 5156 100.0% 0.0621.9 MB 100.0% 0.26
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1351 1148 0 0 0 0 0 0 203
2newsfeed.endofthelinebbs.com 323 78 0 0 0 0 0 0 245
3news.tebibyte.org 26 26 0 0 0 0 0 0 0
4news.sklaffkom.se 10 10 0 0 0 0 0 0 0
TOTAL: 4 1710 1262 0 0 0 0 0 0 448

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.legal.moderated 52
alt.checkmate 46
alt.usage.english 46
de.soc.umwelt 43
alt.bestjobsusa.computer.jobs 39
alt.prophecies.nostradamus 37
pl.misc.elektronika 32
pl.sci.fizyka 28
lada.talk 27
linux.debian.maint.ocaml.maint 23
it.discussioni.commercialisti 22
pl.soc.prawo 21
it-alt.sport.calcio.juventus 20
it.hobby.cucina 19
fr.comp.ia 18
alt.jobs 16
alt.privacy.anon-server 16
de.talk.jokes 16
linux.debian.kernel 16
linux.debian.vote 16
TOTAL: 203 1262

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 251
EMP (phn path) 125
User-issued cancel 16
HTML Multipart 9
Too many newsgroups (meow) 1
TOTAL: 5 402

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7114 1044 4087 6 0 837 14%23:59:54
2sklaffkom.se 5362 882 1657 2599 0 3 16%23:59:05
3newsfeed.endofthelinebbs.com 8694 23 4470 21 0 329 0%23:59:54
4weretis.net 779 0 769 0 0 0 0%23:15:22
TOTAL: 4 21949 1949 10983 2626 0 1169 8%95:14:15
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.6 MB602.2 KB4.2 MB0.0 KB/s4.1 KB23:59:54
2sklaffkom.se1.5 MB14.1 MB15.7 MB0.2 KB/s4.6 KB23:59:05
3newsfeed.endofthelinebbs.com62.3 KB57.1 KB119.4 KB0.0 KB/s2.7 KB23:59:54
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:15:22
TOTAL: 45.2 MB14.8 MB19.9 MB0.1 KB/s4.5 KB95:14:15
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1valhalla.ausics.net 2 00.0 KB 0 0 000:00:03
2124.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:10
3188.240.59.59 1 00.0 KB 0 0 000:00:00
4198.235.24.178 1 00.0 KB 0 0 000:00:00
5213.166.84.54 1 00.0 KB 0 0 000:00:00
65.226.140.84 1 00.0 KB 0 0 000:00:00
75.252.83.34 1 00.0 KB 0 0 000:00:00
8bentley.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
9louisa.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
10prod-boron-sfo2-47.do.binaryedge.ninja 1 00.0 KB 0 0 000:00:02
11v2683292.hostpapadedi.net 1 00.0 KB 0 0 000:00:00
TOTAL: 11 12 00.0 KB 0 0 000:00:23

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 5 00.0 KB 0 0 000:00:01
2*.ausics.net 2 00.0 KB 0 0 000:00:03
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:10
5*.do.binaryedge.ninja 1 00.0 KB 0 0 000:00:02
6*.hostpapadedi.net 1 00.0 KB 0 0 000:00:00
TOTAL: 6 12 00.0 KB 0 0 000:00:23

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
124.195.132.66.censys-scanner.com 0.026 0.011 0.00000:00:10
valhalla.ausics.net 0.009 0.019 0.00000:00:03
bentley.probe.onyphe.net 0.007 0.004 0.00000:00:02
prod-boron-sfo2-47.do.binaryedge.ninja 0.003 0.011 0.00000:00:02
louisa.probe.onyphe.net 0.003 0.010 0.00000:00:00
v2683292.hostpapadedi.net 0.003 0.008 0.00000:00:00
213.166.84.54 0.005 0.009 0.00000:00:00
188.240.59.59 0.004 0.004 0.00000:00:00
5.252.83.34 0.004 0.034 0.00000:00:00
198.235.24.178 0.000 0.013 0.00000:00:00
5.226.140.84 0.003 0.007 0.00000:00:00
TOTAL: 11 0.067 0.130 0.00000:00:23

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

SystemConn
v2683292.hostpapadedi.net 5
louisa.probe.onyphe.net 2
124.195.132.66.censys-scanner.com 1
TOTAL: 3 8

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

CommandCount
#026#003#003#002c#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
Accept: */* 1
Connection: close 1
EHLO www.censys.io 1
GET /..%2F..%2F..%2F..%2F..%2F..%2Fetc%2... 1
Host: 172.234.115.63:119 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
TOTAL: 8 8

NNRP client timeouts [Top 20]:

SystemConnPeer
188.240.59.59 1 1
5.226.140.84 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