Daily Usenet report

Jun 13 03:00:05 -- Jun 14 03:00:06

Unknown entries from news log file:

First 50 / 769 lines (6.5%)

Jun 13 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 13 03:43:19 twilightnode nnrpd[1807778]: Reading access from /usr/local/news/etc/readers.conf
Jun 13 03:43:19 twilightnode nnrpd[1807778]: Auth strategy 'localhost' does not match client. Removing.
Jun 13 03:43:19 twilightnode nnrpd[1807778]: o340.scanner.modat.io res <anonymous>
Jun 13 03:43:19 twilightnode nnrpd[1807778]: o340.scanner.modat.io no_match_user <anonymous> <localhost>
Jun 13 03:43:19 twilightnode nnrpd[1807778]: o340.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 13 03:43:19 twilightnode nnrpd[1807778]: o340.scanner.modat.io match_user <anonymous> <anonymous>
Jun 13 03:43:19 twilightnode nnrpd[1807778]: o340.scanner.modat.io no_post anonymous-read
Jun 13 04:20:38 twilightnode nnrpd[1809329]: Reading access from /usr/local/news/etc/readers.conf
Jun 13 04:20:38 twilightnode nnrpd[1809329]: Auth strategy 'localhost' does not match client. Removing.
Jun 13 04:20:38 twilightnode nnrpd[1809329]: prod51client01.academyforinternetresearch.org res <anonymous>
Jun 13 04:20:38 twilightnode nnrpd[1809329]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> <localhost>
Jun 13 04:20:38 twilightnode nnrpd[1809329]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 13 04:20:38 twilightnode nnrpd[1809329]: prod51client01.academyforinternetresearch.org match_user <anonymous> <anonymous>
Jun 13 04:20:38 twilightnode nnrpd[1809329]: prod51client01.academyforinternetresearch.org no_post anonymous-read
Jun 13 04:20:59 twilightnode nnrpd[1809331]: Reading access from /usr/local/news/etc/readers.conf
Jun 13 04:20:59 twilightnode nnrpd[1809331]: Auth strategy 'localhost' does not match client. Removing.
Jun 13 04:20:59 twilightnode nnrpd[1809331]: prod51client01.academyforinternetresearch.org res <anonymous>
Jun 13 04:20:59 twilightnode nnrpd[1809331]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> <localhost>
Jun 13 04:20:59 twilightnode nnrpd[1809331]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 13 04:20:59 twilightnode nnrpd[1809331]: prod51client01.academyforinternetresearch.org match_user <anonymous> <anonymous>
Jun 13 04:20:59 twilightnode nnrpd[1809331]: prod51client01.academyforinternetresearch.org no_post anonymous-read
Jun 13 04:24:12 twilightnode nnrpd[1809393]: Reading access from /usr/local/news/etc/readers.conf
Jun 13 04:24:12 twilightnode nnrpd[1809393]: Auth strategy 'localhost' does not match client. Removing.
Jun 13 04:24:12 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org res <anonymous>
Jun 13 04:24:12 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> <localhost>
Jun 13 04:24:12 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 13 04:24:12 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org match_user <anonymous> <anonymous>
Jun 13 04:24:12 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org no_post anonymous-read
Jun 13 04:24:12 twilightnode nnrpd[1809397]: Reading access from /usr/local/news/etc/readers.conf
Jun 13 04:24:12 twilightnode nnrpd[1809397]: Auth strategy 'localhost' does not match client. Removing.
Jun 13 04:24:12 twilightnode nnrpd[1809397]: prod51client01.academyforinternetresearch.org res <anonymous>
Jun 13 04:24:12 twilightnode nnrpd[1809397]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> <localhost>
Jun 13 04:24:12 twilightnode nnrpd[1809397]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 13 04:24:12 twilightnode nnrpd[1809397]: prod51client01.academyforinternetresearch.org match_user <anonymous> <anonymous>
Jun 13 04:24:12 twilightnode nnrpd[1809397]: prod51client01.academyforinternetresearch.org no_post anonymous-read
Jun 13 04:24:14 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org res <anonymous>
Jun 13 04:24:14 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> <localhost>
Jun 13 04:24:14 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 13 04:24:14 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org match_user <anonymous> <anonymous>
Jun 13 04:24:14 twilightnode nnrpd[1809393]: prod51client01.academyforinternetresearch.org no_post anonymous-read
Jun 13 04:24:18 twilightnode nnrpd[1809413]: Reading access from /usr/local/news/etc/readers.conf
Jun 13 04:24:18 twilightnode nnrpd[1809413]: Auth strategy 'localhost' does not match client. Removing.
Jun 13 04:24:18 twilightnode nnrpd[1809413]: prod51client01.academyforinternetresearch.org res <anonymous>
Jun 13 04:24:18 twilightnode nnrpd[1809413]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> <localhost>
Jun 13 04:24:18 twilightnode nnrpd[1809413]: prod51client01.academyforinternetresearch.org no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 13 04:24:18 twilightnode nnrpd[1809413]: prod51client01.academyforinternetresearch.org match_user <anonymous> <anonymous>
Jun 13 04:24:18 twilightnode nnrpd[1809413]: prod51client01.academyforinternetresearch.org no_post anonymous-read
Jun 13 04:24:46 twilightnode nnrpd[1809569]: Reading access from /usr/local/news/etc/readers.conf
Jun 13 04:24:46 twilightnode nnrpd[1809569]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 5269 61.0%858.2 KB 65.6%
innfeed 1578 18.3%216.8 KB 16.6%
nnrpd 983 11.4%116.5 KB 8.9%
innd 805 9.3%117.0 KB 8.9%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 8637 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 5663 35.0%
Positive hits 5302 32.8%
Do not exist 5207 32.2%
Cache misses 1 0.0%
TOTAL: 4 16173 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.007 0.0%4 1.000 1.750 2.000
article cleanup00:00:00.062 0.0%5230 0.000 0.012 0.097
article logging00:00:00.275 0.0%5263 0.000 0.052 0.182
article parse00:00:00.099 0.0%6282 0.000 0.016 0.182
article write00:00:03.526 0.0%3846 0.357 0.917 3.792
data move00:00:00.039 0.0%17502 0.000 0.002 0.027
hisgrep/artcncl00:00:00.001 0.0%4 0.000 0.250 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.171 0.0%16242 0.111 0.257 0.702
history sync00:00:00.083 0.0%413 0.000 0.201 3.333
history write00:00:02.787 0.0%5230 0.000 0.533 1.000
idle24:00:38.255100.0%17551 2316.405 4924.976 20717.103
nntp read00:00:00.463 0.0%17052 0.000 0.027 0.109
overview write00:00:01.886 0.0%3846 0.152 0.490 2.091
perl filter00:00:11.433 0.0%5230 1.309 2.186 9.760
python filter00:00:01.154 0.0%5230 0.071 0.221 1.300
site send00:00:00.137 0.0%3846 0.000 0.036 0.500
TOTAL: 1724:01:04.378100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.012 0.0%3853 0.000 0.003 0.250
article prepare00:00:00.002 0.0%23032 0.000 0.000 0.011
article read00:00:00.608 0.0%3851 0.000 0.158 0.571
backlog stats00:00:00.042 0.0%26163 0.000 0.002 0.053
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.248 0.0%14211 0.000 0.017 0.136
data write00:00:00.315 0.0%10420 0.000 0.030 0.129
idle23:59:27.670100.0%26163 1428.943 3301.138 8460.521
status file00:00:00.286 0.0%316 0.000 0.905 5.000
TOTAL: 923:59:29.183100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:27.466100.0%74 7.000 371.162 2992.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.009 0.0%244 0.000 0.037 1.000
TOTAL: 300:00:27.475100.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 10014 3762 4903 1349 37%45:41:00
2news.sklaffkom.se 42 105 41 37 27 39%11:13:15
3localhost 25 0 0 0 0 0%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 4 70 10119 3803 4940 1376 37%57:04:15
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net10.5 MB0.0 KB4.1 MB14.6 MB 72%2.9 KB
2news.sklaffkom.se117.3 KB0.0 KB58.2 KB175.5 KB 66%2.6 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: 410.6 MB0.0 KB4.1 MB14.8 MB 72%2.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 13 03:00:05 - 03:59:59 79 2.1% 0.02253.7 KB 2.3% 0.07
Jun 13 04:00:00 - 04:59:59 92 2.4% 0.03225.1 KB 2.0% 0.06
Jun 13 05:00:00 - 05:59:59 86 2.2% 0.02278.0 KB 2.5% 0.08
Jun 13 06:00:00 - 06:59:59 87 2.3% 0.02211.1 KB 1.9% 0.06
Jun 13 07:00:00 - 07:59:59 125 3.2% 0.03415.7 KB 3.8% 0.12
Jun 13 08:00:00 - 08:59:59 129 3.3% 0.04292.7 KB 2.6% 0.08
Jun 13 09:00:00 - 09:59:59 191 5.0% 0.05490.7 KB 4.4% 0.14
Jun 13 10:00:00 - 10:59:59 233 6.1% 0.06563.4 KB 5.1% 0.16
Jun 13 11:00:00 - 11:59:59 193 5.0% 0.05454.0 KB 4.1% 0.13
Jun 13 12:00:00 - 12:59:59 198 5.1% 0.06481.9 KB 4.3% 0.13
Jun 13 13:00:00 - 13:59:59 189 4.9% 0.05477.0 KB 4.3% 0.13
Jun 13 14:00:00 - 14:59:59 176 4.6% 0.05423.7 KB 3.8% 0.12
Jun 13 15:00:00 - 15:59:59 203 5.3% 0.06518.6 KB 4.7% 0.14
Jun 13 16:00:00 - 16:59:59 231 6.0% 0.06722.6 KB 6.5% 0.20
Jun 13 17:00:00 - 17:59:59 199 5.2% 0.061004.9 KB 9.1% 0.28
Jun 13 18:00:00 - 18:59:59 242 6.3% 0.07862.0 KB 7.8% 0.24
Jun 13 19:00:00 - 19:59:59 203 5.3% 0.06529.5 KB 4.8% 0.15
Jun 13 20:00:00 - 20:59:59 159 4.1% 0.04460.8 KB 4.2% 0.13
Jun 13 21:00:00 - 21:59:59 185 4.8% 0.05589.2 KB 5.3% 0.16
Jun 13 22:00:00 - 22:59:59 151 3.9% 0.04360.7 KB 3.3% 0.10
Jun 13 23:00:00 - 23:59:59 139 3.6% 0.04473.1 KB 4.3% 0.13
Jun 14 00:00:00 - 00:59:59 139 3.6% 0.04359.7 KB 3.2% 0.10
Jun 14 01:00:00 - 01:59:59 82 2.1% 0.02188.2 KB 1.7% 0.05
Jun 14 02:00:00 - 02:59:59 129 3.3% 0.04428.3 KB 3.9% 0.12
Jun 14 03:00:00 - 03:00:06 11 0.3% 1.8318.1 KB 0.2% 3.02
TOTAL: 24:00:01 3851 100.0% 0.0410.8 MB 100.0% 0.13
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1392 1312 0 0 0 0 0 0 80
2news.sklaffkom.se 26 25 0 0 0 0 0 0 1
TOTAL: 2 1418 1337 0 0 0 0 0 0 81

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.sport.calcio.milan 44
pl.misc.samochody 43
de.rec.fahrrad 42
alt.usage.english 41
de.rec.fotografie 38
pl.soc.prawo 37
de.alt.fan.pluesch 30
nl.religie 30
uk.d-i-y 27
it.discussioni.misteri 25
lada.talk 25
bln.test 24
fido7.ru.home 24
alt.bestjobsusa.computer.jobs 23
dk.politik 23
uk.legal.moderated 23
alt.food.fast-food 20
cn.comp 20
linux.debian.user 20
de.rec.film.misc 19
TOTAL: 230 1337

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 23
User-issued cancel 20
Too many newsgroups 4
EMP (md5) 1
TOTAL: 4 48

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 3864 3862 0 0 0 0 99%23:59:24
2sklaffkom.se 4158 947 1932 965 0 1 22%23:59:42
3weretis.net 47 0 40 0 0 0 0%06:01:23
4sklaff.dev 0 0 0 0 0 3843 0%23:59:43
5thebunker.nu 0 0 0 0 0 3851 0%23:59:57
6usenet.blueworldhosting.com 0 0 0 0 0 3441 0%23:59:57
TOTAL: 6 8069 4809 1972 965 0 11136 59%126:00:06
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se10.8 MB0.0 KB10.8 MB0.1 KB/s2.9 KB23:59:24
2sklaffkom.se1.5 MB3.0 MB4.5 MB0.1 KB/s2.4 KB23:59:42
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
4thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s006:01:23
TOTAL: 612.4 MB3.0 MB15.3 MB0.0 KB/s2.7 KB126:00:06
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1prod51client01.academyforinternetresearch.org 60 00.0 KB 0 0 000:00:19
253.195.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3172-234-237-248.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:00
4o324.scanner.modat.io 1 00.0 KB 0 0 000:00:00
5o340.scanner.modat.io 1 00.0 KB 0 0 000:00:00
6o342.scanner.modat.io 1 00.0 KB 0 0 000:00:00
7o346.scanner.modat.io 1 00.0 KB 0 0 000:00:00
8r4-239-ef.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 8 69 00.0 KB 0 0 000:00:27

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.academyforinternetresearch.org 60 00.0 KB 0 0 000:00:19
2*.scanner.modat.io 4 00.0 KB 0 0 000:00:00
3*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
4*.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:00
5*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 5 69 00.0 KB 0 0 000:00:27

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
prod51client01.academyforinternetresearch.org 1.712 0.487 0.00000:00:19
53.195.132.66.censys-scanner.com 0.042 0.031 0.00000:00:04
r4-239-ef.monitoring.internet-measurement.com 0.006 0.017 0.00000:00:01
172-234-237-248.ip.linodeusercontent.com 0.004 0.015 0.00000:00:00
o340.scanner.modat.io 0.004 0.013 0.00000:00:00
o346.scanner.modat.io 0.004 0.014 0.00000:00:00
o324.scanner.modat.io 0.004 0.011 0.00000:00:00
o342.scanner.modat.io 0.005 0.013 0.00000:00:00
TOTAL: 8 1.781 0.601 0.00000:00:27

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

SystemConn
53.195.132.66.censys-scanner.com 1
TOTAL: 1 1

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

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
53.195.132.66.censys-scanner.com 1 2
prod51client01.academyforinternetresearch.org 1 1
TOTAL: 2 2 3