Daily Usenet Report

Mar 22 00:01:01 -- Mar 23 00:01:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Mar 22 00:07:47 www innd: message repeated 2 times: [ ctlinnd command s]
Mar 22 13:25:55 www nnrpd[559536]: unable to get certificates from '/news/etc/cert.pem'
Mar 22 13:25:55 www nnrpd[559536]: 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 27170 73.0%7.8 MB 84.1%
innd 7264 19.5%1011.3 KB 10.6%
innfeed 2764 7.4%498.6 KB 5.2%
nnrpd 22 0.1%2.4 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 37221 100.0%9.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 190148 59.7%
Negative hits 102549 32.2%
Do not exist 25777 8.1%
Cache misses 37 0.0%
TOTAL: 4 318511 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.174 0.0%180 0.000 0.967 5.000
article cleanup00:00:00.413 0.0%26875 0.000 0.015 0.072
article logging00:00:01.136 0.0%27170 0.000 0.042 0.105
article parse00:00:01.854 0.0%98908 0.000 0.019 0.044
article write00:00:13.614 0.0%19994 0.325 0.681 1.838
artlog/artcncl00:00:00.001 0.0%15 0.000 0.067 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.625 0.0%374316 0.000 0.002 0.006
hisgrep/artcncl00:00:00.061 0.0%195 0.000 0.313 2.000
hishave/artcncl00:00:00.000 0.0%15 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:13.067 0.0%319643 0.005 0.041 0.070
history sync00:00:00.054 0.0%340 0.000 0.159 1.500
history write00:00:14.264 0.0%25873 0.100 0.551 0.769
idle23:56:28.191 99.7%366485 57.656 235.175 1036.112
nntp read00:00:18.730 0.0%369810 0.020 0.051 0.168
overview write00:00:08.602 0.0%19994 0.178 0.430 8.108
perl filter00:00:56.233 0.1%25872 1.192 2.174 4.382
python filter00:00:03.470 0.0%25872 0.076 0.134 0.286
site send00:00:04.795 0.0%39988 0.042 0.120 0.559
TOTAL: 24:00:48.95523:58:45.284 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.189 0.0%20660 0.000 0.009 0.105
article prepare00:00:00.010 0.0%49736 0.000 0.000 0.005
article read00:00:01.904 0.0%8259 0.000 0.231 2.842
backlog stats00:00:00.995 0.0%496918 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:10.686 0.0%334497 0.016 0.032 0.056
data write00:00:38.911 0.0%317547 0.079 0.123 0.224
idle23:56:02.717 99.9%496918 30.877 173.394 1040.680
status file00:00:00.262 0.0%273 0.000 0.960 4.000
TOTAL: 23:57:13.04223:56:55.674100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle171:26:19.471100.0%10710 21.000 57626.468 57658.199
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.061 0.0%21594 0.000 0.188 0.188
readart00:00:00.106 0.0%361 0.294 0.294 0.294
TOTAL: 171:26:27.516171:26:23.638100.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 10 36538 14102 21396 1040 38%43:28:03
2usenet.blueworldhosting.com 298 13058 3133 9109 816 23%23:46:38
3usenet.network 4 19559 904 18600 55 4%24:18:06
4outgoing.news.lysator.liu.se 3 20489 651 19626 212 3%24:18:08
5newsfeed.xs3.de 4 18302 511 17732 59 2%24:18:00
6endofthelinebbs.peers.news.panix.com 70 22853 311 19714 2828 1%31:01:23
7news.samoylyk.net 3 18773 146 18607 20 0%24:08:39
8glockenspiel.complete.org 4 21498 119 21249 130 0%24:14:43
9news.bbs.nz 3 27778 10 27768 0 0%29:43:55
10news.furie.org.uk 2 5686 3 5683 0 0%23:59:28
11eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:14
12news.nk.ca 2 0 0 0 0 0%00:20:00
13localhost 25 0 0 0 0 0%00:00:00
14news.netfront.net 1 22625 0 20893 1732 0%24:44:06
TOTAL: 14 430 227159 19890 200377 6892 8%298:31:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net25.7 MB2.4 MB838.2 KB29.0 MB 88%2.0 KB
2usenet.blueworldhosting.com23.4 MB882.1 KB3.2 MB27.5 MB 85%7.1 KB
3usenet.network8.2 MB79.9 KB67.8 KB8.3 MB 98%8.9 KB
4newsfeed.xs3.de1.5 MB81.4 KB0.0 KB1.6 MB 94%2.8 KB
5outgoing.news.lysator.liu.se1.3 MB14.2 KB676.7 KB2.0 MB 66%2.4 KB
6endofthelinebbs.peers.news.panix.com986.5 KB5.6 KB18.6 MB19.6 MB 4%6.4 KB
7news.samoylyk.net903.1 KB118.1 KB37.1 KB1.0 MB 85%6.4 KB
8glockenspiel.complete.org495.8 KB87.2 KB333.6 KB916.5 KB 54%3.7 KB
9news.bbs.nz22.5 KB0.0 KB0.0 KB22.5 KB100%2.2 KB
10news.furie.org.uk17.2 KB0.0 KB0.0 KB17.2 KB100%5.7 KB
11eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
12news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
13localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
14news.netfront.net0.0 KB0.0 KB602.5 MB602.5 MB 0%356.2 KB
TOTAL: 1462.5 MB3.7 MB626.2 MB692.4 MB 9%26.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 22 00:01:01 - 00:59:59 265 1.3% 0.072.7 MB 4.5% 0.80
Mar 22 01:00:00 - 01:59:59 368 1.8% 0.101.8 MB 2.9% 0.52
Mar 22 02:00:00 - 02:59:59 641 3.2% 0.181.9 MB 3.2% 0.55
Mar 22 03:00:00 - 03:59:59 2606 13.0% 0.723.5 MB 5.6% 0.99
Mar 22 04:00:00 - 04:59:59 3302 16.5% 0.923.7 MB 6.0% 1.05
Mar 22 05:00:00 - 05:59:59 2411 12.1% 0.675.5 MB 9.0% 1.58
Mar 22 06:00:00 - 06:59:59 1728 8.7% 0.482.4 MB 4.0% 0.70
Mar 22 07:00:00 - 07:59:59 549 2.7% 0.152.4 MB 3.9% 0.69
Mar 22 08:00:00 - 08:59:59 623 3.1% 0.172.7 MB 4.4% 0.77
Mar 22 09:00:00 - 09:59:59 748 3.7% 0.213.7 MB 6.0% 1.06
Mar 22 10:00:00 - 10:59:59 775 3.9% 0.223.4 MB 5.5% 0.97
Mar 22 11:00:00 - 11:59:59 800 4.0% 0.222.6 MB 4.1% 0.73
Mar 22 12:00:00 - 12:59:59 635 3.2% 0.181.8 MB 3.0% 0.52
Mar 22 13:00:00 - 13:59:59 689 3.4% 0.192.5 MB 4.1% 0.72
Mar 22 14:00:00 - 14:59:59 675 3.4% 0.194.7 MB 7.7% 1.35
Mar 22 15:00:00 - 15:59:59 705 3.5% 0.203.0 MB 4.9% 0.85
Mar 22 16:00:00 - 16:59:59 501 2.5% 0.142.1 MB 3.4% 0.59
Mar 22 17:00:00 - 17:59:59 431 2.2% 0.121.7 MB 2.8% 0.50
Mar 22 18:00:00 - 18:59:59 427 2.1% 0.123.7 MB 6.0% 1.06
Mar 22 19:00:00 - 19:59:59 263 1.3% 0.071.0 MB 1.7% 0.29
Mar 22 20:00:00 - 20:59:59 201 1.0% 0.06781.3 KB 1.2% 0.22
Mar 22 21:00:00 - 21:59:59 242 1.2% 0.07971.3 KB 1.5% 0.27
Mar 22 22:00:00 - 22:59:59 208 1.0% 0.062.1 MB 3.4% 0.60
Mar 22 23:00:00 - 23:59:59 174 0.9% 0.05787.9 KB 1.2% 0.22
Mar 23 00:00:00 - 00:01:01 5 0.0% 0.0817.0 KB 0.0% 0.28
TOTAL: 24:00:00 19972 100.0% 0.2361.8 MB 100.0% 0.73
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1endofthelinebbs.peers.news.panix.com 2991 2980 0 11 0 0 0 0 0
2news.netfront.net 1582 0 0 0 0 0 0 0 1582
3endofthelinebbs-out.news.weretis.net 1300 0 0 728 0 0 0 0 572
4usenet.blueworldhosting.com 817 10 0 132 0 0 0 0 675
5outgoing.news.lysator.liu.se 216 0 0 17 0 0 0 0 199
6glockenspiel.complete.org 108 0 0 6 0 0 0 0 102
7newsfeed.xs3.de 69 0 0 59 0 0 0 0 10
8usenet.network 68 0 0 34 0 0 0 0 34
9news.samoylyk.net 32 0 0 16 0 0 0 0 16
TOTAL: 9 7183 2990 0 1003 0 0 0 0 3190

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.backup 2700
alt.binaries.ftd 151
finet.binaries.keskustelu 30
alt.binaries.sounds.karaoke 11
alt.binaries.pictures.nude.celebrities.fake 9
alt.binaries.pictures.scenic 8
alt.binaries.pictures.nude 6
alt.binaries.ebooks 6
alt.binaries.sounds.lossless 6
alt.binaries.test.yenc 5
alt.binaries.nospam.female.bodyhair 5
alt.binaries.e-books.flood 5
rocksolid.feeds.news 5
alt.binaries.ect 4
rocksolid.feeds.tech 3
alt.binaries.movies.thelostmovies 3
alt.binaries.e-book.flood 3
alt.binaries.boneless 3
alt.binaries.comics.dcp 2
alt.binaries.sounds.lossless.country 2
TOTAL: 41 2990

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 562
EMP (phn path) 380
Binary: misplaced binary fx17.iad.POSTED 223
Too many newsgroups 197
Binary: misplaced binary fx09.iad.POSTED 185
Too many newsgroups (low_xpost) 163
Binary: misplaced binary fx05.ams4.POSTED 116
Binary: misplaced binary fx07.ams4.POSTED 97
Binary: misplaced binary fx47.iad.POSTED 93
EMP (phn nph) 90
Binary: misplaced binary fx16.iad.POSTED 87
EMP (phl) 85
Binary: misplaced binary fx14.ams4.POSTED 78
Binary: misplaced binary fx09.ams4.POSTED 78
Binary: misplaced binary fx11.ams4.POSTED 75
Binary: misplaced binary fx02.ams4.POSTED 53
Binary: misplaced binary fx12.ams4.POSTED 48
Binary: misplaced binary fx04.ams4.POSTED 45
Binary: misplaced binary fx15.ams4.POSTED 44
Binary: misplaced binary fx10.ams4.POSTED 41
TOTAL: 34 2894

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
usenet.blueworldhosting.com 4
TOTAL: 1 4
Including strange strings
endofthelinebbs-out.news.weretis.net 263
news.samoylyk.net 12
newsfeed.xs3.de 10
outgoing.news.lysator.liu.se 7
glockenspiel.complete.org 2
usenet.blueworldhosting.com 1
TOTAL: 6 295
No colon-space in header field
usenet.blueworldhosting.com 1
TOTAL: 1 1
TOTAL: 3 300

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 29913 7413 12546 11 0 5 24%23:59:55
2nyheter.lysator.liu.se 19720 3016 15763 540 0 0 15%23:59:32
3endofthelinebbs.peers.news.panix.com 22793 207 19756 2 0 2 0%23:59:56
4news.nk.ca 30150 142 19679 120 0 132 0%23:59:56
5news.bbs.nz 38560 88 19717 158 0 169 0%23:59:56
6news.tnetconsulting.net 26087 34 19868 59 0 39 0%23:59:51
7news.furie.org.uk 7169 28 6079 68 0 1 0%23:59:23
8usenet.network 22036 25 18555 12 0 0 0%23:59:20
9news.quux.org 24014 16 19577 246 0 6 0%23:59:56
10news.weretis.net 20113 14 19860 93 0 0 0%23:59:38
11newsfeed.xs3.de 22571 3 19293 140 0 1 0%23:59:21
12news.samoylyk.net 22861 2 19812 4 0 11 0%23:59:56
13peer.alt119.net 0 0 0 0 0 19967 0%23:57:46
14usenet.blueworldhosting.com 21502 0 16329 0 0 332 0%23:59:53
TOTAL: 14 307489 10988 226834 1453 0 20665 3%335:54:19
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net29.4 MB54.3 KB29.5 MB0.3 KB/s4.1 KB23:59:55
2nyheter.lysator.liu.se20.2 MB5.4 MB25.5 MB0.3 KB/s7.4 KB23:59:32
3endofthelinebbs.peers.news.panix.com1.6 MB7.3 KB1.6 MB0.0 KB/s7.8 KB23:59:56
4news.furie.org.uk904.0 KB738.5 KB1.6 MB0.0 KB/s17.1 KB23:59:23
5news.nk.ca666.6 KB2.5 MB3.1 MB0.0 KB/s12.2 KB23:59:56
6news.quux.org488.3 KB2.6 MB3.0 MB0.0 KB/s11.9 KB23:59:56
7news.samoylyk.net421.5 KB7.2 KB428.8 KB0.0 KB/s71.5 KB23:59:56
8news.bbs.nz232.1 KB1.7 MB1.9 MB0.0 KB/s8.1 KB23:59:56
9news.tnetconsulting.net114.7 KB639.5 KB754.2 KB0.0 KB/s8.1 KB23:59:51
10usenet.network81.3 KB62.5 KB143.9 KB0.0 KB/s3.9 KB23:59:20
11news.weretis.net52.6 KB2.6 MB2.7 MB0.0 KB/s25.8 KB23:59:38
12newsfeed.xs3.de16.2 KB1.9 MB1.9 MB0.0 KB/s13.6 KB23:59:21
13peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:46
14usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 1454.1 MB18.1 MB72.2 MB0.1 KB/s5.9 KB335:54:19
Outgoing feeds (innfeed) by Volume

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 1 2249.0 KB 1 0 0171:26:23
2*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 3 4 2249.0 KB 1 0 0171:26:27

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47.189.156.66 2.991 5.871 0.005171:26:20
192.241.207.91 0.095 0.044 0.00000:00:02
graceful.monitoring.internet-measurement.com 0.075 0.039 0.00000:00:01
masterful.monitoring.internet-measurement.com 0.079 0.042 0.00000:00:01
scanner-06.ch1.censys-scanner.com 0.103 0.044 0.00000:00:00
TOTAL: 5 3.343 6.040 0.005171:26:27

Curious NNRP server explorers [Top 20]:

SystemConn
192.241.207.91 1
scanner-06.ch1.censys-scanner.com 1
graceful.monitoring.internet-measurement.com 1
masterful.monitoring.internet-measurement.com 1
TOTAL: 4 4

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

SystemConn
scanner-06.ch1.censys-scanner.com 1
TOTAL: 1 1

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

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1news 3100.0%
TOTAL: 1 3100.0%

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

NewsgroupCount
1news.software.nntp 3
TOTAL: 1 3