Daily Usenet Report

Feb 15 00:01:01 -- Feb 16 00:01:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Feb 15 00:06:37 www innd: message repeated 2 times: [ ctlinnd command s]
Feb 15 10:00:06 www pgpverify[2316331]: pgpverify: verification failed
Feb 15 18:24:56 www nnrpd[2329486]: unable to get certificates from '/news/etc/cert.pem'
Feb 15 18:24:56 www nnrpd[2329486]: 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 15009 65.2%3.8 MB 76.0%
innd 5559 24.1%784.1 KB 15.4%
innfeed 2331 10.1%419.6 KB 8.3%
nnrpd 102 0.4%9.3 KB 0.2%
controlchan 23 0.1%5.7 KB 0.1%
pgpverify 1 0.0%0.0 KB 0.0%
TOTAL: 6 23025 100.0%5.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 91215 56.4%
Negative hits 56015 34.6%
Do not exist 14581 9.0%
Cache misses 38 0.0%
TOTAL: 4 161849 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.104 0.0%69 0.000 1.507 7.000
article cleanup00:00:00.325 0.0%14642 0.000 0.022 0.086
article logging00:00:00.641 0.0%14941 0.000 0.043 0.161
article parse00:00:02.077 0.0%114931 0.000 0.018 0.068
article write00:00:09.177 0.0%11177 0.374 0.821 2.396
artlog/artcncl00:00:00.001 0.0%68 0.000 0.015 0.100
data move00:00:00.630 0.0%263727 0.000 0.002 0.007
hisgrep/artcncl00:00:00.013 0.0%68 0.000 0.191 1.000
hishave/artcncl00:00:00.008 0.0%69 0.000 0.116 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:07.860 0.0%162293 0.007 0.048 0.116
history sync00:00:00.055 0.0%353 0.000 0.156 1.000
history write00:00:08.985 0.0%14640 0.064 0.614 0.871
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:57:43.248 99.8%253220 53.185 340.665 1862.584
nntp read00:00:19.441 0.0%254117 0.019 0.077 0.133
overview write00:00:06.735 0.0%11177 0.141 0.603 5.227
perl filter00:00:39.094 0.0%14640 1.432 2.670 4.617
python filter00:00:02.864 0.0%14641 0.095 0.196 0.462
site send00:00:03.895 0.0%22356 0.038 0.174 0.318
TOTAL: 24:00:43.88723:59:25.153 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.144 0.0%15544 0.000 0.009 0.078
article prepare00:00:00.007 0.0%38104 0.000 0.000 0.005
article read00:00:01.598 0.0%5800 0.111 0.276 2.524
backlog stats00:00:00.594 0.0%228553 0.000 0.003 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.227 0.0%153097 0.014 0.034 0.050
data write00:00:20.040 0.0%143310 0.056 0.140 0.221
idle23:56:36.150100.0%228553 176.790 377.139 1080.128
status file00:00:00.184 0.0%197 0.000 0.934 3.000
TOTAL: 23:57:14.35023:57:03.944100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:34.095 96.2%10 26.000 3409.500 10033.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%20 0.000 0.050 0.500
TOTAL: 00:00:35.44800:00:34.096 96.2%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002112
TOTAL002112

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 4 22565 6167 16132 266 27%52:19:48
2usenet.blueworldhosting.com 4 12755 4099 8009 647 32%24:47:01
3outgoing.news.lysator.liu.se 1 11922 638 10949 335 5%23:59:58
4newsfeed.xs3.de 2 9822 294 9528 0 2%23:59:15
5news.samoylyk.net 2 10007 36 9967 4 0%23:59:38
6glockenspiel.complete.org 2 10429 33 10363 33 0%23:59:43
7news.bbs.nz 2 10956 5 10951 0 0%26:47:13
8news.furie.org.uk 2 5697 4 5693 0 0%23:59:43
9localhost 26 1 1 0 0 100%00:00:00
10news.netfront.net 2 15777 0 13465 2312 0%29:12:53
11news.nk.ca 2 0 0 0 0 0%00:21:22
12eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:05
TOTAL: 12 50 109931 11277 95057 3597 10%253:36:39
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1usenet.blueworldhosting.com26.0 MB0.0 KB2.1 MB28.1 MB 92%6.1 KB
2endofthelinebbs-out.news.weretis.net20.9 MB1.8 KB506.1 KB21.4 MB 97%3.4 KB
3outgoing.news.lysator.liu.se1.3 MB0.0 KB919.3 KB2.2 MB 59%2.3 KB
4newsfeed.xs3.de1.3 MB0.0 KB0.0 KB1.3 MB100%4.6 KB
5glockenspiel.complete.org230.4 KB3.7 KB85.6 KB319.7 KB 72%4.8 KB
6news.samoylyk.net100.5 KB0.0 KB7.0 KB107.5 KB 93%2.7 KB
7news.furie.org.uk10.9 KB0.0 KB0.0 KB10.9 KB100%2.7 KB
8news.bbs.nz6.8 KB0.0 KB0.0 KB6.8 KB100%1.4 KB
9localhost1.2 KB0.0 KB0.0 KB1.2 KB100%1.2 KB
10news.netfront.net0.0 KB0.0 KB814.9 MB814.9 MB 0%360.9 KB
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: 1249.9 MB5.5 KB818.5 MB868.4 MB 5%59.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 15 00:01:01 - 00:59:59 289 2.6% 0.082.5 MB 5.0% 0.73
Feb 15 01:00:00 - 01:59:59 393 3.5% 0.111.3 MB 2.7% 0.38
Feb 15 02:00:00 - 02:59:59 512 4.6% 0.141.8 MB 3.6% 0.51
Feb 15 03:00:00 - 03:59:59 510 4.6% 0.142.1 MB 4.1% 0.59
Feb 15 04:00:00 - 04:59:59 521 4.7% 0.141.8 MB 3.6% 0.51
Feb 15 05:00:00 - 05:59:59 560 5.0% 0.162.0 MB 3.9% 0.56
Feb 15 06:00:00 - 06:59:59 546 4.9% 0.151.8 MB 3.5% 0.50
Feb 15 07:00:00 - 07:59:59 519 4.6% 0.141.8 MB 3.5% 0.50
Feb 15 08:00:00 - 08:59:59 554 5.0% 0.151.8 MB 3.7% 0.52
Feb 15 09:00:00 - 09:59:59 686 6.1% 0.193.8 MB 7.6% 1.08
Feb 15 10:00:00 - 10:59:59 697 6.2% 0.192.7 MB 5.3% 0.76
Feb 15 11:00:00 - 11:59:59 752 6.7% 0.212.5 MB 5.1% 0.72
Feb 15 12:00:00 - 12:59:59 604 5.4% 0.173.0 MB 6.0% 0.86
Feb 15 13:00:00 - 13:59:59 550 4.9% 0.152.9 MB 5.8% 0.83
Feb 15 14:00:00 - 14:59:59 549 4.9% 0.152.3 MB 4.5% 0.64
Feb 15 15:00:00 - 15:59:59 457 4.1% 0.132.8 MB 5.5% 0.79
Feb 15 16:00:00 - 16:59:59 423 3.8% 0.122.7 MB 5.4% 0.78
Feb 15 17:00:00 - 17:59:59 437 3.9% 0.122.6 MB 5.2% 0.75
Feb 15 18:00:00 - 18:59:59 332 3.0% 0.092.1 MB 4.2% 0.59
Feb 15 19:00:00 - 19:59:59 315 2.8% 0.091.4 MB 2.8% 0.40
Feb 15 20:00:00 - 20:59:59 190 1.7% 0.05845.5 KB 1.6% 0.23
Feb 15 21:00:00 - 21:59:59 269 2.4% 0.07921.5 KB 1.8% 0.26
Feb 15 22:00:00 - 22:59:59 210 1.9% 0.06915.4 KB 1.8% 0.25
Feb 15 23:00:00 - 23:59:59 290 2.6% 0.081.6 MB 3.3% 0.47
Feb 16 00:00:00 - 00:01:01 6 0.1% 0.10168.0 KB 0.3% 2.75
TOTAL: 24:00:00 11171 100.0% 0.1350.1 MB 100.0% 0.59
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 2169 0 0 0 0 0 0 0 2169
2usenet.blueworldhosting.com 598 83 0 0 0 0 0 0 515
3endofthelinebbs-out.news.weretis.net 539 0 0 1 0 0 0 0 538
4outgoing.news.lysator.liu.se 371 0 0 0 0 0 0 0 371
5glockenspiel.complete.org 77 0 0 1 0 0 0 0 76
6newsfeed.xs3.de 12 0 0 0 0 0 0 0 12
7news.samoylyk.net 4 0 0 0 0 0 0 0 4
TOTAL: 7 3770 83 0 2 0 0 0 0 3685

