Daily Usenet Report

Mar 12 00:01:01 -- Mar 13 00:01:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Mar 12 00:07:55 www innd: message repeated 2 times: [ ctlinnd command s]
Mar 12 08:41:03 www nnrpd[2887371]: unable to get certificates from '/news/etc/cert.pem'
Mar 12 08:41:03 www nnrpd[2887371]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]
Mar 12 08:41:15 www nnrpd[2887685]: unable to get certificates from '/news/etc/cert.pem'
Mar 12 08:41:15 www nnrpd[2887685]: 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 19085 67.9%5.0 MB 79.1%
innd 6433 22.9%845.1 KB 13.2%
innfeed 2533 9.0%487.3 KB 7.6%
nnrpd 45 0.2%5.1 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 28097 100.0%6.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 105042 53.5%
Negative hits 72553 36.9%
Do not exist 18806 9.6%
Cache misses 7 0.0%
TOTAL: 4 196408 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.025 0.0%56 0.000 0.446 2.000
article cleanup00:00:00.370 0.0%18846 0.000 0.020 0.147
article logging00:00:00.927 0.0%19071 0.000 0.049 0.139
article parse00:00:02.255 0.0%133903 0.000 0.017 0.052
article write00:00:08.416 0.0%10934 0.315 0.770 3.895
artlog/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
data move00:00:00.903 0.0%307720 0.000 0.003 0.008
hisgrep/artcncl00:00:00.006 0.0%64 0.000 0.094 0.833
hishave/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.251 0.0%197060 0.007 0.047 0.127
history sync00:00:00.049 0.0%369 0.000 0.133 1.000
history write00:00:10.518 0.0%18846 0.113 0.558 0.925
idle22:57:27.919 99.7%300860 55.074 274.706 1348.387
nntp read00:00:21.953 0.0%302983 0.024 0.072 0.162
overview write00:00:06.609 0.0%10934 0.179 0.604 6.318
perl filter00:00:50.071 0.1%18846 1.748 2.657 5.203
python filter00:00:02.695 0.0%18846 0.066 0.143 0.417
site send00:00:03.896 0.0%21868 0.066 0.178 0.455
TOTAL: 23:00:55.46622:59:25.863 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.125 0.0%10948 0.000 0.011 0.171
article prepare00:00:00.004 0.0%32400 0.000 0.000 0.004
article read00:00:01.262 0.0%4850 0.000 0.260 2.500
backlog stats00:00:00.550 0.0%270425 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.301 0.0%180985 0.018 0.029 0.070
data write00:00:25.421 0.0%170846 0.109 0.149 0.284
idle22:56:40.286 99.9%270425 167.092 305.446 879.096
status file00:00:00.204 0.0%188 0.000 1.085 4.000
TOTAL: 22:57:24.50222:57:13.153100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:31.927 91.9%19 31.500 1680.368 10024.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%72 0.000 0.014 0.200
TOTAL: 00:00:34.73600:00:31.928 91.9%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 3 17139 5556 11411 172 32%37:46:00
2usenet.blueworldhosting.com 5 12109 3596 7915 598 29%24:35:40
3outgoing.news.lysator.liu.se 1 11652 708 10806 138 6%23:59:57
4newsfeed.xs3.de 2 9378 409 8969 0 4%23:59:08
5usenet.network 2 10322 245 10032 45 2%23:59:57
6glockenspiel.complete.org 2 11510 178 11331 1 1%26:19:48
7endofthelinebbs.peers.news.panix.com 67 15102 109 9793 5200 0%28:25:18
8news.samoylyk.net 1 9576 48 9524 4 0%23:59:48
9news.furie.org.uk 1 5073 15 5058 0 0%23:59:53
10news.bbs.nz 2 9748 1 9747 0 0%23:59:36
11news.netfront.net 2 11722 1 10111 1610 0%18:59:49
12localhost 24 0 0 0 0 0%00:00:01
13news.nk.ca 2 0 0 0 0 0%00:20:22
14eotl-out.news.tnetconsulting.net 2 0 0 0 0 0%00:20:29
TOTAL: 14 116 123331 10866 104697 7768 8%280:45:46
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1usenet.blueworldhosting.com22.0 MB0.0 KB3.1 MB25.1 MB 87%6.1 KB
2endofthelinebbs-out.news.weretis.net16.4 MB0.0 KB320.9 KB16.7 MB 98%3.0 KB
3endofthelinebbs.peers.news.panix.com3.7 MB0.0 KB190.2 MB193.9 MB 1%37.4 KB
4usenet.network1.5 MB0.0 KB702.5 KB2.2 MB 69%7.9 KB
5newsfeed.xs3.de1.4 MB0.0 KB0.0 KB1.4 MB100%3.4 KB
6outgoing.news.lysator.liu.se1.1 MB0.0 KB405.8 KB1.5 MB 73%1.8 KB
7glockenspiel.complete.org424.7 KB0.0 KB3.3 KB428.0 KB 99%2.4 KB
8news.samoylyk.net226.2 KB0.0 KB12.3 KB238.5 KB 94%4.6 KB
9news.furie.org.uk42.8 KB0.0 KB0.0 KB42.8 KB100%2.9 KB
10news.netfront.net16.0 KB0.0 KB556.4 MB556.4 MB 0%353.6 KB
11news.bbs.nz2.0 KB0.0 KB0.0 KB2.0 KB100%2.0 KB
12localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
13news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
14eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1446.9 MB0.0 KB751.0 MB797.9 MB 5%43.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 12 00:01:01 - 00:59:59 227 2.1% 0.06737.0 KB 0.0% 0.21
Mar 12 01:00:00 - 01:59:59 340 3.1% 0.091.0 MB 5.4% 0.29
Mar 12 02:00:00 - 02:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Mar 12 03:00:00 - 03:59:59 462 4.2% 0.132.0 MB 10.5% 0.56
Mar 12 04:00:00 - 04:59:59 530 4.8% 0.151.6 MB 8.4% 0.45
Mar 12 05:00:00 - 05:59:59 576 5.3% 0.161.8 MB 9.8% 0.52
Mar 12 06:00:00 - 06:59:59 579 5.3% 0.161.5 MB 7.9% 0.42
Mar 12 07:00:00 - 07:59:59 558 5.1% 0.151.6 MB 8.7% 0.47
Mar 12 08:00:00 - 08:59:59 587 5.4% 0.162.2 MB 11.8% 0.63
Mar 12 09:00:00 - 09:59:59 611 5.6% 0.172.9 MB 15.3% 0.82
Mar 12 10:00:00 - 10:59:59 648 5.9% 0.182.6 MB 13.7% 0.73
Mar 12 11:00:00 - 11:59:59 755 6.9% 0.212.7 MB 14.1% 0.76
Mar 12 12:00:00 - 12:59:59 574 5.2% 0.161.9 MB 10.1% 0.54
Mar 12 13:00:00 - 13:59:59 675 6.2% 0.193.6 MB 19.0% 1.02
Mar 12 14:00:00 - 14:59:59 622 5.7% 0.173.1 MB 16.7% 0.90
Mar 12 15:00:00 - 15:59:59 595 5.4% 0.172.3 MB 12.0% 0.64
Mar 12 16:00:00 - 16:59:59 526 4.8% 0.152.9 MB 15.1% 0.81
Mar 12 17:00:00 - 17:59:59 404 3.7% 0.114.2 MB 22.1% 1.18
Mar 12 18:00:00 - 18:59:59 327 3.0% 0.092.7 MB 14.3% 0.77
Mar 12 19:00:00 - 19:59:59 404 3.7% 0.111.3 MB 6.9% 0.37
Mar 12 20:00:00 - 20:59:59 247 2.3% 0.07914.1 KB 4.7% 0.25
Mar 12 21:00:00 - 21:59:59 224 2.0% 0.06721.2 KB 3.7% 0.20
Mar 12 22:00:00 - 22:59:59 229 2.1% 0.061.0 MB 5.4% 0.29
Mar 12 23:00:00 - 23:59:59 229 2.1% 0.061.2 MB 6.3% 0.34
Mar 13 00:00:00 - 00:01:02 11 0.1% 0.1839.0 KB 0.2% 0.63
TOTAL: 24:00:01 10940 100.0% 0.1318.9 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1endofthelinebbs.peers.news.panix.com 5181 5181 0 0 0 0 0 0 0
2news.netfront.net 1769 0 0 0 0 0 0 0 1769
3usenet.blueworldhosting.com 601 11 0 0 0 0 0 0 590
4endofthelinebbs-out.news.weretis.net 375 0 0 0 0 0 0 0 375
5outgoing.news.lysator.liu.se 139 0 0 0 0 0 0 0 139
6usenet.network 45 0 0 0 0 0 0 0 45
7glockenspiel.complete.org 15 0 0 0 0 0 0 0 15
8newsfeed.xs3.de 8 0 0 0 0 0 0 0 8
9news.samoylyk.net 4 0 0 0 0 0 0 0 4
TOTAL: 9 8137 5192 0 0 0 0 0 0 2945

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.backup 2181
ab.alt.binaries.bitburger 1572
alt.binaries.bloaf 440
alt.binaries.ftd 239
ab.alt.binaries.pizza 238
ab.alt.binaries.boneless 194
4u.alt.binaries.4u 153
ab.alt.binaries.moovee 35
alt.binaries.pictures.wallpaper 18
alt.binaries.multimedia.erotica.amateur 14
4u.alt.binaries.bloaf 12
finet.binaries.keskustelu 11
alt.binaries.pictures.scenic 7
alt.binaries.pictures.young.celebrities 6
es.binaries.bd 6
alt.binaries.pictures.nude.celebrities.fake 6
rocksolid.feeds.news 5
alt.binaries.test.yenc 5
alt.binaries.sounds.mp3.new-age 4
alt.binaries.e-book.flood 4
TOTAL: 50 5192

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 459
Binary: misplaced binary fx02.ams4.POSTED 296
Binary: misplaced binary fx14.ams4.POSTED 271
EMP (phn path) 238
Binary: misplaced binary fx11.iad.POSTED 230
Binary: misplaced binary fx10.iad.POSTED 228
Binary: misplaced binary fx15.ams4.POSTED 198
Too many newsgroups 150
Binary: misplaced binary fx13.ams4.POSTED 103
Binary: misplaced binary fx04.ams4.POSTED 98
Binary: misplaced binary fx08.ams4.POSTED 79
Binary: misplaced binary fx05.ams4.POSTED 78
Binary: misplaced binary fx10.ams4.POSTED 70
EMP (phl) 38
Binary: misplaced binary fx03.ams4.POSTED 34
Binary: misplaced binary fx11.ams4.POSTED 27
Scoring filter 16
Binary: misplaced binary fx09.ams4.POSTED 15
Binary: misplaced binary fx06.ams4.POSTED 14
Binary: misplaced binary fx01.ams4.POSTED 14
TOTAL: 31 2720

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 201
usenet.blueworldhosting.com 13
newsfeed.xs3.de 8
news.samoylyk.net 2
outgoing.news.lysator.liu.se 1
TOTAL: 5 225
TOTAL: 1 225

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 15738 4345 6537 1 0 0 27%22:59:52
2nyheter.lysator.liu.se 10494 2802 7292 135 0 0 26%22:59:56
3news.nk.ca 14629 200 10497 132 0 4 1%22:59:56
4endofthelinebbs.peers.news.panix.com 11184 76 10812 43 0 0 0%22:59:56
5usenet.network 13401 25 10222 0 0 0 0%22:59:55
6news.bbs.nz 21092 17 10774 89 0 9 0%22:59:56
7usenet.blueworldhosting.com 8151 14 7338 5 0 0 0%22:59:56
8news.furie.org.uk 8063 13 5274 57 0 0 0%22:59:56
9news.quux.org 11254 10 10681 68 0 0 0%22:59:51
10news.tnetconsulting.net 11545 1 10866 9 0 7 0%22:59:56
11news.weretis.net 10977 1 10858 22 0 0 0%22:59:52
12news.samoylyk.net 13494 0 10830 1 0 0 0%22:59:56
13peer.alt119.net 0 0 0 0 0 10937 0%22:59:54
14newsfeed.xs3.de 13232 0 10424 53 0 0 0%22:59:56
TOTAL: 14 163254 7504 122405 615 0 10957 4%321:58:48
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net17.5 MB9.9 KB17.5 MB0.2 KB/s4.1 KB22:59:52
2nyheter.lysator.liu.se16.3 MB1.3 MB17.6 MB0.2 KB/s6.1 KB22:59:56
3news.nk.ca3.4 MB2.6 MB5.9 MB0.1 KB/s18.3 KB22:59:56
4news.quux.org1.0 MB761.2 KB1.7 MB0.0 KB/s23.0 KB22:59:51
5news.furie.org.uk469.1 KB713.8 KB1.2 MB0.0 KB/s16.9 KB22:59:56
6endofthelinebbs.peers.news.panix.com335.0 KB82.2 KB417.2 KB0.0 KB/s3.5 KB22:59:56
7news.bbs.nz117.1 KB1.3 MB1.4 MB0.0 KB/s13.9 KB22:59:56
8usenet.network60.5 KB0.0 KB60.5 KB0.0 KB/s2.4 KB22:59:55
9usenet.blueworldhosting.com52.7 KB24.8 KB77.5 KB0.0 KB/s4.1 KB22:59:56
10news.tnetconsulting.net5.1 KB90.6 KB95.7 KB0.0 KB/s9.6 KB22:59:56
11news.weretis.net1.2 KB730.6 KB731.8 KB0.0 KB/s31.8 KB22:59:52
12news.samoylyk.net0.0 KB184.6 KB184.6 KB0.0 KB/s184.6 KB22:59:56
13peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:59:54
14newsfeed.xs3.de0.0 KB670.6 KB670.6 KB0.0 KB/s12.7 KB22:59:56
TOTAL: 1439.1 MB8.4 MB47.5 MB0.0 KB/s6.0 KB321:58:48
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1159.65.246.73 1 00.0 KB 0 0 000:00:11
2139-144-52-241.ip.linodeusercontent.com 9 00.0 KB 0 0 000:00:21
3gutsy.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 3 11 00.0 KB 0 0 000:00:34

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
2unresolved 1 00.0 KB 0 0 000:00:11
3*.ip.linodeusercontent.com 9 00.0 KB 0 0 000:00:21
TOTAL: 3 11 00.0 KB 0 0 000:00:34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
139-144-52-241.ip.linodeusercontent.com 0.699 0.237 0.00000:00:21
159.65.246.73 0.081 0.041 0.00000:00:11
gutsy.monitoring.internet-measurement.com 0.103 0.031 0.00000:00:01
TOTAL: 3 0.883 0.309 0.00000:00:34

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

SystemConn
139-144-52-241.ip.linodeusercontent.com 4
TOTAL: 1 4

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

CommandCount
#026#003#001#002 1
#026#003#001 1
À#024 1
#010#013#010#004#010#005#010#006#004#001#005#001#006#001#003#003#003#001#003#002#004#002#005#002#006#002 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
139-144-52-241.ip.linodeusercontent.com 1 1
TOTAL: 1 1 1