Daily Usenet report

Jul 31 03:00:05 -- Aug 1 03:00:05

Unknown entries from news log file:

First 50 / 84 lines (59.5%)

Jul 31 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 31 03:36:41 twilightnode nnrpd[351793]: Reading access from /usr/local/news/etc/readers.conf
Jul 31 03:36:41 twilightnode nnrpd[351793]: Auth strategy 'localhost' does not match client. Removing.
Jul 31 03:36:41 twilightnode nnrpd[351793]: 69.5.169.155 res <anonymous>
Jul 31 03:36:41 twilightnode nnrpd[351793]: 69.5.169.155 no_match_user <anonymous> <localhost>
Jul 31 03:36:41 twilightnode nnrpd[351793]: 69.5.169.155 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 31 03:36:41 twilightnode nnrpd[351793]: 69.5.169.155 match_user <anonymous> <anonymous>
Jul 31 03:36:41 twilightnode nnrpd[351793]: 69.5.169.155 no_post anonymous-read
Jul 31 03:37:17 twilightnode nnrpd[351795]: Reading access from /usr/local/news/etc/readers.conf
Jul 31 03:37:17 twilightnode nnrpd[351795]: Auth strategy 'localhost' does not match client. Removing.
Jul 31 03:37:17 twilightnode nnrpd[351795]: 213.166.84.53 res <anonymous>
Jul 31 03:37:17 twilightnode nnrpd[351795]: 213.166.84.53 no_match_user <anonymous> <localhost>
Jul 31 03:37:17 twilightnode nnrpd[351795]: 213.166.84.53 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 31 03:37:17 twilightnode nnrpd[351795]: 213.166.84.53 match_user <anonymous> <anonymous>
Jul 31 03:37:17 twilightnode nnrpd[351795]: 213.166.84.53 no_post anonymous-read
Jul 31 09:43:53 twilightnode nnrpd[367776]: Reading access from /usr/local/news/etc/readers.conf
Jul 31 09:43:53 twilightnode nnrpd[367776]: Auth strategy 'localhost' does not match client. Removing.
Jul 31 09:43:53 twilightnode nnrpd[367776]: 147.185.132.87 res <anonymous>
Jul 31 09:43:53 twilightnode nnrpd[367776]: 147.185.132.87 no_match_user <anonymous> <localhost>
Jul 31 09:43:53 twilightnode nnrpd[367776]: 147.185.132.87 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 31 09:43:53 twilightnode nnrpd[367776]: 147.185.132.87 match_user <anonymous> <anonymous>
Jul 31 09:43:53 twilightnode nnrpd[367776]: 147.185.132.87 no_post anonymous-read
Jul 31 09:57:32 twilightnode nnrpd[368305]: Reading access from /usr/local/news/etc/readers.conf
Jul 31 09:57:32 twilightnode nnrpd[368305]: Auth strategy 'localhost' does not match client. Removing.
Jul 31 09:57:32 twilightnode nnrpd[368305]: 147.185.132.101 res <anonymous>
Jul 31 09:57:32 twilightnode nnrpd[368305]: 147.185.132.101 no_match_user <anonymous> <localhost>
Jul 31 09:57:32 twilightnode nnrpd[368305]: 147.185.132.101 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 31 09:57:32 twilightnode nnrpd[368305]: 147.185.132.101 match_user <anonymous> <anonymous>
Jul 31 09:57:32 twilightnode nnrpd[368305]: 147.185.132.101 no_post anonymous-read
Jul 31 10:43:02 twilightnode nnrpd[370284]: Reading access from /usr/local/news/etc/readers.conf
Jul 31 10:43:02 twilightnode nnrpd[370284]: Auth strategy 'localhost' does not match client. Removing.
Jul 31 10:43:02 twilightnode nnrpd[370284]: 165.154.163.10 res <anonymous>
Jul 31 10:43:02 twilightnode nnrpd[370284]: 165.154.163.10 no_match_user <anonymous> <localhost>
Jul 31 10:43:02 twilightnode nnrpd[370284]: 165.154.163.10 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 31 10:43:02 twilightnode nnrpd[370284]: 165.154.163.10 match_user <anonymous> <anonymous>
Jul 31 10:43:02 twilightnode nnrpd[370284]: 165.154.163.10 no_post anonymous-read
Jul 31 17:08:13 twilightnode nnrpd[387142]: Reading access from /usr/local/news/etc/readers.conf
Jul 31 17:08:13 twilightnode nnrpd[387142]: Auth strategy 'localhost' does not match client. Removing.
Jul 31 17:08:13 twilightnode nnrpd[387142]: grayson.probe.onyphe.net res <anonymous>
Jul 31 17:08:13 twilightnode nnrpd[387142]: grayson.probe.onyphe.net no_match_user <anonymous> <localhost>
Jul 31 17:08:13 twilightnode nnrpd[387142]: grayson.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 31 17:08:13 twilightnode nnrpd[387142]: grayson.probe.onyphe.net match_user <anonymous> <anonymous>
Jul 31 17:08:13 twilightnode nnrpd[387142]: grayson.probe.onyphe.net no_post anonymous-read
Jul 31 17:08:13 twilightnode nnrpd[387143]: Reading access from /usr/local/news/etc/readers.conf
Jul 31 17:08:13 twilightnode nnrpd[387143]: Auth strategy 'localhost' does not match client. Removing.
Jul 31 17:08:13 twilightnode nnrpd[387143]: anais.probe.onyphe.net res <anonymous>
Jul 31 17:08:13 twilightnode nnrpd[387143]: anais.probe.onyphe.net no_match_user <anonymous> <localhost>
Jul 31 17:08:13 twilightnode nnrpd[387143]: anais.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 31 17:08:13 twilightnode nnrpd[387143]: anais.probe.onyphe.net match_user <anonymous> <anonymous>
Jul 31 17:08:13 twilightnode nnrpd[387143]: anais.probe.onyphe.net no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6796 72.9%1.0 MB 73.0%
innfeed 1232 13.2%185.1 KB 13.1%
innd 1130 12.1%174.9 KB 12.4%
nnrpd 137 1.5%14.2 KB 1.0%
controlchan 26 0.3%6.6 KB 0.5%
pgpverify 1 0.0%0.3 KB 0.0%
TOTAL: 6 9322 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15449 49.5%
Negative hits 9040 28.9%
Do not exist 6738 21.6%
Cache misses 1 0.0%
TOTAL: 4 31228 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.005 0.0%3 0.000 1.667 4.000
article cleanup00:00:00.043 0.0%6762 0.000 0.006 0.167
article logging00:00:00.222 0.0%6798 0.000 0.033 0.278
article parse00:00:00.103 0.0%9431 0.000 0.011 0.100
article write00:00:04.597 0.0%5188 0.364 0.886 5.893
data move00:00:00.149 0.0%34493 0.000 0.004 0.474
hisgrep/artcncl00:00:00.003 0.0%3 0.000 1.000 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.360 0.0%31314 0.068 0.139 0.941
history sync00:00:00.043 0.0%308 0.000 0.140 2.000
history write00:00:03.103 0.0%6762 0.067 0.459 1.167
idle24:00:02.542100.0%33609 958.121 2570.816 10912.309
nntp read00:00:00.714 0.0%33359 0.000 0.021 0.140
overview write00:00:02.124 0.0%5188 0.143 0.409 2.643
perl filter00:00:07.725 0.0%6762 0.617 1.142 18.833
python filter00:00:00.517 0.0%6762 0.000 0.076 1.389
site send00:00:00.114 0.0%5168 0.000 0.022 0.167
TOTAL: 1724:00:26.364100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.046 0.0%5300 0.000 0.009 1.077
article prepare00:00:00.001 0.0%18812 0.000 0.000 0.009
article read00:00:00.518 0.0%3563 0.000 0.145 1.300
backlog stats00:00:00.028 0.0%57680 0.000 0.000 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.420 0.0%31290 0.000 0.013 0.044
data write00:00:00.653 0.0%26029 0.000 0.025 0.088
idle23:59:07.293100.0%57680 365.965 1497.006 5097.364
status file00:00:00.118 0.0%228 0.000 0.518 5.000
TOTAL: 923:59:09.077100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:36.781100.0%18 72.000 2043.389 12517.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%133 0.000 0.008 0.500
TOTAL: 300:00:36.782100.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
bofh-control@killfile.org001001
us-control@lists.killfile.org001101
TOTAL002102

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 11949 4610 6153 1186 38%50:29:01
2newsfeed.endofthelinebbs.com 2 4184 450 3473 261 10%18:52:42
3news.tebibyte.org 2 4881 71 4772 38 1%23:59:19
4news.sklaffkom.se 38 104 46 11 47 44%10:57:38
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 71 21118 5177 14409 1532 24%104:18:40
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.8 MB0.0 KB3.4 MB16.2 MB 79%2.9 KB
2newsfeed.endofthelinebbs.com5.1 MB0.0 KB581.6 KB5.7 MB 90%8.2 KB
3news.tebibyte.org308.5 KB0.0 KB124.6 KB433.1 KB 71%4.0 KB
4news.sklaffkom.se115.8 KB0.0 KB123.5 KB239.3 KB 48%2.6 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 518.4 MB0.0 KB4.2 MB22.6 MB 81%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 31 03:00:05 - 03:59:59 91 1.8% 0.03365.5 KB 1.9% 0.10
Jul 31 04:00:00 - 04:59:59 116 2.2% 0.03706.4 KB 3.7% 0.20
Jul 31 05:00:00 - 05:59:59 106 2.0% 0.03431.2 KB 2.2% 0.12
Jul 31 06:00:00 - 06:59:59 144 2.8% 0.04457.7 KB 2.4% 0.13
Jul 31 07:00:00 - 07:59:59 141 2.7% 0.04387.5 KB 2.0% 0.11
Jul 31 08:00:00 - 08:59:59 204 3.9% 0.06677.8 KB 3.5% 0.19
Jul 31 09:00:00 - 09:59:59 242 4.7% 0.07803.5 KB 4.2% 0.22
Jul 31 10:00:00 - 10:59:59 251 4.8% 0.07753.5 KB 3.9% 0.21
Jul 31 11:00:00 - 11:59:59 269 5.2% 0.071.1 MB 5.9% 0.32
Jul 31 12:00:00 - 12:59:59 224 4.3% 0.06622.5 KB 3.2% 0.17
Jul 31 13:00:00 - 13:59:59 258 5.0% 0.07677.2 KB 3.5% 0.19
Jul 31 14:00:00 - 14:59:59 224 4.3% 0.06792.4 KB 4.1% 0.22
Jul 31 15:00:00 - 15:59:59 254 4.9% 0.07870.7 KB 4.5% 0.24
Jul 31 16:00:00 - 16:59:59 274 5.3% 0.08959.7 KB 5.0% 0.27
Jul 31 17:00:00 - 17:59:59 349 6.7% 0.101.7 MB 9.1% 0.49
Jul 31 18:00:00 - 18:59:59 354 6.8% 0.101.4 MB 7.3% 0.39
Jul 31 19:00:00 - 19:59:59 257 5.0% 0.07861.3 KB 4.5% 0.24
Jul 31 20:00:00 - 20:59:59 267 5.1% 0.071.0 MB 5.5% 0.29
Jul 31 21:00:00 - 21:59:59 274 5.3% 0.081.2 MB 6.1% 0.33
Jul 31 22:00:00 - 22:59:59 256 4.9% 0.07856.6 KB 4.4% 0.24
Jul 31 23:00:00 - 23:59:59 136 2.6% 0.04397.2 KB 2.1% 0.11
Aug 1 00:00:00 - 00:59:59 162 3.1% 0.04564.1 KB 2.9% 0.16
Aug 1 01:00:00 - 01:59:59 173 3.3% 0.05627.8 KB 3.3% 0.17
Aug 1 02:00:00 - 02:59:59 150 2.9% 0.04909.1 KB 4.7% 0.25
Aug 1 03:00:00 - 03:00:05 9 0.2% 1.807.8 KB 0.0% 1.56
TOTAL: 24:00:00 5185 100.0% 0.0618.8 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1197 1088 0 0 0 0 0 0 109
2newsfeed.endofthelinebbs.com 346 54 0 0 0 0 0 0 292
3news.sklaffkom.se 47 47 0 0 0 0 0 0 0
4news.tebibyte.org 21 18 0 0 0 0 0 0 3
TOTAL: 4 1611 1207 0 0 0 0 0 0 404