Unwanted newsgroups [Top 20]:

NewsgroupCount
panix.test 31
panix.staff.general 16
panix.staff.tech-staff 7
panix.staff.netbsd.current-users 7
panix.chat 5
rocksolid.feeds.news 4
t-netz.wrestling.wwf 4
rocksolid.feeds.tech 2
panix.questions 2
panix.staff.netbsd.announce 1
gun.general 1
sympatico.highspeed 1
sympatico.support 1
ee.arvutid 1
TOTAL: 14 83

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 564
EMP (phn path) 462
Binary: misplaced binary fx12.ams4.POSTED 335
Binary: misplaced binary fx17.iad.POSTED 328
Binary: misplaced binary fx16.iad.POSTED 180
Binary: misplaced binary fx15.iad.POSTED 130
Binary: misplaced binary fx15.ams4.POSTED 105
Binary: misplaced binary fx04.ams4.POSTED 95
EMP (phn nph) 94
Binary: misplaced binary fx01.ams4.POSTED 91
Binary: misplaced binary fx05.ams4.POSTED 88
Binary: misplaced binary fx11.ams4.POSTED 83
Binary: misplaced binary fx08.ams4.POSTED 82
Binary: misplaced binary fx18.iad.POSTED 76
Binary: misplaced binary fx09.ams4.POSTED 67
Binary: misplaced binary fx14.iad.POSTED 63
Binary: misplaced binary fx06.ams4.POSTED 62
Binary: misplaced binary fx07.ams4.POSTED 56
Binary: misplaced binary fx09.iad.POSTED 49
Binary: misplaced binary fx02.ams4.POSTED 47
TOTAL: 36 3386

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 273
newsfeed.xs3.de 12
usenet.blueworldhosting.com 10
outgoing.news.lysator.liu.se 3
news.samoylyk.net 1
TOTAL: 5 299
TOTAL: 1 299

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 14869 5542 5607 21 2 21 37%23:59:56
2nyheter.lysator.liu.se 10730 3456 7050 17 0 0 32%23:59:56
3news.bbs.nz 21494 20 10983 159 0 82 0%23:59:56
4news.nk.ca 12247 19 11362 106 0 3939 0%23:59:56
5news.quux.org 11479 12 10945 171 0 0 0%23:59:06
6news.furie.org.uk 7523 9 5947 1 0 0 0%23:59:56
7usenet.blueworldhosting.com 7950 5 7112 0 0 3 0%23:59:26
8news.weretis.net 11245 4 11164 1 0 0 0%23:59:30
9news.tnetconsulting.net 11746 2 11161 0 0 0 0%23:59:56
10newsfeed.xs3.de 13969 1 10872 1 0 0 0%23:59:56
11news.samoylyk.net 14271 1 11140 0 0 0 0%23:59:56
12peer.alt119.net 0 0 0 0 0 11165 0%23:57:47
TOTAL: 12 137523 9071 103343 477 2 15210 6%287:55:17
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net27.5 MB363.9 KB27.9 MB0.3 KB/s5.1 KB23:59:56
2nyheter.lysator.liu.se21.3 MB259.4 KB21.5 MB0.3 KB/s6.4 KB23:59:56
3news.bbs.nz539.7 KB1.0 MB1.5 MB0.0 KB/s8.8 KB23:59:56
4news.nk.ca100.9 KB559.4 KB660.3 KB0.0 KB/s5.3 KB23:59:56
5news.furie.org.uk52.1 KB5.0 KB57.1 KB0.0 KB/s5.7 KB23:59:56
6news.quux.org49.0 KB1.1 MB1.2 MB0.0 KB/s6.6 KB23:59:06
7usenet.blueworldhosting.com25.5 KB0.0 KB25.5 KB0.0 KB/s5.1 KB23:59:26
8news.weretis.net13.8 KB6.1 KB19.9 KB0.0 KB/s4.0 KB23:59:30
9news.tnetconsulting.net10.4 KB0.0 KB10.4 KB0.0 KB/s5.2 KB23:59:56
10newsfeed.xs3.de1.2 KB6.1 KB7.3 KB0.0 KB/s3.6 KB23:59:56
11news.samoylyk.net1.2 KB0.0 KB1.2 KB0.0 KB/s1.2 KB23:59:56
12peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
TOTAL: 1249.6 MB3.3 MB52.9 MB0.1 KB/s5.7 KB287:55:17
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.186.32.124 1 00.0 KB 5 0 000:00:00
TOTAL: 1 1 00.0 KB 5 0 000:00:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.eu.edgewatch.com 1 00.0 KB 0 0 000:00:10
2unresolved 4 00.0 KB 5 0 000:00:20
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
TOTAL: 4 8 00.0 KB 5 0 000:00:35

