Daily Usenet Report

Mar 31 00:01:02 -- Apr 1 00:01:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Mar 31 00:07:53 www innd: message repeated 2 times: [ ctlinnd command s]
Mar 31 09:26:03 www nnrpd[2175809]: unable to get certificates from '/news/etc/cert.pem'
Mar 31 09:26:03 www nnrpd[2175809]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]
Mar 31 09:26:14 www nnrpd[2175960]: unable to get certificates from '/news/etc/cert.pem'
Mar 31 09:26:14 www nnrpd[2175960]: 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 68034 86.6%12.3 MB 88.8%
innd 7771 9.9%1.0 MB 7.5%
innfeed 2625 3.3%510.8 KB 3.6%
nnrpd 128 0.2%11.9 KB 0.1%
controlchan 22 0.0%5.3 KB 0.0%
TOTAL: 5 78580 100.0%13.9 MB100.0%

History cache:

ReasonCount%Count
Negative hits 126519 41.1%
Positive hits 113616 36.9%
Do not exist 67325 21.9%
Cache misses 34 0.0%
TOTAL: 4 307494 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.074 0.0%104 0.000 0.712 5.000
article cleanup00:00:01.018 0.0%67376 0.000 0.015 0.070
article logging00:00:03.737 0.0%67600 0.011 0.055 0.130
article parse00:00:07.810 0.0%550170 0.000 0.014 0.089
article write00:00:13.184 0.0%11287 0.324 1.168 2.750
artlog/artcncl00:00:00.000 0.0%15 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:01.270 0.0%626870 0.000 0.002 0.006
hisgrep/artcncl00:00:00.026 0.0%119 0.000 0.218 1.000
hishave/artcncl00:00:00.000 0.0%15 0.000 0.000 0.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:12.735 0.0%308383 0.007 0.041 0.107
history sync00:00:00.040 0.0%349 0.000 0.115 0.667
history write00:00:21.484 0.0%67376 0.091 0.319 0.935
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:52:36.213 99.4%679013 11.873 126.590 1036.076
nntp read00:01:07.727 0.1%695751 0.025 0.097 0.137
overview write00:00:13.762 0.0%11287 0.190 1.219 103.446
perl filter00:03:22.015 0.2%67374 1.907 2.998 4.244
python filter00:00:07.789 0.0%67374 0.060 0.116 0.400
site send00:00:04.282 0.0%22576 0.044 0.190 0.587
TOTAL: 24:00:44.97123:58:33.166 99.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.143 0.0%11456 0.000 0.012 0.096
article prepare00:00:00.005 0.0%35224 0.000 0.000 0.009
article read00:00:01.420 0.0%5162 0.094 0.275 2.905
backlog stats00:00:00.640 0.0%277139 0.000 0.002 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:06.117 0.0%187754 0.015 0.033 0.048
data write00:00:27.772 0.0%176858 0.110 0.157 0.287
idle23:55:55.606 99.9%277139 169.436 310.875 920.567
status file00:00:00.230 0.0%198 0.000 1.162 8.000
TOTAL: 23:56:43.56623:56:31.933100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:04:47.829 99.3%23 25.500 12514.304 81353.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%90 0.000 0.022 0.200
TOTAL: 00:04:49.83100:04:47.831 99.3%----

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
bofh-control@killfile.org001001
us-control@lists.killfile.org001011
TOTAL002012

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 4 21792 7046 14129 617 32%50:38:12
2usenet.blueworldhosting.com 288 12945 2318 10015 612 17%23:45:04
3usenet.network 2 11617 657 10922 38 5%23:59:53
4outgoing.news.lysator.liu.se 1 12448 635 11242 571 5%23:59:57
5newsfeed.xs3.de 1 9799 418 9370 11 4%23:59:54
6endofthelinebbs.peers.news.panix.com 123 64954 177 11401 53376 0%41:20:59
7news.samoylyk.net 2 10150 83 10063 4 0%23:59:42
8glockenspiel.complete.org 2 11102 73 10958 71 0%24:01:27
9news.bbs.nz 2 10292 11 10281 0 0%25:00:25
10news.netfront.net 1 14365 8 13075 1282 0%25:44:54
11news.furie.org.uk 2 5426 3 5423 0 0%23:59:52
12localhost 25 0 0 0 0 0%00:00:00
13eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:16
14news.nk.ca 2 0 0 0 0 0%00:20:00
TOTAL: 14 456 184890 11429 116879 56582 6%311:00:35
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net28.5 MB0.0 KB1.4 MB29.9 MB 95%4.0 KB
2usenet.blueworldhosting.com14.7 MB0.0 KB3.0 MB17.7 MB 83%6.2 KB
3usenet.network5.0 MB0.0 KB165.9 KB5.2 MB 96%7.7 KB
4newsfeed.xs3.de1.5 MB3.4 KB40.1 KB1.5 MB 97%3.7 KB
5outgoing.news.lysator.liu.se1.4 MB0.0 KB2.4 MB3.8 MB 37%3.2 KB
6endofthelinebbs.peers.news.panix.com825.6 KB0.0 KB2.6 GB2.6 GB 0%50.6 KB
7news.samoylyk.net665.9 KB3.4 KB8.8 KB678.2 KB 98%7.8 KB
8glockenspiel.complete.org394.3 KB958.4 KB155.3 KB1.5 MB 26%10.5 KB
9news.bbs.nz29.0 KB0.0 KB0.0 KB29.0 KB100%2.6 KB
10news.netfront.net19.7 KB0.0 KB423.3 MB423.3 MB 0%336.0 KB
11news.furie.org.uk8.5 KB0.0 KB0.0 KB8.5 KB100%2.8 KB
12localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
13eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
14news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1453.1 MB965.3 KB3.0 GB3.1 GB 1%47.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 31 00:01:02 - 00:59:59 292 2.6% 0.081.2 MB 2.3% 0.34
Mar 31 01:00:00 - 01:59:59 448 4.0% 0.121.4 MB 2.7% 0.40
Mar 31 02:00:00 - 02:59:59 509 4.5% 0.142.2 MB 4.3% 0.63
Mar 31 03:00:00 - 03:59:59 559 5.0% 0.162.4 MB 4.5% 0.67
Mar 31 04:00:00 - 04:59:59 628 5.6% 0.172.4 MB 4.7% 0.69
Mar 31 05:00:00 - 05:59:59 534 4.7% 0.151.9 MB 3.7% 0.55
Mar 31 06:00:00 - 06:59:59 616 5.5% 0.172.2 MB 4.2% 0.63
Mar 31 07:00:00 - 07:59:59 598 5.3% 0.172.2 MB 4.2% 0.62
Mar 31 08:00:00 - 08:59:59 592 5.2% 0.163.0 MB 5.8% 0.87
Mar 31 09:00:00 - 09:59:59 636 5.6% 0.182.5 MB 4.9% 0.72
Mar 31 10:00:00 - 10:59:59 661 5.9% 0.182.3 MB 4.3% 0.64
Mar 31 11:00:00 - 11:59:59 662 5.9% 0.183.1 MB 5.9% 0.88
Mar 31 12:00:00 - 12:59:59 621 5.5% 0.173.7 MB 7.1% 1.06
Mar 31 13:00:00 - 13:59:59 534 4.7% 0.152.3 MB 4.4% 0.66
Mar 31 14:00:00 - 14:59:59 428 3.8% 0.121.5 MB 2.8% 0.42
Mar 31 15:00:00 - 15:59:59 466 4.1% 0.132.7 MB 5.2% 0.77
Mar 31 16:00:00 - 16:59:59 439 3.9% 0.121.8 MB 3.5% 0.52
Mar 31 17:00:00 - 17:59:59 480 4.3% 0.133.9 MB 7.5% 1.12
Mar 31 18:00:00 - 18:59:59 329 2.9% 0.091.6 MB 3.0% 0.45
Mar 31 19:00:00 - 19:59:59 274 2.4% 0.081.0 MB 2.0% 0.29
Mar 31 20:00:00 - 20:59:59 322 2.9% 0.092.0 MB 3.9% 0.57
Mar 31 21:00:00 - 21:59:59 218 1.9% 0.06914.5 KB 1.7% 0.25
Mar 31 22:00:00 - 22:59:59 181 1.6% 0.052.2 MB 4.2% 0.63
Mar 31 23:00:00 - 23:59:59 251 2.2% 0.071.6 MB 3.1% 0.47
Apr 1 00:00:00 - 00:01:02 9 0.1% 0.1576.2 KB 0.1% 1.23
TOTAL: 24:00:00 11287 100.0% 0.1352.3 MB 100.0% 0.62
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1endofthelinebbs.peers.news.panix.com 53327 53325 0 0 0 0 0 0 2
2news.netfront.net 1330 0 0 0 0 0 0 0 1330
3endofthelinebbs-out.news.weretis.net 822 0 0 0 0 0 0 0 822
4usenet.blueworldhosting.com 613 10 0 0 2 0 0 0 601
5outgoing.news.lysator.liu.se 492 0 0 0 0 0 0 0 492
6glockenspiel.complete.org 84 0 0 0 0 0 0 0 84
7usenet.network 34 0 0 0 0 0 0 0 34
8newsfeed.xs3.de 18 0 0 1 0 0 0 0 17
9news.samoylyk.net 12 0 0 1 0 0 0 0 11
TOTAL: 9 56732 53335 0 2 2 0 0 0 3393

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.games.encrypted 23198
alt.binaries.backup 4473
alt.binaries.bloaf 1596
alt.binaries.boneless 1261
alt.binaries.ath 1096
alt.binaries.encrypted 1048
alt.binaries.frogs 809
alt.binaries.warez 784
alt.binaries.multimedia.csi 743
alt.binaries.dvd.midnightmovies 687
alt.binaries.roger 682
alt.binaries.hunters 673
alt.binaries.svcd 660
alt.binaries.lou 647
alt.binaries.residents 612
alt.binaries.mou 608
alt.binaries.ftn 440
alt.binaries.mom 431
alt.binaries.amazing 377
alt.binaries.wtfnzb.bravo 346
TOTAL: 197 53335

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

