Daily Usenet report

Sep 4 03:00:01 -- Sep 5 01:23:02

Unknown entries from news log file:

First 50 / 1221 lines (4.1%)

Sep 4 03:01:58 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:17:41 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:17:51 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:18:01 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:18:11 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:18:21 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:18:29 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:18:39 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:18:51 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:19:01 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:19:13 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:19:23 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:19:31 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:20:13 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:20:23 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:20:31 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:20:43 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:20:55 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:21:05 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:21:16 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:21:26 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:21:32 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:21:46 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:21:56 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:22:12 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:22:26 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:22:32 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:22:48 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:22:58 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:23:08 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:23:18 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:23:24 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:23:48 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:23:56 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:24:10 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:24:50 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:24:58 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:25:10 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:25:20 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:25:30 news innd: message repeated 5 times: [ ctlinnd command s]
Sep 4 10:25:42 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:25:48 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:25:58 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:26:08 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 4 10:26:52 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:27:00 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:27:12 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:27:24 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 4 10:27:32 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 4 10:27:44 news innd: message repeated 3 times: [ ctlinnd command s]

Log entries by program:

Program nameLines%LinesSize%Size
inn 117152 65.9%13.3 MB 71.2%
innd 53644 30.2%4.7 MB 25.2%
nnrpd 5764 3.2%583.9 KB 3.1%
innfeed 1307 0.7%94.7 KB 0.5%
controlchan 5 0.0%0.3 KB 0.0%
TOTAL: 5 177872 100.0%18.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 89446 48.9%
Negative hits 88490 48.3%
Cache misses 4030 2.2%
Positive hits 1101 0.6%
TOTAL: 4 183067 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.165 0.0%290 0.000 0.569 13.000
article cleanup00:00:00.432 0.0%89400 0.000 0.005 1.000
article logging00:00:01.520 0.0%89442 0.000 0.017 1.883
article parse00:00:00.953 0.0%276167 0.000 0.003 1.000
article write00:00:39.939 0.1%58031 0.000 0.688 130.000
data move00:00:00.229 0.0%300598 0.000 0.001 0.750
hisgrep/artcncl00:00:00.106 0.0%290 0.000 0.366 4.250
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:05:42.714 0.5%183069 0.000 1.872 77.000
history sync00:00:00.073 0.0%661 0.000 0.110 35.000
history write00:02:06.905 0.2%89117 0.000 1.424 13.500
idle17:17:16.727 99.0%305763 33.581 203.546 60063.000
nntp read00:00:02.076 0.0%283685 0.000 0.007 1.500
overview write00:00:10.224 0.0%58031 0.000 0.176 47.000
perl filter00:01:16.504 0.1%88681 0.000 0.863 56.000
python filter00:00:04.459 0.0%181446 0.000 0.025 40.500
site send00:00:00.400 0.0%56665 0.000 0.007 0.050
TOTAL: 17:27:42.76117:27:23.426100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.100 0.0%58313 0.000 0.002 0.032
article prepare00:00:00.015 0.0%226532 0.000 0.000 0.003
article read00:00:05.065 0.0%56633 0.000 0.089 1.116
backlog stats00:00:00.037 0.0%246886 0.000 0.000 0.500
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.171 0.0%150470 0.000 0.008 0.071
data write00:00:01.623 0.0%93876 0.000 0.017 0.133
idle17:24:45.094100.0%246886 30.507 253.903 30100.500
status file00:00:00.078 0.0%96 0.000 0.812 13.500
TOTAL: 17:24:55.48817:24:53.183100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.172 0.0%21 0.000 8.190 40.000
idle00:12:56.684 77.7%5567 19.400 139.516 10047.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.119 0.0%13154 0.000 0.009 0.429
readart00:00:00.164 0.0%21 3.000 7.810 29.000
TOTAL: 00:16:39.91100:12:57.139 77.7%----

Control commands to INND:

