Daily Usenet Report

Oct 29 00:01:02 -- Oct 30 00:01:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Oct 29 00:05:41 www innd: message repeated 2 times: [ ctlinnd command s]
Oct 29 06:00:02 www nnrpd[2418134]: unable to get certificates from '/news/etc/cert.pem'
Oct 29 06:00:02 www nnrpd[2418134]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]

Log entries by program:

Program nameLines%LinesSize%Size
inn 12764 66.8%3.3 MB 76.8%
innd 4020 21.0%584.7 KB 13.4%
innfeed 2296 12.0%422.4 KB 9.7%
nnrpd 35 0.2%3.7 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 19116 100.0%4.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 87341 54.8%
Negative hits 59526 37.4%
Do not exist 12470 7.8%
Cache misses 12 0.0%
TOTAL: 4 159349 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.065 0.0%45 0.000 1.444 9.000
article cleanup00:00:00.205 0.0%12521 0.000 0.016 0.105
article logging00:00:00.556 0.0%12729 0.000 0.044 0.148
article parse00:00:00.331 0.0%18939 0.000 0.017 0.091
article write00:00:08.469 0.0%10606 0.348 0.799 3.467
artlog/artcncl00:00:00.011 0.0%45 0.000 0.244 2.500
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.128 0.0%161631 0.000 0.001 0.004
hisgrep/artcncl00:00:00.005 0.0%45 0.000 0.111 1.500
hishave/artcncl00:00:00.002 0.0%45 0.000 0.044 1.000
hishave/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.831 0.0%159916 0.004 0.036 0.083
history sync00:00:00.056 0.0%405 0.000 0.138 1.000
history write00:00:06.126 0.0%12520 0.053 0.489 0.852
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:58:45.092 99.8%159378 294.536 541.637 3015.462
nntp read00:00:07.948 0.0%160520 0.024 0.050 0.077
overview write00:00:06.497 0.0%10606 0.203 0.613 4.581
perl filter00:00:31.503 0.0%12520 1.827 2.516 4.873
python filter00:00:01.664 0.0%12520 0.050 0.133 0.360
site send00:00:03.696 0.0%21212 0.065 0.174 0.525
TOTAL: 24:01:01.66823:59:58.185 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.120 0.0%10752 0.000 0.011 0.115
article prepare00:00:00.001 0.0%18486 0.000 0.000 0.005
article read00:00:00.836 0.0%3342 0.000 0.250 5.333
backlog stats00:00:00.408 0.0%217621 0.000 0.002 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:06.296 0.0%146486 0.018 0.043 0.077
data write00:00:17.846 0.0%137042 0.090 0.130 0.220
idle23:56:37.787100.0%217621 231.582 396.091 1277.543
status file00:00:00.181 0.0%169 0.000 1.071 3.000
TOTAL: 23:57:12.54223:57:03.475100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:04.175 79.0%11 25.000 379.545 1973.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%40 0.000 0.025 0.077
TOTAL: 00:00:05.28300:00:04.176 79.0%----

Control commands to INND:

