Daily Usenet report

Jun 11 03:00:06 -- Jun 12 03:00:04

Unknown entries from news log file:

First 50 / 144 lines (34.7%)

Jun 11 03:01:25 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 11 03:39:34 twilightnode nnrpd[1680618]: Reading access from /usr/local/news/etc/readers.conf
Jun 11 03:39:34 twilightnode nnrpd[1680618]: Auth strategy 'localhost' does not match client. Removing.
Jun 11 03:39:34 twilightnode nnrpd[1680618]: 31.14.254.40 res <anonymous>
Jun 11 03:39:34 twilightnode nnrpd[1680618]: 31.14.254.40 no_match_user <anonymous> <localhost>
Jun 11 03:39:34 twilightnode nnrpd[1680618]: 31.14.254.40 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 03:39:34 twilightnode nnrpd[1680618]: 31.14.254.40 match_user <anonymous> <anonymous>
Jun 11 03:39:34 twilightnode nnrpd[1680618]: 31.14.254.40 no_post anonymous-read
Jun 11 03:39:43 twilightnode nnrpd[1680624]: Reading access from /usr/local/news/etc/readers.conf
Jun 11 03:39:43 twilightnode nnrpd[1680624]: Auth strategy 'localhost' does not match client. Removing.
Jun 11 03:39:43 twilightnode nnrpd[1680624]: 69.5.169.99 res <anonymous>
Jun 11 03:39:43 twilightnode nnrpd[1680624]: 69.5.169.99 no_match_user <anonymous> <localhost>
Jun 11 03:39:43 twilightnode nnrpd[1680624]: 69.5.169.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 03:39:43 twilightnode nnrpd[1680624]: 69.5.169.99 match_user <anonymous> <anonymous>
Jun 11 03:39:43 twilightnode nnrpd[1680624]: 69.5.169.99 no_post anonymous-read
Jun 11 06:07:39 twilightnode nnrpd[1686452]: Reading access from /usr/local/news/etc/readers.conf
Jun 11 06:07:39 twilightnode nnrpd[1686452]: Auth strategy 'localhost' does not match client. Removing.
Jun 11 06:07:39 twilightnode nnrpd[1686452]: 198.235.24.93 res <anonymous>
Jun 11 06:07:39 twilightnode nnrpd[1686452]: 198.235.24.93 no_match_user <anonymous> <localhost>
Jun 11 06:07:39 twilightnode nnrpd[1686452]: 198.235.24.93 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 06:07:39 twilightnode nnrpd[1686452]: 198.235.24.93 match_user <anonymous> <anonymous>
Jun 11 06:07:39 twilightnode nnrpd[1686452]: 198.235.24.93 no_post anonymous-read
Jun 11 06:17:47 twilightnode nnrpd[1686894]: Reading access from /usr/local/news/etc/readers.conf
Jun 11 06:17:47 twilightnode nnrpd[1686894]: Auth strategy 'localhost' does not match client. Removing.
Jun 11 06:17:47 twilightnode nnrpd[1686894]: 69.5.169.23 res <anonymous>
Jun 11 06:17:47 twilightnode nnrpd[1686894]: 69.5.169.23 no_match_user <anonymous> <localhost>
Jun 11 06:17:47 twilightnode nnrpd[1686894]: 69.5.169.23 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 06:17:47 twilightnode nnrpd[1686894]: 69.5.169.23 match_user <anonymous> <anonymous>
Jun 11 06:17:47 twilightnode nnrpd[1686894]: 69.5.169.23 no_post anonymous-read
Jun 11 06:41:14 twilightnode nnrpd[1688975]: Reading access from /usr/local/news/etc/readers.conf
Jun 11 06:41:14 twilightnode nnrpd[1688975]: Auth strategy 'localhost' does not match client. Removing.
Jun 11 06:41:14 twilightnode nnrpd[1688975]: 45-56-79-53.ip.linodeusercontent.com res <anonymous>
Jun 11 06:41:14 twilightnode nnrpd[1688975]: 45-56-79-53.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 11 06:41:14 twilightnode nnrpd[1688975]: 45-56-79-53.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 06:41:14 twilightnode nnrpd[1688975]: 45-56-79-53.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 11 06:41:14 twilightnode nnrpd[1688975]: 45-56-79-53.ip.linodeusercontent.com no_post anonymous-read
Jun 11 08:54:34 twilightnode nnrpd[1694603]: Reading access from /usr/local/news/etc/readers.conf
Jun 11 08:54:34 twilightnode nnrpd[1694603]: Auth strategy 'localhost' does not match client. Removing.
Jun 11 08:54:34 twilightnode nnrpd[1694603]: 172-239-71-244.ip.linodeusercontent.com res <anonymous>
Jun 11 08:54:34 twilightnode nnrpd[1694603]: 172-239-71-244.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 11 08:54:34 twilightnode nnrpd[1694603]: 172-239-71-244.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 08:54:34 twilightnode nnrpd[1694603]: 172-239-71-244.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 11 08:54:34 twilightnode nnrpd[1694603]: 172-239-71-244.ip.linodeusercontent.com no_post anonymous-read
Jun 11 08:54:34 twilightnode nnrpd[1694604]: Reading access from /usr/local/news/etc/readers.conf
Jun 11 08:54:34 twilightnode nnrpd[1694604]: Auth strategy 'localhost' does not match client. Removing.
Jun 11 08:54:34 twilightnode nnrpd[1694604]: 172-239-71-244.ip.linodeusercontent.com res <anonymous>
Jun 11 08:54:34 twilightnode nnrpd[1694604]: 172-239-71-244.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jun 11 08:54:34 twilightnode nnrpd[1694604]: 172-239-71-244.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 08:54:34 twilightnode nnrpd[1694604]: 172-239-71-244.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jun 11 08:54:34 twilightnode nnrpd[1694604]: 172-239-71-244.ip.linodeusercontent.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 5753 66.3%926.2 KB 70.0%
innfeed 1672 19.3%222.2 KB 16.8%
innd 1000 11.5%147.9 KB 11.2%
nnrpd 245 2.8%26.1 KB 2.0%
controlchan 3 0.0%0.2 KB 0.0%
TOTAL: 5 8673 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6129 34.9%
Positive hits 5762 32.8%
Do not exist 5691 32.4%
Cache misses 3 0.0%
TOTAL: 4 17585 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.098 0.0%5739 0.000 0.017 0.250
article logging00:00:00.275 0.0%5769 0.000 0.048 0.182
article parse00:00:00.109 0.0%6996 0.000 0.016 0.095
article write00:00:03.736 0.0%4079 0.444 0.916 4.061
data move00:00:00.037 0.0%18790 0.000 0.002 0.032
hisgrep/artcncl00:00:00.002 0.0%2 1.000 1.000 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.328 0.0%17738 0.115 0.244 0.672
history sync00:00:00.057 0.0%407 0.000 0.140 1.000
history write00:00:03.063 0.0%5739 0.125 0.534 1.225
idle24:00:33.677100.0%18793 2401.096 4599.248 17163.143
nntp read00:00:00.583 0.0%18390 0.000 0.032 0.505
overview write00:00:01.984 0.0%4079 0.122 0.486 1.667
perl filter00:00:12.424 0.0%5739 0.981 2.165 8.652
python filter00:00:01.185 0.0%5739 0.045 0.206 2.321
site send00:00:00.166 0.0%4079 0.000 0.041 0.188
TOTAL: 1724:01:01.728100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.029 0.0%4052 0.000 0.007 0.385
article prepare00:00:00.001 0.0%23700 0.000 0.000 0.011
article read00:00:00.693 0.0%4050 0.000 0.171 0.500
backlog stats00:00:00.031 0.0%27388 0.000 0.001 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.229 0.0%14941 0.000 0.015 0.158
data write00:00:00.374 0.0%10951 0.000 0.034 0.305
idle23:51:26.339100.0%27388 1227.414 3135.911 8108.108
status file00:00:00.275 0.0%354 0.000 0.777 4.000
TOTAL: 923:51:27.971100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:26.901100.0%31 48.333 867.774 10548.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%225 0.000 0.004 0.021
TOTAL: 300:00:26.902100.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 11653 3952 5989 1712 33%48:42:52
2news.sklaffkom.se 48 110 77 24 9 70%12:15:53
3localhost 25 0 0 0 0 0%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 4 76 11763 4029 6013 1721 34%61:08:45
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.7 MB0.0 KB5.4 MB17.1 MB 68%3.1 KB
2news.sklaffkom.se208.2 KB0.0 KB20.1 KB228.2 KB 91%2.7 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
4news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 411.9 MB0.0 KB5.4 MB17.3 MB 68%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 11 03:00:06 - 03:59:59 134 3.3% 0.04432.6 KB 3.5% 0.12
Jun 11 04:00:00 - 04:59:59 94 2.3% 0.03390.4 KB 3.2% 0.11
Jun 11 05:00:00 - 05:59:59 101 2.5% 0.03222.9 KB 1.8% 0.06
Jun 11 06:00:00 - 06:59:59 135 3.3% 0.04429.7 KB 3.5% 0.12
Jun 11 07:00:00 - 07:59:59 131 3.2% 0.04343.9 KB 2.8% 0.10
Jun 11 08:00:00 - 08:59:59 175 4.3% 0.05818.8 KB 6.6% 0.23
Jun 11 09:00:00 - 09:59:59 188 4.6% 0.05846.6 KB 6.9% 0.24
Jun 11 10:00:00 - 10:59:59 205 5.0% 0.06685.6 KB 5.5% 0.19
Jun 11 11:00:00 - 11:59:59 203 5.0% 0.06467.2 KB 3.8% 0.13
Jun 11 12:00:00 - 12:59:59 204 5.0% 0.06543.7 KB 4.4% 0.15
Jun 11 13:00:00 - 13:59:59 221 5.4% 0.06553.0 KB 4.5% 0.15
Jun 11 14:00:00 - 14:59:59 208 5.1% 0.06690.6 KB 5.6% 0.19
Jun 11 15:00:00 - 15:59:59 175 4.3% 0.05472.2 KB 3.8% 0.13
Jun 11 16:00:00 - 16:59:59 164 4.0% 0.05469.7 KB 3.8% 0.13
Jun 11 17:00:00 - 17:59:59 187 4.6% 0.05468.0 KB 3.8% 0.13
Jun 11 18:00:00 - 18:59:59 189 4.6% 0.05429.3 KB 3.5% 0.12
Jun 11 19:00:00 - 19:59:59 242 6.0% 0.07630.9 KB 5.1% 0.18
Jun 11 20:00:00 - 20:59:59 207 5.1% 0.06553.6 KB 4.5% 0.15
Jun 11 21:00:00 - 21:59:59 150 3.7% 0.04423.2 KB 3.4% 0.12
Jun 11 22:00:00 - 22:59:59 216 5.3% 0.06700.7 KB 5.7% 0.19
Jun 11 23:00:00 - 23:59:59 153 3.8% 0.04537.3 KB 4.3% 0.15
Jun 12 00:00:00 - 00:59:59 122 3.0% 0.03407.9 KB 3.3% 0.11
Jun 12 01:00:00 - 01:59:59 122 3.0% 0.03435.2 KB 3.5% 0.12
Jun 12 02:00:00 - 02:59:59 130 3.2% 0.04392.2 KB 3.2% 0.11
Jun 12 03:00:00 - 03:00:04 9 0.2% 2.257.3 KB 0.1% 1.83
TOTAL: 23:59:58 4065 100.0% 0.0512.1 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1679 1434 0 0 0 0 0 0 245
2news.sklaffkom.se 9 9 0 0 0 0 0 0 0
TOTAL: 2 1688 1443 0 0 0 0 0 0 245