CommandNumber
go 2
logmode 1
lowmark 1
mode 14834
name 1
pause 1
paused 1
reload 6
reserve 2
xexec 2
TOTAL: 10 14851

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1chimia.se 9 92775 56685 4845 31245 61%09:52:18
2news.sklaff.dev 6 1414 1280 10 124 90%17:39:26
3localhost 19 0 0 0 0 0%00:00:00
TOTAL: 3 34 94189 57965 4855 31369 61%27:31:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1chimia.se213.2 MB1.1 MB122.6 MB336.8 MB 63%3.9 KB
2news.sklaff.dev4.1 MB3.1 KB339.3 KB4.5 MB 92%3.3 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 3217.3 MB1.1 MB122.9 MB341.3 MB 63%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 4 03:00:01 - 03:59:59 101 0.1% 0.03340.0 KB 0.1% 0.09
Sep 4 04:00:00 - 04:59:59 107 0.2% 0.03384.9 KB 0.1% 0.11
Sep 4 05:00:00 - 05:59:59 60 0.1% 0.02149.0 KB 0.1% 0.04
Sep 4 06:00:00 - 06:59:59 67 0.1% 0.02256.0 KB 0.1% 0.07
Sep 4 07:00:00 - 07:59:59 35 0.0% 0.01197.0 KB 0.1% 0.05
Sep 4 08:00:00 - 08:59:59 55 0.1% 0.02219.0 KB 0.1% 0.06
Sep 4 09:00:00 - 09:59:59 83 0.1% 0.02245.3 KB 0.1% 0.07
Sep 4 10:00:00 - 10:59:59 7236 10.3% 2.0122.9 MB 9.0% 6.52
Sep 4 11:00:00 - 11:59:59 4744 6.7% 1.3215.9 MB 6.3% 4.52
Sep 4 12:00:00 - 12:59:59 5858 8.3% 1.6332.4 MB 12.7% 9.21
Sep 4 13:00:00 - 13:59:59 6571 9.3% 1.8319.2 MB 7.6% 5.47
Sep 4 14:00:00 - 14:59:59 3495 5.0% 0.9710.9 MB 4.3% 3.10
Sep 4 15:00:00 - 15:59:59 1095 1.6% 0.304.4 MB 1.7% 1.26
Sep 4 16:00:00 - 16:59:59 5396 7.7% 1.5036.7 MB 14.4% 10.45
Sep 4 17:00:00 - 17:59:59 8882 12.6% 2.4727.5 MB 10.8% 7.82
Sep 4 18:00:00 - 18:59:59 5039 7.2% 1.4011.5 MB 4.5% 3.26
Sep 4 19:00:00 - 19:59:59 7352 10.4% 2.0424.3 MB 9.6% 6.92
Sep 4 20:00:00 - 20:59:59 4800 6.8% 1.3323.8 MB 9.3% 6.77
Sep 4 21:00:00 - 21:59:59 4013 5.7% 1.119.6 MB 3.8% 2.72
Sep 4 22:00:00 - 22:59:59 1293 1.8% 0.363.1 MB 1.2% 0.89
Sep 4 23:00:00 - 23:59:59 1387 2.0% 0.393.3 MB 1.3% 0.95
Sep 5 00:00:00 - 00:59:59 1923 2.7% 0.535.1 MB 2.0% 1.45
Sep 5 01:00:00 - 01:23:02 803 1.1% 0.582.0 MB 0.8% 1.49
TOTAL: 22:23:01 70395 100.0% 0.87254.5 MB 100.0% 3.23
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1chimia.se 46545 266 0 278 3 436 0 045562
2news.sklaff.dev 212 0 0 2 0 0 0 0 210
TOTAL: 2 46757 266 0 280 3 436 0 045772

Unwanted newsgroups [Top 20]:

NewsgroupCount
net.general 247
net.config 7
net.subculture.usenet 3
net.support.psych.misc 3
net.computers.os.unix.linux 2
net.current-events.general 1
net.food.chocolate 1
net.medicine.clinical.general 1
net.sexuality.general 1
TOTAL: 9 266

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
news.newusers.questions 3
TOTAL: 1 3

INND Perl filter [Top 20]:

ReasonCount
EMP (phn nph) 8064
EMP (phn path) 7290
EMP (phl) 5110
U2 violation - invalid distribution 3087
HTML Multipart 2348
Too many newsgroups 1767
EMP (md5) 1626
Too many newsgroups (meow) 836
HTML post 55
Binary: misplaced binary 42
Bad path (newsfeed.slurp.net) 40
Subject Rejected 27
EMP (fsl) 26
Too many newsgroups (low_xpost) 23
MI5 Filter 22
Bad path (quark.idirect.com.POSTED) 13
Bad path (news0.telusplanet.net.POSTED) 12
Bad path (comstar.ru) 3
Bot - Newsgroup autoposter 3
Bad path (master.news.hetnet.nl) 1
TOTAL: 25 30400

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
chimia.se 1752
TOTAL: 1 1752
Including strange strings
news.sklaff.dev 33
chimia.se 20
TOTAL: 2 53
TOTAL: 2 1805

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaff.dev 56334 7517 8 48787 0 1672 13%09:49:17
TOTAL: 1 56334 7517 8 48787 0 1672 13%09:49:17
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1sklaff.dev20.4 MB191.6 MB211.9 MB6.1 KB/s3.9 KB09:49:17
TOTAL: 120.4 MB191.6 MB211.9 MB6.1 KB/s3.9 KB09:49:17
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
181-229-79-101-no600.tbcn.telia.com 36 2190.6 KB 21 0 000:00:19
TOTAL: 1 36 2190.6 KB 21 0 000:16:39

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.tbcn.telia.com 36 2190.6 KB 21 0 000:00:19
2*.blueworldhosting.com1776 00.0 KB 0 0 000:15:46
3unresolved 30 00.0 KB 0 0 000:00:32
4*.211.203.35.bc.googleusercontent.com 2 00.0 KB 0 0 000:00:00
5*.rn.hr.cox.net 1 00.0 KB 0 0 000:00:01
TOTAL: 51845 2190.6 KB 21 0 000:16:39