CommandNumber
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 146
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 167

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 5 19850 5308 14004 538 26%45:37:03
2usenet.blueworldhosting.com 5 11730 3058 8125 547 26%22:14:37
3peer.alt119.net 2 12743 1581 10340 822 12%24:59:50
4newsfeed.xs3.de 1 9849 332 9506 11 3%23:59:55
5news.samoylyk.net 2 10148 60 10085 3 0%24:59:39
6glockenspiel.complete.org 1 10625 19 10604 2 0%23:59:52
7outgoing.news.lysator.liu.se 2 10212 15 10197 0 0%24:59:57
8news.furie.org.uk 2 5800 9 5791 0 0%24:59:50
9news.bbs.nz 2 9847 7 9840 0 0%24:14:44
10localhost 25 0 0 0 0 0%00:00:00
11news.nk.ca 1 0 0 0 0 0%00:10:00
12eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:05
TOTAL: 12 49 100804 10389 88492 1923 10%240:25:32
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net17.9 MB0.0 KB1015.2 KB18.9 MB 94%3.3 KB
2usenet.blueworldhosting.com17.4 MB0.0 KB1.5 MB18.9 MB 92%5.4 KB
3peer.alt119.net3.6 MB1.4 KB2.6 MB6.2 MB 58%2.6 KB
4newsfeed.xs3.de1.5 MB0.0 KB23.8 KB1.5 MB 98%4.6 KB
5news.samoylyk.net238.0 KB0.0 KB36.1 KB274.2 KB 86%4.4 KB
6glockenspiel.complete.org194.8 KB0.0 KB13.4 KB208.2 KB 93%9.9 KB
7outgoing.news.lysator.liu.se171.1 KB0.0 KB0.0 KB171.1 KB100%11.4 KB
8news.furie.org.uk38.3 KB0.0 KB0.0 KB38.3 KB100%4.3 KB
9news.bbs.nz12.8 KB0.0 KB0.0 KB12.8 KB100%1.8 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
11news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
12eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1241.1 MB1.4 KB5.1 MB46.2 MB 88%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 29 00:01:02 - 00:59:59 223 2.1% 0.061.2 MB 2.8% 0.34
Oct 29 01:00:00 - 01:59:59 335 3.2% 0.092.7 MB 6.4% 0.76
Oct 29 02:00:00 - 02:59:59 432 4.1% 0.121.2 MB 2.9% 0.34
Oct 29 03:00:00 - 03:59:59 558 5.3% 0.151.7 MB 3.9% 0.47
Oct 29 04:00:00 - 04:59:59 531 5.0% 0.151.8 MB 4.3% 0.51
Oct 29 05:00:00 - 05:59:59 573 5.4% 0.161.9 MB 4.6% 0.54
Oct 29 06:00:00 - 06:59:59 498 4.7% 0.141.8 MB 4.2% 0.50
Oct 29 07:00:00 - 07:59:59 574 5.4% 0.163.0 MB 7.2% 0.86
Oct 29 08:00:00 - 08:59:59 535 5.0% 0.152.2 MB 5.2% 0.62
Oct 29 09:00:00 - 09:59:59 635 6.0% 0.182.3 MB 5.4% 0.64
Oct 29 10:00:00 - 10:59:59 664 6.3% 0.182.1 MB 5.1% 0.61
Oct 29 11:00:00 - 11:59:59 613 5.8% 0.173.4 MB 8.0% 0.96
Oct 29 12:00:00 - 12:59:59 562 5.3% 0.162.0 MB 4.7% 0.55
Oct 29 13:00:00 - 13:59:59 527 5.0% 0.151.5 MB 3.7% 0.44
Oct 29 14:00:00 - 14:59:59 542 5.1% 0.152.5 MB 6.1% 0.72
Oct 29 15:00:00 - 15:59:59 544 5.1% 0.151.9 MB 4.4% 0.53
Oct 29 16:00:00 - 16:59:59 502 4.7% 0.142.4 MB 5.7% 0.68
Oct 29 17:00:00 - 17:59:59 391 3.7% 0.111.3 MB 3.0% 0.36
Oct 29 18:00:00 - 18:59:59 342 3.2% 0.101.2 MB 2.8% 0.33
Oct 29 19:00:00 - 19:59:59 244 2.3% 0.07809.9 KB 1.9% 0.22
Oct 29 20:00:00 - 20:59:59 226 2.1% 0.061.4 MB 3.3% 0.39
Oct 29 21:00:00 - 21:59:59 210 2.0% 0.06691.6 KB 1.6% 0.19
Oct 29 22:00:00 - 22:59:59 162 1.5% 0.04605.7 KB 1.4% 0.17
Oct 29 23:00:00 - 23:59:59 168 1.6% 0.05559.1 KB 1.3% 0.16
Oct 30 00:00:00 - 00:01:02 5 0.0% 0.0812.4 KB 0.0% 0.20
TOTAL: 24:00:00 10596 100.0% 0.1241.8 MB 100.0% 0.50
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1peer.alt119.net 814 0 0 1 0 0 0 0 813
2endofthelinebbs-out.news.weretis.net 736 1 0 0 0 0 0 0 735
3usenet.blueworldhosting.com 555 48 0 0 3 0 0 0 504
4newsfeed.xs3.de 14 0 0 0 0 0 0 0 14
5news.samoylyk.net 3 0 0 0 0 0 0 0 3
6outgoing.news.lysator.liu.se 1 0 0 0 0 0 0 0 1
TOTAL: 6 2123 49 0 1 3 0 0 0 2070

Unwanted newsgroups [Top 20]:

NewsgroupCount
at.jobs 11
panix.chat.politics 10
att.jobs 8
aus.jobs 6
rocksolid.feeds.news 5
ee.arvutid 4
alt.music.pink-floyd.publius 2
al.christnet.bible 1
grisbi.test 1
gun.general 1
TOTAL: 10 49

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