Unwanted newsgroups [Top 20]:

NewsgroupCount
pl.soc.prawo 77
it.sport.calcio.milan 76
nl.politiek 71
muc.lists.freebsd.bugs 43
pl.misc.samochody 42
de.rec.fahrrad 33
alt.usage.english 32
bln.test 32
alt.recovery.aa 29
fido7.ru.home 28
linux.debian.maint.ocaml.maint 28
fido7.ru.military 27
alt.slack 26
it.hobby.cucina 26
de.rec.mampf 25
de.rec.musik.hifi 25
uk.legal.moderated 25
fido7.crimea.talk 22
pl.soc.polityka 21
cn.comp 20
TOTAL: 196 1443

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 184
User-issued cancel 20
Too many newsgroups 5
HTML Multipart 4
Too many newsgroups (meow) 2
TOTAL: 5 215

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4067 4053 0 12 0 0 99%23:59:50
2sklaffkom.se 4420 805 2177 1075 0 2 18%23:59:53
3weretis.net 100 0 75 0 0 0 0%09:39:58
4sklaff.dev 0 0 0 0 0 4057 0%23:59:52
5thebunker.nu 0 0 0 0 0 4065 0%23:59:53
6usenet.blueworldhosting.com 0 0 0 0 0 3721 0%23:59:53
TOTAL: 6 8587 4858 2252 1087 0 11845 56%129:39:19
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se11.9 MB190.6 KB12.1 MB0.1 KB/s3.0 KB23:59:50
2sklaffkom.se1.3 MB3.7 MB5.0 MB0.1 KB/s2.7 KB23:59:53
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
4thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s009:39:58
TOTAL: 613.2 MB3.8 MB17.1 MB0.0 KB/s2.9 KB129:39:19
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1104.155.45.199.censys-scanner.com 3 00.0 KB 0 0 000:00:05
248.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3172-239-71-244.ip.linodeusercontent.com 2 00.0 KB 0 0 000:00:00
4azpdcgf5goiv.stretchoid.com 2 00.0 KB 0 0 000:00:10
5198.235.24.93 1 00.0 KB 0 0 000:00:00
631.14.254.40 1 00.0 KB 0 0 000:00:00
735.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
845-56-79-53.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:00
945-79-207-110.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:00
1047.250.120.237 1 00.0 KB 0 0 000:00:03
1169.5.169.23 1 00.0 KB 0 0 000:00:00
1269.5.169.45 1 00.0 KB 0 0 000:00:00
1369.5.169.99 1 00.0 KB 0 0 000:00:00
TOTAL: 13 19 00.0 KB 0 0 000:00:26

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 6 00.0 KB 0 0 000:00:04
2*.ip.linodeusercontent.com 4 00.0 KB 0 0 000:00:01
3*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:04
4*.199.censys-scanner.com 3 00.0 KB 0 0 000:00:05
5*.stretchoid.com 2 00.0 KB 0 0 000:00:10
6*.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
TOTAL: 6 19 00.0 KB 0 0 000:00:26

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcgf5goiv.stretchoid.com 0.010 0.018 0.00000:00:10
104.155.45.199.censys-scanner.com 0.039 0.035 0.00000:00:05
48.146.94.167.censys-scanner.com 0.045 0.032 0.00000:00:04
47.250.120.237 0.003 0.021 0.00000:00:03
45-56-79-53.ip.linodeusercontent.com 0.007 0.016 0.00000:00:00
45-79-207-110.ip.linodeusercontent.com 0.004 0.015 0.00000:00:00
198.235.24.93 0.001 0.019 0.00000:00:00
31.14.254.40 0.005 0.014 0.00000:00:00
172-239-71-244.ip.linodeusercontent.com 0.004 0.022 0.00000:00:00
69.5.169.23 0.005 0.009 0.00000:00:00
69.5.169.99 0.004 0.007 0.00000:00:00
69.5.169.45 0.004 0.014 0.00000:00:00
35.210.203.35.bc.googleusercontent.com 0.009 0.017 0.00000:00:00
TOTAL: 13 0.140 0.239 0.00000:00:26

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

SystemConn
172-239-71-244.ip.linodeusercontent.com 7
35.210.203.35.bc.googleusercontent.com 4
104.155.45.199.censys-scanner.com 1
48.146.94.167.censys-scanner.com 1
azpdcgf5goiv.stretchoid.com 1
TOTAL: 5 14

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

CommandCount
Accept-Encoding: gzip 2
EHLO www.censys.io 2
GET / HTTP/1.1 2
Host: 172.234.115.63:119 2
#001 1
#026#003#001 1
Accept: */* 1
MGLNDD_172.234.115.63_119 1
User-Agent: Hello from Palo Alto Network... 1
User-Agent: Mozilla/5.0 (Macintosh; Inte... 1
TOTAL: 10 14

NNRP client timeouts [Top 20]:

SystemConnPeer
104.155.45.199.censys-scanner.com 1 2
47.250.120.237 1 1
48.146.94.167.censys-scanner.com 1 2
69.5.169.45 1 1
69.5.169.99 1 1
TOTAL: 5 5 12
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