Daily Usenet report

Jun 10 03:00:05 -- Jun 11 03:00:06

Unknown entries from news log file:

First 34 / 34 lines (100.0%)

Jun 10 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 10 06:10:59 twilightnode nnrpd[1622847]: Reading access from /usr/local/news/etc/readers.conf
Jun 10 06:10:59 twilightnode nnrpd[1622847]: Auth strategy 'localhost' does not match client. Removing.
Jun 10 06:10:59 twilightnode nnrpd[1622847]: r4-142-8e.monitoring.internet-measurement.com res <anonymous>
Jun 10 06:10:59 twilightnode nnrpd[1622847]: r4-142-8e.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Jun 10 06:10:59 twilightnode nnrpd[1622847]: r4-142-8e.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 10 06:10:59 twilightnode nnrpd[1622847]: r4-142-8e.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Jun 10 06:10:59 twilightnode nnrpd[1622847]: r4-142-8e.monitoring.internet-measurement.com no_post anonymous-read
Jun 10 18:05:25 twilightnode nnrpd[1654637]: Reading access from /usr/local/news/etc/readers.conf
Jun 10 18:05:25 twilightnode nnrpd[1654637]: Auth strategy 'localhost' does not match client. Removing.
Jun 10 18:05:25 twilightnode nnrpd[1654637]: azpdwsx3ote8.stretchoid.com res <anonymous>
Jun 10 18:05:25 twilightnode nnrpd[1654637]: azpdwsx3ote8.stretchoid.com no_match_user <anonymous> <localhost>
Jun 10 18:05:25 twilightnode nnrpd[1654637]: azpdwsx3ote8.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 10 18:05:25 twilightnode nnrpd[1654637]: azpdwsx3ote8.stretchoid.com match_user <anonymous> <anonymous>
Jun 10 18:05:25 twilightnode nnrpd[1654637]: azpdwsx3ote8.stretchoid.com no_post anonymous-read
Jun 10 18:05:35 twilightnode nnrpd[1654640]: Reading access from /usr/local/news/etc/readers.conf
Jun 10 18:05:35 twilightnode nnrpd[1654640]: Auth strategy 'localhost' does not match client. Removing.
Jun 10 18:05:35 twilightnode nnrpd[1654640]: azpdwsx3ote8.stretchoid.com res <anonymous>
Jun 10 18:05:35 twilightnode nnrpd[1654640]: azpdwsx3ote8.stretchoid.com no_match_user <anonymous> <localhost>
Jun 10 18:05:35 twilightnode nnrpd[1654640]: azpdwsx3ote8.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 10 18:05:35 twilightnode nnrpd[1654640]: azpdwsx3ote8.stretchoid.com match_user <anonymous> <anonymous>
Jun 10 18:05:35 twilightnode nnrpd[1654640]: azpdwsx3ote8.stretchoid.com no_post anonymous-read
Jun 11 01:36:09 twilightnode nnrpd[1675251]: Reading access from /usr/local/news/etc/readers.conf
Jun 11 01:36:09 twilightnode nnrpd[1675251]: Auth strategy 'localhost' does not match client. Removing.
Jun 11 01:36:09 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com res <anonymous>
Jun 11 01:36:09 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 11 01:36:09 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 01:36:09 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 11 01:36:09 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com no_post anonymous-read
Jun 11 01:36:10 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com res <anonymous>
Jun 11 01:36:10 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 11 01:36:10 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 11 01:36:10 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 11 01:36:10 twilightnode nnrpd[1675251]: 99.195.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6340 68.1%1011.6 KB 71.5%
innfeed 1652 17.7%219.3 KB 15.5%
innd 1267 13.6%178.5 KB 12.6%
nnrpd 48 0.5%5.5 KB 0.4%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 9309 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6772 34.9%
Positive hits 6378 32.8%
Do not exist 6270 32.3%
Cache misses 4 0.0%
TOTAL: 4 19424 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.011 0.0%4 1.000 2.750 3.500
article cleanup00:00:00.070 0.0%6288 0.000 0.011 0.182
article logging00:00:00.258 0.0%6337 0.000 0.041 0.217
article parse00:00:00.069 0.0%7912 0.000 0.009 0.097
article write00:00:03.753 0.0%4345 0.475 0.864 2.583
data move00:00:00.054 0.0%20477 0.000 0.003 0.031
hisgrep/artcncl00:00:00.005 0.0%4 0.000 1.250 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.515 0.0%19482 0.113 0.232 0.793
history sync00:00:00.049 0.0%414 0.000 0.118 1.667
history write00:00:03.128 0.0%6288 0.174 0.497 1.179
idle24:00:37.784100.0%20201 1962.657 4278.886 26095.609
nntp read00:00:00.544 0.0%19821 0.000 0.027 0.081
overview write00:00:01.894 0.0%4345 0.111 0.436 1.231
perl filter00:00:10.880 0.0%6288 0.925 1.730 12.269
python filter00:00:00.856 0.0%6288 0.000 0.136 0.923
site send00:00:00.143 0.0%4345 0.000 0.033 0.270
TOTAL: 1724:01:04.013100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.023 0.0%4321 0.000 0.005 0.071
article prepare00:00:00.001 0.0%25636 0.000 0.000 0.003
article read00:00:00.645 0.0%4320 0.000 0.149 0.706
backlog stats00:00:00.012 0.0%28688 0.000 0.000 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.190 0.0%15969 0.000 0.012 0.052
data write00:00:00.344 0.0%11722 0.000 0.029 0.152
idle23:50:43.822100.0%28688 1645.159 2992.325 7129.540
status file00:00:00.300 0.0%330 0.000 0.909 29.000
TOTAL: 923:50:45.337100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:14.162100.0%6 84.500 2360.333 10286.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%13 0.000 0.000 0.000
TOTAL: 300:00:14.162100.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 4 13210 4273 7081 1856 32%49:49:43
2news.sklaffkom.se 50 173 100 46 27 57%16:41:51
3localhost 25 0 0 0 0 0%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 4 80 13383 4373 7127 1883 32%66:41:34
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.5 MB0.0 KB5.9 MB19.4 MB 69%3.2 KB
2news.sklaffkom.se286.2 KB0.0 KB52.1 KB338.3 KB 84%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: 413.8 MB0.0 KB6.0 MB19.8 MB 69%3.2 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 10 03:00:05 - 03:59:59 80 1.8% 0.02336.3 KB 2.4% 0.09
Jun 10 04:00:00 - 04:59:59 82 1.9% 0.02308.1 KB 2.2% 0.09
Jun 10 05:00:00 - 05:59:59 130 3.0% 0.04466.7 KB 3.3% 0.13
Jun 10 06:00:00 - 06:59:59 131 3.0% 0.04358.7 KB 2.6% 0.10
Jun 10 07:00:00 - 07:59:59 146 3.4% 0.04534.7 KB 3.8% 0.15
Jun 10 08:00:00 - 08:59:59 161 3.7% 0.04494.8 KB 3.5% 0.14
Jun 10 09:00:00 - 09:59:59 192 4.4% 0.051013.5 KB 7.2% 0.28
Jun 10 10:00:00 - 10:59:59 224 5.2% 0.06664.9 KB 4.7% 0.18
Jun 10 11:00:00 - 11:59:59 212 4.9% 0.06503.2 KB 3.6% 0.14
Jun 10 12:00:00 - 12:59:59 208 4.8% 0.06568.4 KB 4.1% 0.16
Jun 10 13:00:00 - 13:59:59 216 5.0% 0.06517.6 KB 3.7% 0.14
Jun 10 14:00:00 - 14:59:59 189 4.3% 0.05500.8 KB 3.6% 0.14
Jun 10 15:00:00 - 15:59:59 291 6.7% 0.08922.0 KB 6.6% 0.26
Jun 10 16:00:00 - 16:59:59 229 5.3% 0.06773.2 KB 5.5% 0.21
Jun 10 17:00:00 - 17:59:59 278 6.4% 0.081.0 MB 7.6% 0.30
Jun 10 18:00:00 - 18:59:59 228 5.2% 0.06762.7 KB 5.4% 0.21
Jun 10 19:00:00 - 19:59:59 179 4.1% 0.05438.9 KB 3.1% 0.12
Jun 10 20:00:00 - 20:59:59 187 4.3% 0.05481.1 KB 3.4% 0.13
Jun 10 21:00:00 - 21:59:59 219 5.0% 0.06765.3 KB 5.5% 0.21
Jun 10 22:00:00 - 22:59:59 234 5.4% 0.07837.9 KB 6.0% 0.23
Jun 10 23:00:00 - 23:59:59 196 4.5% 0.05632.5 KB 4.5% 0.18
Jun 11 00:00:00 - 00:59:59 100 2.3% 0.03354.6 KB 2.5% 0.10
Jun 11 01:00:00 - 01:59:59 100 2.3% 0.03290.2 KB 2.1% 0.08
Jun 11 02:00:00 - 02:59:59 125 2.9% 0.03395.3 KB 2.8% 0.11
Jun 11 03:00:00 - 03:00:06 10 0.2% 1.6710.2 KB 0.1% 1.69
TOTAL: 24:00:01 4347 100.0% 0.0513.7 MB 100.0% 0.16
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1966 1452 0 0 0 0 0 0 514
2news.sklaffkom.se 27 27 0 0 0 0 0 0 0
TOTAL: 2 1993 1479 0 0 0 0 0 0 514