NNRP auth users [Top 20]:

UserConn
nelgin 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
explorer.eu.edgewatch.com 0.116 0.032 0.00000:00:10
104.152.52.214 0.071 0.040 0.00000:00:10
185.114.175.11 0.077 0.047 0.00000:00:10
amazing.monitoring.internet-measurement.com 0.067 0.032 0.00000:00:01
auspicious.monitoring.internet-measurement.com 0.084 0.021 0.00000:00:01
scanner-07.ch1.censys-scanner.com 0.094 0.028 0.00000:00:00
192.241.208.233 0.070 0.046 0.00000:00:00
TOTAL: 7 0.579 0.246 0.00000:00:35

Curious NNRP server explorers [Top 20]:

SystemConn
185.114.175.11 1
auspicious.monitoring.internet-measurement.com 1
explorer.eu.edgewatch.com 1
192.241.208.233 1
104.152.52.214 1
amazing.monitoring.internet-measurement.com 1
scanner-07.ch1.censys-scanner.com 1
TOTAL: 7 7

NNRP unrecognized commands (by host):

SystemConn
scanner-07.ch1.censys-scanner.com 1
192.241.208.233 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
MGLNDD_192.138.210.162_119 1
EHLO www.censys.io 1
TOTAL: 2 2

Newsgroup request counts (by category):

CategoryCountPct
1news 16 84.2%
2alt 2 10.5%
3dfw 1 5.3%
TOTAL: 3 19100.0%

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

NewsgroupCount
1news.software.nntp 14
2news.admin.peering 2
3dfw.forsale 1
4alt.genealogy 1
5alt.music.dire-straits 1
TOTAL: 5 19