GroupsCount
alt.penthouse.sex.spanking 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 942
EMP (md5) 575
Too many newsgroups 192
Binary: misplaced binary fx06.ams4.POSTED 128
Binary: misplaced binary fx04.ams4.POSTED 125
Poison newsgroup 120
Binary: misplaced binary fx01.ams4.POSTED 118
Binary: misplaced binary fx02.ams4.POSTED 108
EMP (phl) 80
Binary: misplaced binary fx12.ams4.POSTED 73
EMP (phn nph) 72
Binary: misplaced binary fx05.ams4.POSTED 69
Binary: misplaced binary fx46.iad.POSTED 57
Binary: misplaced binary fx11.ams4.POSTED 54
Binary: misplaced binary fx11.iad.POSTED 53
Binary: misplaced binary fx45.iad.POSTED 49
Binary: misplaced binary fx15.ams4.POSTED 46
Binary: misplaced binary fx10.ams4.POSTED 41
Binary: misplaced binary fx13.ams4.POSTED 38
Binary: misplaced binary fx14.ams4.POSTED 37
TOTAL: 35 3166

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
usenet.blueworldhosting.com 6
TOTAL: 1 6
Including strange strings
endofthelinebbs-out.news.weretis.net 205
newsfeed.xs3.de 8
news.samoylyk.net 8
outgoing.news.lysator.liu.se 2
glockenspiel.complete.org 1
endofthelinebbs.peers.news.panix.com 1
TOTAL: 6 225
No colon-space in header field
usenet.blueworldhosting.com 2
TOTAL: 1 2
TOTAL: 3 233

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 15574 4183 7078 17 0 0 26%23:59:56
2nyheter.lysator.liu.se 10873 2168 7966 515 0 0 19%23:59:52
3endofthelinebbs.peers.news.panix.com 11631 408 10873 3 0 0 3%23:59:49
4news.nk.ca 14185 95 11033 132 0 9 0%23:59:56
5news.furie.org.uk 8717 66 5569 239 0 150 0%23:59:56
6news.bbs.nz 21731 29 11066 178 0 21 0%23:59:55
7usenet.network 12487 23 10150 16 0 0 0%23:59:56
8news.tnetconsulting.net 12550 16 11177 86 0 0 0%23:59:56
9news.weretis.net 11415 6 11120 156 0 0 0%23:59:56
10news.quux.org 11727 4 11071 167 0 0 0%23:59:56
11usenet.blueworldhosting.com 11994 4 8580 0 0 2 0%23:59:56
12newsfeed.xs3.de 13026 1 10568 295 0 0 0%23:59:48
13news.samoylyk.net 13211 0 11190 8 0 1 0%23:59:55
14peer.alt119.net 0 0 0 0 0 11287 0%23:57:46
TOTAL: 14 169121 7003 127441 1812 0 11470 4%335:56:33
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net19.0 MB61.6 KB19.1 MB0.2 KB/s4.6 KB23:59:56
2nyheter.lysator.liu.se10.8 MB4.8 MB15.6 MB0.2 KB/s5.9 KB23:59:52
3endofthelinebbs.peers.news.panix.com1.7 MB3.9 KB1.8 MB0.0 KB/s4.4 KB23:59:49
4news.furie.org.uk1.4 MB2.3 MB3.7 MB0.0 KB/s12.5 KB23:59:56
5news.nk.ca726.9 KB1.3 MB2.1 MB0.0 KB/s9.3 KB23:59:56
6news.bbs.nz131.5 KB1.8 MB1.9 MB0.0 KB/s9.3 KB23:59:55
7news.tnetconsulting.net111.0 KB895.1 KB1006.1 KB0.0 KB/s9.9 KB23:59:56
8usenet.network89.3 KB61.7 KB151.0 KB0.0 KB/s3.9 KB23:59:56
9news.quux.org23.4 KB1.7 MB1.7 MB0.0 KB/s10.3 KB23:59:56
10news.weretis.net21.8 KB1.5 MB1.5 MB0.0 KB/s9.8 KB23:59:56
11usenet.blueworldhosting.com16.3 KB0.0 KB16.3 KB0.0 KB/s4.1 KB23:59:56
12newsfeed.xs3.de10.4 KB2.6 MB2.7 MB0.0 KB/s9.2 KB23:59:48
13news.samoylyk.net0.0 KB155.6 KB155.6 KB0.0 KB/s19.4 KB23:59:55
14peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:46
TOTAL: 1434.1 MB17.2 MB51.3 MB0.0 KB/s6.0 KB335:56:33
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1104.156.155.31 9 00.0 KB 0 0 000:04:36
2104.152.52.238 1 00.0 KB 0 0 000:00:10
3192.241.235.22 1 00.0 KB 0 0 000:00:00
4vivacious.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
594.102.61.41 1 00.0 KB 0 0 000:00:00
6104.152.52.199 1 00.0 KB 0 0 000:00:00
TOTAL: 6 14 00.0 KB 0 0 000:04:49

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
2unresolved 13 00.0 KB 0 0 000:04:47
TOTAL: 2 14 00.0 KB 0 0 000:04:49

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
104.156.155.31 0.829 0.252 0.00000:04:36
104.152.52.238 0.068 0.040 0.00000:00:10
vivacious.monitoring.internet-measurement.com 0.093 0.025 0.00000:00:01
94.102.61.41 0.082 0.059 0.00000:00:00
192.241.235.22 0.096 0.033 0.00000:00:00
104.152.52.199 0.086 0.045 0.00000:00:00
TOTAL: 6 1.254 0.454 0.00000:04:49

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

SystemConn
94.102.61.41 5
104.156.155.31 4
192.241.235.22 1
TOTAL: 3 10

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

CommandCount
Accept-Encoding: gzip 1
À#024 1
áeHl¼ùº¥ 1
#026#003#001#002 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
GET / HTTP/1.1 1
Host: 192.138.210.162:119 1
MGLNDD_192.138.210.162_119 1
Accept: */* 1
#010#013#010#004#010#005#010#006#004#001#005#001#006#001#003#003#002#003#003#001#002#001#003#002#002#002#004#002#005#002#006#002 1
TOTAL: 10 10

NNRP client timeouts [Top 20]:

SystemConnPeer
104.156.155.31 1 1
TOTAL: 1 1 1