NNRP auth users [Top 20]:

UserConn
peterlondon 35
TOTAL: 1 35

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
usenet.blueworldhosting.com 15.517 9.809 0.00000:15:46
81-229-79-101-no600.tbcn.telia.com 0.333 0.154 0.64700:00:19
152.32.235.90 0.021 0.014 0.00000:00:10
167.94.146.48 0.051 0.019 0.00000:00:04
162.142.125.34 0.039 0.007 0.00000:00:03
167.94.146.58 0.052 0.019 0.00000:00:03
104.152.52.204 0.006 0.005 0.00000:00:01
104.152.52.133 0.128 0.021 0.00000:00:01
104.152.52.211 0.009 0.005 0.00000:00:01
wsip-184-178-172-3.rn.hr.cox.net 0.007 0.003 0.00000:00:01
205.210.31.249 0.008 0.003 0.00000:00:00
104.152.52.146 0.342 0.019 0.00000:00:00
147.185.132.94 0.009 0.003 0.00000:00:00
104.152.52.129 0.036 0.009 0.00000:00:00
104.152.52.202 0.007 0.006 0.00000:00:00
104.152.52.209 0.006 0.007 0.00000:00:00
104.152.52.131 0.040 0.004 0.00000:00:00
104.152.52.130 0.041 0.003 0.00000:00:00
14.211.203.35.bc.googleusercontent.com 0.039 0.006 0.00000:00:00
242.211.203.35.bc.googleusercontent.com 0.038 0.007 0.00000:00:00
TOTAL: 21 16.769 10.125 0.64700:16:39

Curious NNRP server explorers [Top 20]:

SystemConn
usenet.blueworldhosting.com 1776
104.152.52.146 5
104.152.52.133 3
152.32.235.90 3
167.94.146.48 3
167.94.146.58 3
104.152.52.126 1
104.152.52.129 1
104.152.52.130 1
104.152.52.131 1
104.152.52.139 1
104.152.52.144 1
104.152.52.202 1
104.152.52.204 1
104.152.52.209 1
104.152.52.211 1
14.211.203.35.bc.googleusercontent.com 1
147.185.132.94 1
162.142.125.34 1
205.210.31.249 1
TOTAL: 22 1809

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 6
TOTAL: 1 6

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

SystemConn
104.152.52.133 5
104.152.52.211 5
14.211.203.35.bc.googleusercontent.com 4
242.211.203.35.bc.googleusercontent.com 4
104.152.52.129 2
104.152.52.202 2
104.152.52.209 2
152.32.235.90 2
104.152.52.204 1
162.142.125.34 1
167.94.146.48 1
167.94.146.58 1
wsip-184-178-172-3.rn.hr.cox.net 1
TOTAL: 13 31

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

CommandCount
GET / HTTP/1.1 4
EHLO www.censys.io 3
Accept-Encoding: gzip 2
Accept: */* 2
Connection: close 2
EHLO 109.238.130.155 2
Host: 109.238.130.155 2
Host: 109.238.130.155:563 2
MAIL FROM:<relaycheck_please_ignore@prot... 2
User-Agent: Hello from Palo Alto Network... 2
User-Agent: curl/7.61.1 2
#026#003#001 1
#026#003#001#001#027#001 1
SSH-2.0-Go 1
{"id":1,"jsonrpc":"2.0","method":"login"... 1
À#024 1
À#024#023#001#023#002#023#003#001 1
TOTAL: 17 31

NNRP client timeouts [Top 20]:

SystemConnPeer
104.152.52.129 1 1
104.152.52.133 1 1
14.211.203.35.bc.googleusercontent.com 1 1
167.94.146.48 1 2
167.94.146.58 1 2
242.211.203.35.bc.googleusercontent.com 1 1
TOTAL: 6 6 12

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1news 20 95.2%
2swnet 1 4.8%
TOTAL: 2 21100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1news.admin.peering 20
2swnet.sklaff.test 1
TOTAL: 2 21
innreport from INN 2.7.2 based on initial work (©) 1996-2001 by Fabien Tassin <fta@sofaraway.org> and enhanced by several contributors since then