Daily Usenet Report

Sep 5 00:01:01 -- Sep 6 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 5 08:12:34 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 6 00:00:01 newsfeed nnrpd[3678196]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
inn 8496 28.9%3.6 MB 55.8%
nnrpd 8349 28.4%860.8 KB 13.1%
innd 7901 26.9%1.1 MB 17.5%
innfeed 4167 14.2%820.7 KB 12.5%
cnfsstat 432 1.5%63.2 KB 1.0%
nocem 35 0.1%3.9 KB 0.1%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 7 29381 100.0%6.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 116822 60.1%
Negative hits 69188 35.6%
Do not exist 8304 4.3%
Cache misses 4 0.0%
TOTAL: 4 194318 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.315 0.0%69 0.000 4.565 10.917
article cleanup00:00:00.452 0.0%8424 0.000 0.054 3.017
article logging00:00:00.427 0.0%8510 0.000 0.050 0.250
article parse00:00:00.188 0.0%12201 0.000 0.015 0.100
article write00:00:02.413 0.0%7426 0.000 0.325 5.156
artlog/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.788 0.0%200278 0.000 0.004 0.136
hisgrep/artcncl00:00:00.258 0.0%74 0.000 3.486 10.000
hishave/artcncl00:00:00.001 0.0%6 0.000 0.167 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:43.518 0.2%196780 0.101 0.831 5.440
history sync00:00:00.071 0.0%385 0.000 0.184 1.000
history write00:02:17.757 0.2%8424 3.000 16.353 152.720
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:43:38.285 98.8%198075 69.392 431.242 1648.931
nntp read00:00:03.755 0.0%200404 0.003 0.019 0.204
overview write00:10:47.527 0.7%7426 0.500 87.197 7430.344
perl filter00:00:27.425 0.0%8424 1.479 3.256 17.688
python filter00:00:01.564 0.0%8424 0.000 0.186 14.280
site send00:00:01.246 0.0%14885 0.000 0.084 0.580
TOTAL: 24:01:01.25124:00:05.990 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.016 0.0%7566 0.000 0.002 0.030
article prepare00:00:00.008 0.0%46640 0.000 0.000 0.009
article read00:00:00.464 0.0%3410 0.000 0.136 0.517
backlog stats00:00:00.339 0.0%259780 0.000 0.001 0.016
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.455 0.0%182403 0.004 0.013 0.048
data write00:00:03.133 0.0%176964 0.007 0.018 0.033
idle23:58:39.173100.0%259780 150.244 332.278 1888.208
status file00:00:00.355 0.0%297 0.968 1.195 16.000
TOTAL: 23:58:55.74023:58:45.943100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:21:57.642 53.1%8292 0.000 158.905 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:03.760 0.2%95388 0.000 0.039 0.667
TOTAL: 00:41:23.43600:22:01.402 53.2%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 98
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 120

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 4 14151 1996 11882 273 14%53:47:40
2outgoing.news.lysator.liu.se 2 6299 1721 4494 84 27%23:59:27
3usenet.blueworldhosting.com 716 8197 965 6846 386 11%23:59:12
4news.hispagatos.org 10 7414 494 6871 49 6%24:51:16
5usenet.network 1 5786 457 5326 3 7%23:59:58
6nntp.comgw.net 2 7483 420 6944 119 5%23:59:31
7news.corradoroberto.it 10 1405 368 1037 0 26%24:29:58
8i2pn.org 1 7389 292 7080 17 3%23:59:56
9endofthelinebbs.peers.news.panix.com 24 7813 192 7606 15 2%23:55:43
10usenet.goja.nl.eu.org 10 7446 186 7242 18 2%23:54:41
11newsfeed.bofh.team 2 8199 165 8028 6 2%23:59:46
12endoftheline-out.news.chmurka.net 1 3680 122 3558 0 3%23:59:57
13newsfeed.xs3.de 2 6088 80 6007 1 1%23:59:25
14news.samoylyk.net 1 6453 66 6387 0 1%23:59:44
15glockenspiel.complete.org 2 5785 11 5773 1 0%23:57:32
16news.bbs.nz 1 6564 7 6557 0 0%23:59:31
17news.nntp4.net 1 6326 6 6320 0 0%23:59:56
18photonic.trudheim.com 3 1826 5 1821 0 0%22:46:06
19news.netfront.net 1 4618 3 4595 20 0%22:43:33
20news.furie.org.uk 1 3914 3 3911 0 0%23:59:41
21eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:34
22localhost 27 0 0 0 0 0%00:00:00
23news.nk.ca 3 0 0 0 0 0%00:30:25
TOTAL: 23 826 126836 7559 118285 992 5%509:03:32
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net5.8 MB3.5 KB520.1 KB6.3 MB 91%2.9 KB
2outgoing.news.lysator.liu.se5.2 MB0.0 KB697.6 KB5.9 MB 88%3.3 KB
3usenet.network4.7 MB0.0 KB5.0 KB4.7 MB 99%10.4 KB
4usenet.blueworldhosting.com2.5 MB0.0 KB995.4 KB3.5 MB 72%2.7 KB
5news.corradoroberto.it1.8 MB0.0 KB0.0 KB1.8 MB100%5.0 KB
6news.hispagatos.org1.3 MB0.0 KB213.1 KB1.5 MB 86%2.9 KB
7nntp.comgw.net1.3 MB0.0 KB924.1 KB2.2 MB 58%4.2 KB
8i2pn.org951.4 KB0.0 KB20.2 KB971.5 KB 97%3.1 KB
9usenet.goja.nl.eu.org552.2 KB0.0 KB16.0 KB568.3 KB 97%2.8 KB
10endoftheline-out.news.chmurka.net440.4 KB0.0 KB0.0 KB440.4 KB100%3.6 KB
11endofthelinebbs.peers.news.panix.com439.2 KB0.0 KB54.1 KB493.3 KB 89%2.4 KB
12newsfeed.bofh.team405.2 KB0.0 KB14.7 KB419.8 KB 96%2.5 KB
13newsfeed.xs3.de270.5 KB0.0 KB4.7 KB275.2 KB 98%3.4 KB
14news.samoylyk.net260.2 KB0.0 KB0.0 KB260.2 KB100%3.9 KB
15glockenspiel.complete.org63.6 KB0.0 KB3.1 KB66.7 KB 95%5.6 KB
16news.bbs.nz23.1 KB0.0 KB0.0 KB23.1 KB100%3.3 KB
17photonic.trudheim.com17.2 KB0.0 KB0.0 KB17.2 KB100%3.4 KB
18news.netfront.net11.7 KB0.0 KB23.4 KB35.1 KB 33%1.5 KB
19news.nntp4.net9.1 KB0.0 KB0.0 KB9.1 KB100%1.5 KB
20news.furie.org.uk8.7 KB0.0 KB0.0 KB8.7 KB100%2.9 KB
21eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
23news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2326.0 MB3.5 KB3.4 MB29.4 MB 88%3.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 5 00:01:01 - 00:59:59 192 2.6% 0.05527.7 KB 2.0% 0.15
Sep 5 01:00:00 - 01:59:59 267 3.6% 0.071.4 MB 5.4% 0.40
Sep 5 02:00:00 - 02:59:59 367 4.9% 0.101.3 MB 4.9% 0.36
Sep 5 03:00:00 - 03:59:59 378 5.1% 0.101.1 MB 4.4% 0.32
Sep 5 04:00:00 - 04:59:59 365 4.9% 0.101013.7 KB 3.8% 0.28
Sep 5 05:00:00 - 05:59:59 317 4.3% 0.09941.7 KB 3.6% 0.26
Sep 5 06:00:00 - 06:59:59 362 4.9% 0.10956.2 KB 3.6% 0.27
Sep 5 07:00:00 - 07:59:59 396 5.3% 0.111.1 MB 4.1% 0.30
Sep 5 08:00:00 - 08:59:59 398 5.4% 0.111.1 MB 4.3% 0.32
Sep 5 09:00:00 - 09:59:59 468 6.3% 0.131.2 MB 4.8% 0.35
Sep 5 10:00:00 - 10:59:59 516 7.0% 0.142.0 MB 7.6% 0.56
Sep 5 11:00:00 - 11:59:59 389 5.2% 0.111.4 MB 5.5% 0.41
Sep 5 12:00:00 - 12:59:59 403 5.4% 0.111.6 MB 6.1% 0.45
Sep 5 13:00:00 - 13:59:59 415 5.6% 0.121.3 MB 4.9% 0.36
Sep 5 14:00:00 - 14:59:59 342 4.6% 0.101.4 MB 5.4% 0.40
Sep 5 15:00:00 - 15:59:59 377 5.1% 0.101.5 MB 5.8% 0.42
Sep 5 16:00:00 - 16:59:59 331 4.5% 0.091.2 MB 4.7% 0.35
Sep 5 17:00:00 - 17:59:59 263 3.5% 0.071.3 MB 4.9% 0.36
Sep 5 18:00:00 - 18:59:59 223 3.0% 0.06945.4 KB 3.6% 0.26
Sep 5 19:00:00 - 19:59:59 147 2.0% 0.04481.0 KB 1.8% 0.13
Sep 5 20:00:00 - 20:59:59 125 1.7% 0.03469.3 KB 1.8% 0.13
Sep 5 21:00:00 - 21:59:59 123 1.7% 0.03902.9 KB 3.4% 0.25
Sep 5 22:00:00 - 22:59:59 102 1.4% 0.03364.6 KB 1.4% 0.10
Sep 5 23:00:00 - 23:59:59 152 2.0% 0.04586.6 KB 2.2% 0.16
Sep 6 00:00:00 - 00:01:01 6 0.1% 0.1022.3 KB 0.1% 0.37
TOTAL: 24:00:00 7424 100.0% 0.0925.9 MB 100.0% 0.31
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
CYCNGS1NGS1536.0 GB48.0 MB 0.0% 1 0.00 0.00
CYCNGS2NGS1536.0 GB97.1 GB 6.3% 0 0.4838766.14
CYCTXT1TEXT5.0 GB3.8 GB 76.8% 0 0.03 1950.95
TOTAL: 3-3077.0 GB101.0 GB 3.3% 1 0.34-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 420 2 0 0 0 0 0 0 418
2endofthelinebbs-out.news.weretis.net 287 0 0 0 0 0 0 0 287
3nntp.comgw.net 126 0 0 0 0 0 0 0 126
4outgoing.news.lysator.liu.se 84 0 0 0 0 0 0 0 84
5news.netfront.net 54 0 0 0 0 0 0 0 54
6news.hispagatos.org 27 7 0 0 0 0 0 0 20
7usenet.goja.nl.eu.org 20 0 0 0 0 0 0 0 20
8endofthelinebbs.peers.news.panix.com 17 0 0 0 0 0 0 0 17
9i2pn.org 17 0 0 0 0 0 0 0 17
10newsfeed.bofh.team 6 0 0 0 0 0 0 0 6
11usenet.network 3 0 0 0 0 0 0 0 3
12glockenspiel.complete.org 2 0 0 0 0 0 0 0 2
13newsfeed.xs3.de 2 0 0 0 0 0 0 0 2
14news.nntp4.net 1 0 0 0 0 0 0 0 1
TOTAL: 14 1066 9 0 0 0 0 0 0 1057

