Daily Usenet Report

Sep 19 00:01:01 -- Sep 20 00:01:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 19 00:04:54 www innd: message repeated 2 times: [ ctlinnd command s]
Sep 19 05:54:48 www innd: tradspool: could not open /news/spool/articles/us/politics/127: File exists
Sep 19 05:54:50 www innd: tradspool: could not open /news/spool/articles/us/politics/128: File exists
Sep 19 05:54:53 www innd: tradspool: could not open /news/spool/articles/us/politics/129: File exists
Sep 19 05:54:54 www innd: tradspool: could not open /news/spool/articles/us/politics/130: File exists
Sep 19 05:55:04 www innd: tradspool: could not open /news/spool/articles/us/politics/131: File exists
Sep 19 05:55:05 www innd: tradspool: could not open /news/spool/articles/us/politics/132: File exists
Sep 19 05:55:14 www innd: tradspool: could not open /news/spool/articles/us/politics/133: File exists
Sep 19 12:38:02 www innd: theuse.net funnel_bad
Sep 19 12:40:55 www innd: theuse.net funnel_bad

Log entries by program:

Program nameLines%LinesSize%Size
inn 13887 69.7%3.3 MB 78.6%
innd 3716 18.6%522.0 KB 12.1%
innfeed 2230 11.2%395.6 KB 9.1%
nnrpd 94 0.5%7.9 KB 0.2%
controlchan 4 0.0%0.3 KB 0.0%
TOTAL: 5 19931 100.0%4.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 82262 53.0%
Negative hits 59317 38.3%
Do not exist 13433 8.7%
Cache misses 64 0.0%
TOTAL: 4 155076 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.123 0.0%66 0.000 1.864 37.000
article cleanup00:00:00.234 0.0%13498 0.000 0.017 0.080
article logging00:00:00.610 0.0%13790 0.000 0.044 0.133
article parse00:00:00.497 0.0%21867 0.000 0.023 0.086
article write00:00:12.467 0.0%11733 0.386 1.063 2.669
artlog/artcncl00:00:00.000 0.0%64 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%7 0.000 0.000 0.000
data move00:00:00.200 0.0%157047 0.000 0.001 0.009
hisgrep/artcncl00:00:00.013 0.0%65 0.000 0.200 2.000
hishave/artcncl00:00:00.005 0.0%66 0.000 0.076 0.500
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:05.525 0.0%155637 0.003 0.035 0.095
history sync00:00:00.063 0.0%391 0.000 0.161 1.000
history write00:00:06.570 0.0%13489 0.012 0.487 0.800
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:48:44.671 99.8%154868 317.878 553.534 2014.419
nntp read00:00:06.901 0.0%156041 0.024 0.044 0.077
overview write00:00:07.927 0.0%11726 0.238 0.676 4.649
perl filter00:00:33.336 0.0%13496 1.602 2.470 4.333
python filter00:00:01.951 0.0%13499 0.044 0.145 0.400
site send00:00:04.520 0.0%23452 0.050 0.193 0.756
TOTAL: 23:51:10.54723:50:05.613 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.148 0.0%13114 0.000 0.011 0.244
article prepare00:00:00.001 0.0%1212 0.000 0.001 0.125
article read00:00:00.158 0.0%203 0.000 0.778 25.500
backlog stats00:00:00.355 0.0%175295 0.000 0.002 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:04.538 0.0%127819 0.018 0.036 0.056
data write00:00:16.381 0.0%117661 0.067 0.139 0.343
idle23:36:34.673100.0%175295 254.892 484.866 1436.818
status file00:00:00.217 0.0%194 0.000 1.119 5.000
TOTAL: 23:37:04.21323:36:56.471100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:47:40.149100.0%447 104.000 6398.544 10052.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.172 0.0%1645 0.000 0.105 0.105
readart00:00:00.389 0.0%383 1.016 1.016 1.016
TOTAL: 00:47:41.27800:47:40.710100.0%----

Control commands to INND:

