Daily Usenet Report

Sep 25 00:01:02 -- Sep 26 00:01:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 25 00:04:53 www innd: message repeated 2 times: [ ctlinnd command s]
Sep 25 03:20:46 www nnrpd[1174792]: unable to get certificates from '/news/etc/cert.pem'
Sep 25 03:20:46 www nnrpd[1174792]: 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 12346 67.4%3.2 MB 77.5%
innd 3543 19.4%510.5 KB 12.1%
innfeed 2332 12.7%432.9 KB 10.2%
nnrpd 84 0.5%7.1 KB 0.2%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 18306 100.0%4.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 85230 54.3%
Negative hits 59717 38.0%
Do not exist 11992 7.6%
Cache misses 57 0.0%
TOTAL: 4 156996 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.096 0.0%79 0.000 1.215 4.000
article cleanup00:00:00.232 0.0%12014 0.000 0.019 0.084
article logging00:00:00.548 0.0%12265 0.000 0.045 0.145
article parse00:00:00.381 0.0%18278 0.000 0.021 0.102
article write00:00:11.558 0.0%10566 0.414 1.094 2.612
artlog/artcncl00:00:00.002 0.0%78 0.000 0.026 0.500
data move00:00:00.183 0.0%158558 0.000 0.001 0.007
hisgrep/artcncl00:00:00.010 0.0%75 0.000 0.133 1.000
hishave/artcncl00:00:00.001 0.0%79 0.000 0.013 0.100
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.424 0.0%157201 0.005 0.035 0.079
history sync00:00:00.061 0.0%346 0.000 0.176 1.000
history write00:00:06.070 0.0%12008 0.093 0.505 0.925
hiswrite/artcncl00:00:00.003 0.0%4 0.000 0.750 1.000
idle23:58:25.100 99.8%155414 327.309 555.324 1936.906
nntp read00:00:07.319 0.0%157061 0.025 0.047 0.076
overview write00:00:06.180 0.0%10566 0.257 0.585 5.571
perl filter00:00:32.177 0.0%12008 1.918 2.680 6.364
python filter00:00:01.791 0.0%12009 0.057 0.149 0.355
site send00:00:04.471 0.0%21132 0.054 0.212 0.574
TOTAL: 24:00:50.09923:59:41.607 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.113 0.0%10832 0.000 0.010 0.132
article prepare00:00:00.000 0.0%4412 0.000 0.000 0.000
article read00:00:00.293 0.0%914 0.000 0.321 41.000
backlog stats00:00:00.391 0.0%191291 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.832 0.0%138837 0.021 0.042 0.076
data write00:00:16.973 0.0%123914 0.094 0.137 0.237
idle23:55:13.292100.0%191291 254.258 450.169 1305.653
status file00:00:00.206 0.0%178 0.000 1.157 8.000
TOTAL: 23:55:46.40523:55:37.100100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:01.730 73.0%9 25.000 192.222 1143.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%21 0.000 0.048 0.500
TOTAL: 00:00:02.36900:00:01.731 73.1%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 4 19429 4975 13959 495 25%45:26:57
2peer.alt119.net 3 11928 3084 8316 528 25%25:48:44
3usenet.blueworldhosting.com 4 11465 2220 8856 389 19%24:24:25
4newsfeed.xs3.de 2 9435 111 9323 1 1%23:59:12
5news.samoylyk.net 1 9621 44 9576 1 0%23:59:50
6glockenspiel.complete.org 2 9290 29 9258 3 0%23:59:36
7news.furie.org.uk 2 5288 21 5267 0 0%23:59:41
8news.bbs.nz 4 5113 10 5102 1 0%16:53:52
9outgoing.news.lysator.liu.se 1 9622 8 9613 1 0%23:59:57
10localhost 26 1 1 0 0 100%00:00:00
11news.nk.ca 2 0 0 0 0 0%00:20:03
12eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:03
TOTAL: 12 52 91192 10503 79270 1419 11%233:02:20
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net18.0 MB0.8 KB1023.3 KB19.0 MB 94%3.6 KB
2usenet.blueworldhosting.com14.2 MB0.0 KB842.5 KB15.0 MB 94%5.9 KB
3peer.alt119.net7.6 MB0.0 KB1.6 MB9.3 MB 82%2.6 KB
4newsfeed.xs3.de635.4 KB0.4 KB0.4 KB636.2 KB 99%5.7 KB
5news.samoylyk.net430.0 KB34.1 KB34.1 KB498.1 KB 86%11.1 KB
6glockenspiel.complete.org147.4 KB40.0 KB87.5 KB275.0 KB 53%8.6 KB
7outgoing.news.lysator.liu.se111.6 KB0.0 KB2.0 KB113.6 KB 98%12.6 KB
8news.furie.org.uk100.5 KB0.0 KB0.0 KB100.5 KB100%4.8 KB
9news.bbs.nz13.4 KB2.9 KB2.9 KB19.1 KB 69%1.7 KB
10localhost1.6 KB0.0 KB0.0 KB1.6 KB100%1.6 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: 1241.2 MB78.2 KB3.6 MB44.9 MB 91%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 25 00:01:02 - 00:59:59 199 1.9% 0.06632.9 KB 1.5% 0.18
Sep 25 01:00:00 - 01:59:59 312 3.0% 0.09933.2 KB 2.2% 0.26
Sep 25 02:00:00 - 02:59:59 409 3.9% 0.111.2 MB 2.9% 0.34
Sep 25 03:00:00 - 03:59:59 501 4.7% 0.141.8 MB 4.3% 0.51
Sep 25 04:00:00 - 04:59:59 551 5.2% 0.152.0 MB 4.8% 0.58
Sep 25 05:00:00 - 05:59:59 494 4.7% 0.141.5 MB 3.7% 0.44
Sep 25 06:00:00 - 06:59:59 553 5.2% 0.151.6 MB 3.8% 0.45
Sep 25 07:00:00 - 07:59:59 532 5.0% 0.151.7 MB 4.1% 0.49
Sep 25 08:00:00 - 08:59:59 631 6.0% 0.183.3 MB 7.9% 0.93
Sep 25 09:00:00 - 09:59:59 610 5.8% 0.172.2 MB 5.2% 0.62
Sep 25 10:00:00 - 10:59:59 634 6.0% 0.182.3 MB 5.4% 0.65
Sep 25 11:00:00 - 11:59:59 563 5.3% 0.162.4 MB 5.6% 0.67
Sep 25 12:00:00 - 12:59:59 623 5.9% 0.173.2 MB 7.6% 0.90
Sep 25 13:00:00 - 13:59:59 613 5.8% 0.172.1 MB 5.0% 0.59
Sep 25 14:00:00 - 14:59:59 509 4.8% 0.141.5 MB 3.6% 0.43
Sep 25 15:00:00 - 15:59:59 499 4.7% 0.141.7 MB 4.1% 0.49
Sep 25 16:00:00 - 16:59:59 394 3.7% 0.112.0 MB 4.8% 0.57
Sep 25 17:00:00 - 17:59:59 364 3.4% 0.101.4 MB 3.4% 0.41
Sep 25 18:00:00 - 18:59:59 296 2.8% 0.082.5 MB 6.0% 0.71
Sep 25 19:00:00 - 19:59:59 285 2.7% 0.08995.1 KB 2.3% 0.28
Sep 25 20:00:00 - 20:59:59 283 2.7% 0.081.1 MB 2.6% 0.31
Sep 25 21:00:00 - 21:59:59 276 2.6% 0.081.4 MB 3.3% 0.39
Sep 25 22:00:00 - 22:59:59 178 1.7% 0.05725.7 KB 1.7% 0.20
Sep 25 23:00:00 - 23:59:59 252 2.4% 0.071.8 MB 4.2% 0.50
Sep 26 00:00:00 - 00:01:01 8 0.1% 0.1326.0 KB 0.1% 0.43
TOTAL: 23:59:59 10569 100.0% 0.1241.8 MB 100.0% 0.50
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1endofthelinebbs-out.news.weretis.net 717 1 0 1 0 0 0 0 715
2peer.alt119.net 530 0 0 0 0 0 0 0 530
3usenet.blueworldhosting.com 438 77 0 0 0 0 0 0 361
4newsfeed.xs3.de 6 0 0 1 0 0 0 0 5
5news.samoylyk.net 5 0 0 1 0 0 0 0 4
6glockenspiel.complete.org 2 0 0 2 0 0 0 0 0
7news.bbs.nz 1 0 0 1 0 0 0 0 0
TOTAL: 7 1699 78 0 6 0 0 0 0 1615