GroupsCount
misc.activism.progressive 1
soc.politics 1
rec.guns 1
TOTAL: 3 3

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 978
EMP (md5) 510
EMP (phl) 170
Too many newsgroups 106
EMP (phn nph) 76
Scoring filter 20
HTML Multipart 1
Too many newsgroups (low_xpost) 1
TOTAL: 8 1862

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 195
peer.alt119.net 6
usenet.blueworldhosting.com 3
newsfeed.xs3.de 3
outgoing.news.lysator.liu.se 1
TOTAL: 5 208
TOTAL: 1 208

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nyheter.lysator.liu.se 12888 2391 7761 431 1 94 18%23:59:56
2peer.alt119.net 9907 1079 7927 4 0 0 10%23:59:47
3news.nk.ca 14158 223 10271 83 0 0 1%23:59:56
4news.furie.org.uk 8650 59 5892 10 0 13 0%23:59:56
5news.quux.org 10971 25 10383 168 0 0 0%23:59:44
6news.bbs.nz 20820 18 10466 103 0 41 0%23:59:56
7news.tnetconsulting.net 11942 11 10567 12 0 0 0%23:59:56
8usenet.blueworldhosting.com 7807 3 7397 0 0 0 0%23:59:56
9news.weretis.net 10692 2 10590 2 0 0 0%23:59:56
10news.samoylyk.net 13398 1 10536 2 0 7 0%23:59:56
11theuse.net 0 0 0 0 0 10594 0%23:59:56
12newsfeed.xs3.de 12957 0 10261 1 0 11 0%23:59:56
TOTAL: 12 134190 3812 102051 816 1 10760 2%287:58:51
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nyheter.lysator.liu.se12.3 MB1.9 MB14.2 MB0.2 KB/s5.2 KB23:59:56
2peer.alt119.net4.5 MB18.7 KB4.5 MB0.1 KB/s4.2 KB23:59:47
3news.nk.ca467.6 KB310.6 KB778.2 KB0.0 KB/s2.5 KB23:59:56
4news.furie.org.uk371.9 KB191.6 KB563.6 KB0.0 KB/s8.2 KB23:59:56
5news.bbs.nz119.0 KB402.9 KB521.9 KB0.0 KB/s4.3 KB23:59:56
6news.quux.org66.8 KB561.0 KB627.9 KB0.0 KB/s3.3 KB23:59:44
7news.tnetconsulting.net40.3 KB15.7 KB56.0 KB0.0 KB/s2.4 KB23:59:56
8news.weretis.net5.3 KB3.1 KB8.4 KB0.0 KB/s2.1 KB23:59:56
9usenet.blueworldhosting.com3.7 KB0.0 KB3.7 KB0.0 KB/s1.2 KB23:59:56
10news.samoylyk.net1.3 KB4.8 KB6.1 KB0.0 KB/s2.0 KB23:59:56
11theuse.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
12newsfeed.xs3.de0.0 KB2.9 KB2.9 KB0.0 KB/s2.9 KB23:59:56
TOTAL: 1217.8 MB3.4 MB21.2 MB0.0 KB/s4.7 KB287:58:51
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1likeable.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
2183.136.225.45 2 00.0 KB 0 0 000:00:00
3192.241.192.199 1 00.0 KB 0 0 000:00:00
4congratulated.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
5scanner-04.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 5 6 00.0 KB 0 0 000:00:05

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:04
2unresolved 3 00.0 KB 0 0 000:00:00
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 3 6 00.0 KB 0 0 000:00:05

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
likeable.monitoring.internet-measurement.com 0.059 0.028 0.00000:00:02
congratulated.monitoring.internet-measurement.com 0.094 0.033 0.00000:00:01
183.136.225.45 0.155 0.046 0.00000:00:00
scanner-04.ch1.censys-scanner.com 0.085 0.054 0.00000:00:00
192.241.192.199 0.110 0.035 0.00000:00:00
TOTAL: 5 0.503 0.196 0.00000:00:05

NNRP unrecognized commands (by host):

SystemConn
183.136.225.45 10
scanner-04.ch1.censys-scanner.com 1
192.241.192.199 1
TOTAL: 3 12

NNRP unrecognized commands (by command):

CommandCount
Host: 192.138.210.162:119 2
Accept: */* 2
User-Agent: Mozilla/5.0 (Macintosh; Inte... 2
Connection: keep-alive 2
GET / HTTP/1.1 2
MGLNDD_192.138.210.162_119 1
EHLO www.censys.io 1
TOTAL: 7 12