CommandNumber
begin 2
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
shutdown 3
TOTAL: 13 173

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 7 23229 5382 17439 408 23%50:04:53
2usenet.blueworldhosting.com 6 12855 2534 9478 843 19%23:48:07
3peer.alt119.net 3 12757 2336 9929 492 18%23:53:29
4newsfeed.xs3.de 2 10346 1200 9129 17 11%23:44:25
5news.furie.org.uk 2 5671 76 5595 0 1%23:44:27
6news.samoylyk.net 2 10646 69 10564 13 0%23:44:04
7glockenspiel.complete.org 2 15128 48 15011 69 0%32:21:56
8news.bbs.nz 6 17315 10 17304 1 0%39:55:03
9localhost 30 4 4 0 0 100%00:00:05
10eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:04
11news.nk.ca 6 0 0 0 0 0%00:26:13
TOTAL: 11 67 107951 11659 94449 1843 10%241:52:46
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1usenet.blueworldhosting.com19.7 MB0.0 KB3.1 MB22.9 MB 86%6.9 KB
2endofthelinebbs-out.news.weretis.net16.4 MB1.0 KB810.8 KB17.2 MB 95%3.0 KB
3peer.alt119.net5.7 MB0.9 KB1.9 MB7.6 MB 74%2.8 KB
4newsfeed.xs3.de3.4 MB0.0 KB232.3 KB3.6 MB 93%3.0 KB
5news.furie.org.uk518.5 KB0.0 KB0.0 KB518.5 KB100%6.8 KB
6news.samoylyk.net319.3 KB0.0 KB28.3 KB347.7 KB 91%4.2 KB
7glockenspiel.complete.org315.4 KB0.0 KB317.2 KB632.5 KB 49%5.4 KB
8news.bbs.nz24.9 KB0.0 KB94.1 KB119.0 KB 20%10.8 KB
9localhost4.7 KB0.0 KB0.0 KB4.7 KB100%1.2 KB
10eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
11news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1146.4 MB1.9 KB6.5 MB52.9 MB 87%4.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 19 00:01:01 - 00:59:59 275 2.3% 0.081.1 MB 2.3% 0.32
Sep 19 01:00:00 - 01:59:59 410 3.5% 0.112.6 MB 5.5% 0.74
Sep 19 02:00:00 - 02:59:59 572 4.9% 0.161.8 MB 3.8% 0.50
Sep 19 03:00:00 - 03:59:59 584 5.0% 0.161.8 MB 3.8% 0.51
Sep 19 04:00:00 - 04:59:59 566 4.8% 0.161.6 MB 3.5% 0.47
Sep 19 05:00:00 - 05:59:59 548 4.7% 0.153.8 MB 8.0% 1.07
Sep 19 06:00:00 - 06:59:59 516 4.4% 0.141.5 MB 3.3% 0.44
Sep 19 07:00:00 - 07:59:59 544 4.6% 0.151.8 MB 3.8% 0.51
Sep 19 08:00:00 - 08:59:59 649 5.5% 0.182.2 MB 4.8% 0.63
Sep 19 09:00:00 - 09:59:59 682 5.8% 0.193.4 MB 7.4% 0.98
Sep 19 10:00:00 - 10:59:59 644 5.5% 0.182.9 MB 6.2% 0.82
Sep 19 11:00:00 - 11:59:59 687 5.8% 0.192.7 MB 5.8% 0.76
Sep 19 12:00:00 - 12:59:59 740 6.3% 0.212.3 MB 4.9% 0.65
Sep 19 13:00:00 - 13:59:59 685 5.8% 0.191.8 MB 3.9% 0.52
Sep 19 14:00:00 - 14:59:59 593 5.0% 0.162.0 MB 4.2% 0.56
Sep 19 15:00:00 - 15:59:59 509 4.3% 0.142.2 MB 4.6% 0.62
Sep 19 16:00:00 - 16:59:59 528 4.5% 0.152.4 MB 5.1% 0.68
Sep 19 17:00:00 - 17:59:59 530 4.5% 0.151.8 MB 3.9% 0.51
Sep 19 18:00:00 - 18:59:59 330 2.8% 0.091.5 MB 3.2% 0.42
Sep 19 19:00:00 - 19:59:59 238 2.0% 0.071.0 MB 2.2% 0.29
Sep 19 20:00:00 - 20:59:59 231 2.0% 0.06745.9 KB 1.6% 0.21
Sep 19 21:00:00 - 21:59:59 245 2.1% 0.072.2 MB 4.7% 0.62
Sep 19 22:00:00 - 22:59:59 180 1.5% 0.05671.0 KB 1.4% 0.19
Sep 19 23:00:00 - 23:59:59 259 2.2% 0.07948.6 KB 2.0% 0.26
Sep 20 00:00:00 - 00:01:02 4 0.0% 0.0640.8 KB 0.1% 0.66
TOTAL: 24:00:01 11749 100.0% 0.1446.7 MB 100.0% 0.55
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 846 203 0 0 0 0 0 0 643
2endofthelinebbs-out.news.weretis.net 628 1 0 1 0 0 0 0 626
3peer.alt119.net 499 13 0 1 0 0 0 0 485
4newsfeed.xs3.de 64 0 0 0 0 0 0 0 64
5glockenspiel.complete.org 28 0 0 0 0 0 0 0 28
6news.samoylyk.net 8 0 0 0 0 0 0 0 8
7news.bbs.nz 1 0 0 0 0 0 0 0 1
TOTAL: 7 2074 217 0 2 0 0 0 0 1855

Unwanted newsgroups [Top 20]:

NewsgroupCount
talk.ukrainian.tg 61
talk.english.tg 54
panix.chat.politics 16
news.oriens-occasus.ru 11
at.jobs 10
talk.generic.ua.ws 10
alt.culture.underwear 9
trollnet.cinema 6
att.jobs 6
alt.music.pink-floyd.publius 5
1.test 5
rocksolid.feeds.news 4
talk.english.tw 4
aus.jobs 2
al.christnet.bible 2
grisbi.user.french 2
talk.from.war.diary 1
alt.rock-n-roll.psychedelic 1
David<kc4zvw@earthlink.net> 1
panix.people 1
TOTAL: 26 217

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 764
EMP (md5) 430
EMP (phl) 195
Too many newsgroups 124
EMP (phn nph) 22
Scoring filter 7
HTML Multipart 5
Too many newsgroups (low_xpost) 1
Bot - Newsgroup autoposter 1
MID-Bot 1
TOTAL: 10 1550

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 218
newsfeed.xs3.de 47
glockenspiel.complete.org 14
peer.alt119.net 13
usenet.blueworldhosting.com 6
TOTAL: 5 298
TOTAL: 1 298

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1peer.alt119.net 11460 65 9343 0 0 25 0%23:50:07
2news.nk.ca 12367 57 11502 17 0 248 0%23:50:07
3news.furie.org.uk 6821 27 5952 15 0 0 0%23:50:07
4news.bbs.nz 21862 11 11663 52 0 1096 0%23:50:07
5usenet.blueworldhosting.com 8869 8 8537 0 0 5 0%23:50:07
6news.tnetconsulting.net 12337 5 11650 0 0 19 0%23:50:08
7newsfeed.xs3.de 10545 4 10445 2 0 27 0%23:50:07
8news.quux.org 11735 4 11481 38 0 0 0%23:50:07
9news.samoylyk.net 11866 4 11594 0 0 11 0%23:50:07
10news.weretis.net 11774 3 11734 1 0 0 0%23:50:07
11theuse.net 0 0 0 0 0 4602 0%11:14:00
12nyheter.lysator.liu.se 0 0 0 0 0 4605 0%11:11:50
TOTAL: 12 119636 188 103901 125 0 10638 0%260:47:01
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1peer.alt119.net941.7 KB0.0 KB941.7 KB0.0 KB/s14.5 KB23:50:07
2news.nk.ca224.0 KB56.3 KB280.3 KB0.0 KB/s3.8 KB23:50:07
3news.furie.org.uk168.4 KB83.9 KB252.3 KB0.0 KB/s6.0 KB23:50:07
4news.bbs.nz34.2 KB212.8 KB246.9 KB0.0 KB/s3.9 KB23:50:07
5news.tnetconsulting.net32.9 KB0.0 KB32.9 KB0.0 KB/s6.6 KB23:50:08
6usenet.blueworldhosting.com16.3 KB0.0 KB16.3 KB0.0 KB/s2.0 KB23:50:07
7newsfeed.xs3.de4.7 KB11.0 KB15.7 KB0.0 KB/s2.6 KB23:50:07
8news.quux.org4.7 KB127.4 KB132.1 KB0.0 KB/s3.1 KB23:50:07
9news.samoylyk.net4.7 KB0.0 KB4.7 KB0.0 KB/s1.2 KB23:50:07
10news.weretis.net4.1 KB1.8 KB5.9 KB0.0 KB/s1.5 KB23:50:07
11theuse.net0.0 KB0.0 KB0.0 KB0.0 KB/s011:14:00
12nyheter.lysator.liu.se0.0 KB0.0 KB0.0 KB0.0 KB/s011:11:50
TOTAL: 121.4 MB493.1 KB1.9 MB0.0 KB/s6.2 KB260:47:01
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.186.43.57 2 3711.0 MB 5 2 000:47:30
TOTAL: 1 2 3711.0 MB 5 2 000:47:41

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 4 3711.0 MB 5 2 000:47:41
TOTAL: 1 4 3711.0 MB 5 2 000:47:41

NNRP auth users [Top 20]:

UserConn
nelgin 2
TOTAL: 1 2

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47.186.43.57 0.252 0.374 0.00300:47:30
104.152.52.107 0.074 0.031 0.00000:00:10
104.152.52.201 0.069 0.052 0.00000:00:00
TOTAL: 3 0.395 0.457 0.00300:47:41

Curious NNRP server explorers [Top 20]:

SystemConn
104.152.52.107 1
104.152.52.201 1
TOTAL: 2 2

Newsgroup request counts (by category):

CategoryCountPct
1news 369 98.7%
2soc 2 0.5%
3dfw 1 0.3%
4alt 1 0.3%
5comp 1 0.3%
TOTAL: 5 374100.0%

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

NewsgroupCount
1news.software.nntp 369
2soc.genealogy.computing 2
3alt.bbs.doors 1
4dfw.games 1
5comp.sys.cbm 1
TOTAL: 5 374