Unwanted newsgroups [Top 20]:

NewsgroupCount
de.alt.talk.unmut 67
pl.misc.elektronika 58
pl.soc.prawo 48
de.alt.folklore.computer 45
alt.checkmate 33
eternal-september.talk 33
lada.talk 33
de.talk.jokes 31
de.etc.haushalt 30
muc.lists.freebsd.bugs 24
de.alt.fan.pluesch 23
uk.legal.moderated 23
linux.debian.vote 22
de.alt.comm.android 21
hfx.general 21
cn.comp 20
de.etc.bahn.bahnpolitik 19
pl.soc.polityka 19
alt.prophecies.nostradamus 17
de.test 17
TOTAL: 226 1207

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 324
User-issued cancel 37
HTML Multipart 4
Too many newsgroups 3
TOTAL: 4 368

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7369 1189 3970 2 0 10 16%23:59:53
2sklaffkom.se 5350 920 1681 2561 0 5 17%23:59:55
3newsfeed.endofthelinebbs.com 8777 35 4613 4 0 136 0%23:59:21
4weretis.net 622 0 603 0 0 0 0%22:11:49
TOTAL: 4 22118 2144 10867 2567 0 151 9%94:10:58
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.2 MB251.7 KB3.5 MB0.0 KB/s3.0 KB23:59:53
2sklaffkom.se1.5 MB11.6 MB13.1 MB0.2 KB/s3.9 KB23:59:55
3newsfeed.endofthelinebbs.com100.3 KB7.9 KB108.1 KB0.0 KB/s2.8 KB23:59:21
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:11:49
TOTAL: 44.9 MB11.8 MB16.7 MB0.1 KB/s3.6 KB94:10:58
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
159.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:06
2147.185.132.101 1 00.0 KB 0 0 000:00:00
3147.185.132.87 1 00.0 KB 0 0 000:00:00
4165.154.163.10 1 00.0 KB 0 0 000:00:25
5213.166.84.53 1 00.0 KB 0 0 000:00:00
669.5.169.155 1 00.0 KB 0 0 000:00:00
7anais.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
8grayson.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
9o033.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 9 11 00.0 KB 0 0 000:00:36

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 5 00.0 KB 0 0 000:00:26
2*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:06
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 4 11 00.0 KB 0 0 000:00:36

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
165.154.163.10 0.006 0.011 0.00000:00:25
59.146.94.167.censys-scanner.com 0.031 0.028 0.00000:00:06
anais.probe.onyphe.net 0.004 0.003 0.00000:00:02
grayson.probe.onyphe.net 0.002 0.015 0.00000:00:00
o033.scanner.modat.io 0.003 0.009 0.00000:00:00
147.185.132.101 0.003 0.010 0.00000:00:00
147.185.132.87 0.004 0.011 0.00000:00:00
213.166.84.53 0.002 0.006 0.00000:00:00
69.5.169.155 0.004 0.010 0.00000:00:00
TOTAL: 9 0.059 0.103 0.00000:00:36

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

SystemConn
147.185.132.101 4
grayson.probe.onyphe.net 2
59.146.94.167.censys-scanner.com 1
TOTAL: 3 7

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-Encoding: gzip 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
User-Agent: Hello from Palo Alto Network... 1
TOTAL: 7 7

NNRP client timeouts [Top 20]:

SystemConnPeer
213.166.84.53 1 1
59.146.94.167.censys-scanner.com 1 2
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