Unwanted newsgroups [Top 20]:

NewsgroupCount
nl.politiek 88
it.sport.calcio.milan 84
alt.usage.english 69
fr.sci.maths 47
uk.legal.moderated 43
cn.comp 40
uk.d-i-y 37
pl.sci.fizyka 35
it.hobby.cucina 34
de.rec.fahrrad 31
muc.lists.freebsd.bugs 30
de.rec.mampf 29
pl.pregierz 26
linux.debian.maint.ocaml.maint 22
pl.misc.samochody 22
alt.slack 21
alt.bestjobsusa.computer.jobs 18
fido7.ru.military 18
hfx.general 18
muc.lists.netbsd.port.vax 18
TOTAL: 234 1479

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 421
User-issued cancel 39
EMP (md5) 2
HTML Multipart 2
Too many newsgroups (meow) 1
TOTAL: 5 465

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4349 4311 0 36 0 0 99%23:58:31
2sklaffkom.se 4697 1035 2239 1066 0 2 22%23:59:51
3weretis.net 144 0 98 0 0 0 0%10:58:30
4sklaff.dev 0 0 0 0 0 4341 0%23:59:56
5thebunker.nu 0 0 0 0 0 4347 0%23:59:56
6usenet.blueworldhosting.com 0 0 0 0 0 3915 0%23:59:56
TOTAL: 6 9190 5346 2337 1102 0 12605 58%130:56:40
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se13.6 MB55.4 KB13.7 MB0.2 KB/s3.2 KB23:58:31
2sklaffkom.se1.7 MB4.1 MB5.8 MB0.1 KB/s2.8 KB23:59:51
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
4thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s010:58:30
TOTAL: 615.3 MB4.2 MB19.5 MB0.0 KB/s3.1 KB130:56:40
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1azpdwsx3ote8.stretchoid.com 2 00.0 KB 0 0 000:00:10
299.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
3r4-142-8e.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 3 4 00.0 KB 0 0 000:00:14

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.stretchoid.com 2 00.0 KB 0 0 000:00:10
2*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 3 4 00.0 KB 0 0 000:00:14

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdwsx3ote8.stretchoid.com 0.007 0.027 0.00000:00:10
r4-142-8e.monitoring.internet-measurement.com 0.003 0.027 0.00000:00:01
99.195.132.66.censys-scanner.com 0.031 0.015 0.00000:00:01
TOTAL: 3 0.041 0.069 0.00000:00:14

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

SystemConn
99.195.132.66.censys-scanner.com 1
azpdwsx3ote8.stretchoid.com 1
TOTAL: 2 2

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

CommandCount
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 2 2
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