Unwanted newsgroups [Top 20]:

NewsgroupCount
talk.generic.ua.ws 63
rocksolid.feeds.news 4
alt.ham-radio.fm 2
han.misc.test 2
alt.text 1
grisbi.test 1
talk.from.war.diary 1
gay-net.erotik-storys 1
alt.penthouse.sex.femdom 1
alt.ufo 1
talk.from.diary 1
TOTAL: 11 78

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 588
EMP (md5) 400
EMP (phn nph) 147
Too many newsgroups 126
EMP (phl) 86
Scoring filter 10
HTML Multipart 6
HTML post 1
TOTAL: 8 1364

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 227
peer.alt119.net 12
newsfeed.xs3.de 5
usenet.blueworldhosting.com 4
news.samoylyk.net 3
TOTAL: 5 251
TOTAL: 1 251

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nyheter.lysator.liu.se 13027 509 9783 268 2 129 3%23:59:52
2peer.alt119.net 8348 142 7289 1 0 0 1%23:59:49
3news.furie.org.uk 6007 35 5366 8 0 0 0%23:59:36
4usenet.blueworldhosting.com 10139 24 8077 2 0 15 0%23:59:53
5news.nk.ca 11745 15 10514 13 0 8 0%23:59:55
6news.quux.org 10801 4 10507 26 0 0 0%23:59:41
7news.bbs.nz 20695 4 10514 31 0 110 0%23:59:55
8news.weretis.net 10649 3 10562 3 0 0 0%23:59:24
9news.tnetconsulting.net 11993 3 10559 3 0 21 0%23:59:56
10newsfeed.xs3.de 10556 1 10414 5 0 1 0%23:59:44
11news.samoylyk.net 10872 1 10522 1 0 0 0%23:59:40
12theuse.net 0 0 0 0 0 10568 0%23:59:56
TOTAL: 12 124832 741 104107 361 2 10852 0%287:57:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nyheter.lysator.liu.se3.6 MB903.6 KB4.5 MB0.1 KB/s5.9 KB23:59:52
2peer.alt119.net1.8 MB2.2 KB1.8 MB0.0 KB/s12.8 KB23:59:49
3news.furie.org.uk1.1 MB255.3 KB1.3 MB0.0 KB/s31.1 KB23:59:36
4news.nk.ca83.4 KB26.3 KB109.8 KB0.0 KB/s3.9 KB23:59:55
5usenet.blueworldhosting.com73.9 KB5.4 KB79.3 KB0.0 KB/s3.0 KB23:59:53
6news.quux.org51.5 KB117.3 KB168.8 KB0.0 KB/s5.6 KB23:59:41
7news.tnetconsulting.net22.8 KB92.2 KB115.0 KB0.0 KB/s19.2 KB23:59:56
8news.bbs.nz9.1 KB778.1 KB787.2 KB0.0 KB/s22.5 KB23:59:55
9news.weretis.net4.3 KB5.7 KB10.1 KB0.0 KB/s1.7 KB23:59:24
10newsfeed.xs3.de1.6 KB7.6 KB9.2 KB0.0 KB/s1.5 KB23:59:44
11news.samoylyk.net1.6 KB1.9 KB3.5 KB0.0 KB/s1.8 KB23:59:40
12theuse.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
TOTAL: 126.7 MB2.1 MB8.8 MB0.0 KB/s8.2 KB287:57:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 5 00.0 KB 1 0 000:00:02
TOTAL: 1 5 00.0 KB 1 0 000:00:02

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195.96.137.8 0.346 0.154 0.00000:00:02
192.241.213.72 0.081 0.042 0.00000:00:00
TOTAL: 2 0.427 0.196 0.00000:00:02

Curious NNRP server explorers [Top 20]:

SystemConn
195.96.137.8 4
192.241.213.72 1
TOTAL: 2 5

NNRP unrecognized commands (by host):

SystemConn
195.96.137.8 2
192.241.213.72 1
TOTAL: 2 3

NNRP unrecognized commands (by command):

CommandCount
#026#003#001#002 1
À#024 1
MGLNDD_192.138.210.162_119 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
195.96.137.8 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1dfw 1100.0%
TOTAL: 1 1100.0%

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

NewsgroupCount
1dfw.games 1
TOTAL: 1 1