Unwanted newsgroups [Top 20]:

NewsgroupCount
hispagatos.hacking.news 6
soc.culture.russia 2
hispagatos.hacking.exploits 1
TOTAL: 3 9

INND Perl filter [Top 20]:

ReasonCount
EMP (phr path) 350
EMP (phn path) 285
EMP (md5) 126
Too many newsgroups 118
EMP (phl) 62
HTML Multipart 11
User-issued cancel 11
EMP (fsl) 8
TOTAL: 8 971

NoCeM on Spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (spam)10101
i2pn2-nocem@i2pn2.org (multipost)18007070
i2pn2-nocem@i2pn2.org (rmp)11003232
robot@pasdenom.info (spam3)30033
TOTAL: 43301105106

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
usenet.blueworldhosting.com 2
TOTAL: 1 2
Including strange strings
endofthelinebbs-out.news.weretis.net 37
usenet.blueworldhosting.com 36
nntp.comgw.net 4
usenet.goja.nl.eu.org 2
endofthelinebbs.peers.news.panix.com 2
news.hispagatos.org 1
newsfeed.bofh.team 1
outgoing.news.lysator.liu.se 1
news.nntp4.net 1
newsfeed.xs3.de 1
TOTAL: 10 86
TOTAL: 2 88

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 11848 2152 5258 5 0 10 18%23:59:48
2nyheter.lysator.liu.se 5910 1316 3813 573 0 2 22%23:58:55
3photonic.trudheim.com 7919 582 5776 1045 0 0 7%23:59:48
4endofthelinebbs.peers.news.panix.com 7770 432 6981 7 0 0 5%23:59:16
5news.furie.org.uk 4219 373 3265 14 0 1 8%23:59:12
6usenet.network 7692 341 6113 42 0 0 4%23:59:22
7news.hispagatos.org 8203 305 6307 280 0 31 3%23:59:54
8news.bbs.nz 13130 301 6521 588 0 21 2%23:59:08
9usenet.goja.nl.eu.org 8255 268 6699 239 0 0 3%23:59:41
10newsfeed.bofh.team 8391 209 6965 1 0 1 2%23:59:54
11i2pn.org 7970 123 6968 26 0 16 1%23:59:54
12news.nk.ca 5758 118 3328 315 0 3646 2%23:59:54
13news.tnetconsulting.net 8206 106 7073 239 0 7 1%23:59:34
14news.nntp4.net 8523 105 7217 63 0 14 1%23:59:52
15news.weretis.net 7733 103 7166 153 0 0 1%23:59:54
16nntp.comgw.net 7981 85 6796 123 0 1 1%23:57:30
17news.quux.org 7890 76 6760 562 0 0 0%23:59:32
18newsfeed.xs3.de 8670 56 7120 166 0 1 0%23:59:54
19news.chmurka.net 3891 50 3318 3 0 0 1%23:59:39
20news.corradoroberto.it 1014 36 860 23 0 0 3%22:04:20
21news.samoylyk.net 8827 27 7263 39 0 14 0%23:59:44
22usenet.blueworldhosting.com 6513 4 6153 0 0 8 0%23:59:18
23feed.opticnetworks.net 0 0 0 0 0 7424 0%23:59:54
TOTAL: 23 166313 7168 127720 4506 0 11197 4%549:53:57
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net10.6 MB25.2 KB10.6 MB0.1 KB/s5.0 KB23:59:48
2nyheter.lysator.liu.se4.3 MB4.9 MB9.2 MB0.1 KB/s5.0 KB23:58:55
3photonic.trudheim.com3.7 MB3.6 MB7.3 MB0.1 KB/s4.6 KB23:59:48
4news.furie.org.uk2.9 MB82.3 KB3.0 MB0.0 KB/s7.9 KB23:59:12
5endofthelinebbs.peers.news.panix.com2.3 MB10.3 KB2.3 MB0.0 KB/s5.4 KB23:59:16
6newsfeed.bofh.team1.7 MB2.2 KB1.7 MB0.0 KB/s8.1 KB23:59:54
7news.hispagatos.org1.1 MB2.1 MB3.2 MB0.0 KB/s5.6 KB23:59:54
8usenet.network1.0 MB136.9 KB1.2 MB0.0 KB/s3.1 KB23:59:22
9news.bbs.nz855.0 KB3.8 MB4.6 MB0.1 KB/s5.3 KB23:59:08
10usenet.goja.nl.eu.org710.9 KB2.3 MB3.0 MB0.0 KB/s6.0 KB23:59:41
11news.quux.org634.9 KB3.6 MB4.2 MB0.1 KB/s6.8 KB23:59:32
12news.nntp4.net631.6 KB931.7 KB1.5 MB0.0 KB/s9.3 KB23:59:52
13news.weretis.net457.5 KB1.9 MB2.4 MB0.0 KB/s9.5 KB23:59:54
14news.tnetconsulting.net414.3 KB1.6 MB2.0 MB0.0 KB/s6.0 KB23:59:34
15nntp.comgw.net392.4 KB1.2 MB1.6 MB0.0 KB/s8.0 KB23:57:30
16i2pn.org362.6 KB107.2 KB469.8 KB0.0 KB/s3.2 KB23:59:54
17news.samoylyk.net322.2 KB210.6 KB532.8 KB0.0 KB/s8.1 KB23:59:44
18newsfeed.xs3.de274.0 KB1.5 MB1.7 MB0.0 KB/s8.0 KB23:59:54
19news.chmurka.net247.5 KB22.9 KB270.4 KB0.0 KB/s5.1 KB23:59:39
20news.nk.ca240.5 KB2.2 MB2.4 MB0.0 KB/s5.8 KB23:59:54
21news.corradoroberto.it116.6 KB97.8 KB214.5 KB0.0 KB/s3.6 KB22:04:20
22usenet.blueworldhosting.com15.3 KB0.0 KB15.3 KB0.0 KB/s3.8 KB23:59:18
23feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
TOTAL: 2333.1 MB30.4 MB63.5 MB0.0 KB/s5.6 KB549:53:57
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ddt.demos.su2761 00.0 KB 0 0 000:28:58
2206.168.34.115 5 00.0 KB 0 0 000:00:46
3198.235.24.179 1 00.0 KB 0 0 000:00:10
4205.210.31.142 1 00.0 KB 0 0 000:00:10
591.227.114.243 1 00.0 KB 0 0 000:00:00
691.238.181.21 1 00.0 KB 0 0 000:00:00
7azpdesc40.stretchoid.com 1 00.0 KB 0 0 000:00:09
8azpdsgb32.stretchoid.com 1 00.0 KB 0 0 000:00:00
9localhost 1 00.0 KB 0 0 000:11:02
10server-0-3.survey.inspici.com 1 00.0 KB 0 0 000:00:03
TOTAL: 102774 00.0 KB 0 0 000:41:22

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.demos.su2761 00.0 KB 0 0 000:28:58
2unresolved 9 00.0 KB 0 0 000:01:06
3*.stretchoid.com 2 00.0 KB 0 0 000:00:09
4*.survey.inspici.com 1 00.0 KB 0 0 000:00:03
5? 1 00.0 KB 0 0 000:11:02
TOTAL: 52774 00.0 KB 0 0 000:41:22

NNRP auth users [Top 20]:

UserConn
getlist 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
ddt.demos.su 9.762 36.386 0.00000:28:58
localhost 179.074 369.214 0.07900:11:02
206.168.34.115 0.019 0.063 0.00000:00:46
205.210.31.142 0.003 0.013 0.00000:00:10
198.235.24.179 0.003 0.013 0.00000:00:10
azpdesc40.stretchoid.com 0.004 0.012 0.00000:00:09
server-0-3.survey.inspici.com 0.003 0.013 0.00000:00:03
91.227.114.243 0.005 0.014 0.00000:00:00
91.238.181.21 0.000 0.016 0.00000:00:00
azpdsgb32.stretchoid.com 0.003 0.013 0.00000:00:00
TOTAL: 10 188.876 405.757 0.07900:41:22

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

SystemConn
206.168.34.115 2
91.238.181.21 1
azpdsgb32.stretchoid.com 1
TOTAL: 3 4

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

CommandCount
#003 1
#023#003#023#001#023#002#001 1
#026#003#001 1
MGLNDD_144.172.126.95_119 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
206.168.34.115 2 2
91.238.181.21 1 1
TOTAL: 2 3 3