Daily Usenet report for news.ripco.com

Oct 2 00:01:03 -- Oct 2 23:58:06

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Oct 2 06:40:46 remote5bge0 overchan[20615]: [ID 702911 news.error] input line too long, skipping
Oct 2 11:43:38 remote5bge0 overchan[20615]: [ID 702911 news.error] input line too long, skipping
Oct 2 13:16:15 remote5bge0 overchan[20615]: [ID 702911 news.error] input line too long, skipping
Oct 2 13:57:08 remote5bge0 overchan[20615]: [ID 702911 news.error] input line too long, skipping
Oct 2 19:49:38 remote5bge0 overchan[20615]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 32906 40.8%4.1 MB 34.0%
inn 24493 30.4%4.2 MB 34.8%
innd 19575 24.3%3.0 MB 25.0%
innfeed 3684 4.6%751.4 KB 6.1%
overchan 5 0.0%0.6 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 80665 100.0%12.0 MB100.0%

History cache:

ReasonCount%Count
Cache misses 205568 51.7%
Do not exist 191814 48.3%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 397382 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%130 0.000 0.000 0.000
article cleanup00:00:00.542 0.0%48572 0.000 0.011 0.053
article logging00:00:00.858 0.0%49156 0.000 0.017 0.292
article parse00:00:01.734 0.0%111636 0.000 0.016 0.035
article write00:00:40.266 0.0%34576 0.341 1.165 13.941
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.256 0.0%408236 0.000 0.001 0.004
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:03.372 0.0%400650 0.003 0.008 0.016
history sync00:00:00.310 0.0%792 0.000 0.391 3.333
history write00:00:04.854 0.0%48566 0.000 0.100 2.168
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:59:30.512 99.9%444342 168.641 388.823 1032.609
nntp read00:00:02.554 0.0%444274 0.001 0.006 0.012
overview write00:00:00.226 0.0%34576 0.000 0.007 0.043
perl filter00:01:02.706 0.0%48552 1.020 1.292 3.488
python filter00:00:01.236 0.0%48556 0.000 0.025 0.084
site send00:00:02.628 0.0%103640 0.005 0.025 0.056
TOTAL: 48:02:07.40048:01:32.054100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.116 0.0%45294 0.000 0.003 0.021
article prepare00:00:00.004 0.0%16360 0.000 0.000 0.016
article read00:00:00.520 0.0%3548 0.000 0.147 12.333
backlog stats00:00:00.190 0.0%388990 0.000 0.000 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.466 0.0%259288 0.000 0.006 0.013
data write00:00:03.498 0.0%225628 0.004 0.016 0.031
idle47:53:06.566100.0%388990 157.939 443.165 1135.368
status file00:00:05.600 0.0%366 0.000 15.301 90.000
TOTAL: 47:53:28.72647:53:17.960100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:17.492 0.0%1157884 0.000 0.015 0.200
idle119:14:05.594100.0%1192722 7.000 359.887 60299.062
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:35.240 0.0%3460140 0.000 0.010 0.100
readart00:00:19.524 0.0%2830 0.000 6.899 36.500
TOTAL: 119:17:08.742119:15:17.850100.0%----

Control commands to INND:

CommandNumber
L 2
flush 2
go 4
logmode 2
mode 6
name 5
pause 2
paused 2
reserve 4
TOTAL: 9 29

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1148 54608 15216 33716 5676 27%94:37:48
2news.snarked.org 44 45084 10180 30606 4298 22%53:30:12
3newsfeed.neva.ru 558 61366 7510 51374 2482 12%285:37:08
4newsfeed.fu-berlin.de 2 23618 1150 22434 34 4%64:40:34
5news.lightlink.com 2 9620 354 9078 188 3%43:48:28
6ripco-out.feeder.erje.net 574 37754 104 36496 1154 0%47:17:34
7news-in.rahul.net 574 20162 78 20076 8 0%47:28:34
8news.cmpublishers.com 40 9362 48 9298 16 0%37:22:26
9localhost 148 4 4 0 0 100%00:00:00
TOTAL: 9 3090 261578 34644 213078 13856 13%674:22:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com37.8 MB0.0 KB11.1 MB49.0 MB 77%2.4 KB
2news.snarked.org29.1 MB0.0 KB14.7 MB43.8 MB 66%3.1 KB
3newsfeed.neva.ru22.2 MB53.0 KB7.7 MB30.0 MB 74%3.1 KB
4news.lightlink.com13.5 MB0.0 KB8.8 MB22.3 MB 60%42.2 KB
5newsfeed.fu-berlin.de6.0 MB0.0 KB116.1 KB6.1 MB 98%5.2 KB
6news-in.rahul.net3.2 MB0.0 KB413.1 KB3.6 MB 88%43.0 KB
7ripco-out.feeder.erje.net275.6 KB0.0 KB11.0 MB11.2 MB 2%9.1 KB
8news.cmpublishers.com128.3 KB0.0 KB18.6 KB147.0 KB 87%2.3 KB
9localhost11.0 KB0.0 KB0.0 KB11.0 KB100%2.7 KB
TOTAL: 9112.2 MB53.0 KB53.9 MB166.1 MB 67%3.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 2 00:01:03 - 00:59:59 424 2.5% 0.121.4 MB 2.5% 0.41
Oct 2 01:00:00 - 01:59:59 486 2.8% 0.141.5 MB 2.7% 0.42
Oct 2 02:00:00 - 02:59:59 621 3.6% 0.171.6 MB 2.9% 0.46
Oct 2 03:00:00 - 03:59:59 779 4.5% 0.222.7 MB 4.8% 0.76
Oct 2 04:00:00 - 04:59:59 883 5.1% 0.252.2 MB 4.0% 0.63
Oct 2 05:00:00 - 05:59:59 729 4.2% 0.202.1 MB 3.8% 0.60
Oct 2 06:00:00 - 06:59:59 754 4.4% 0.213.2 MB 5.7% 0.90
Oct 2 07:00:00 - 07:59:59 772 4.5% 0.211.8 MB 3.2% 0.51
Oct 2 08:00:00 - 08:59:59 909 5.3% 0.252.2 MB 4.1% 0.64
Oct 2 09:00:00 - 09:59:59 911 5.3% 0.252.3 MB 4.2% 0.66
Oct 2 10:00:00 - 10:59:59 1194 6.9% 0.335.3 MB 9.5% 1.50
Oct 2 11:00:00 - 11:59:59 1084 6.3% 0.303.8 MB 6.9% 1.08
Oct 2 12:00:00 - 12:59:59 887 5.1% 0.252.5 MB 4.5% 0.71
Oct 2 13:00:00 - 13:59:59 978 5.7% 0.273.3 MB 5.9% 0.93
Oct 2 14:00:00 - 14:59:59 997 5.8% 0.282.7 MB 4.9% 0.77
Oct 2 15:00:00 - 15:59:59 929 5.4% 0.263.1 MB 5.5% 0.87
Oct 2 16:00:00 - 16:59:59 827 4.8% 0.233.1 MB 5.6% 0.89
Oct 2 17:00:00 - 17:59:59 682 4.0% 0.192.1 MB 3.8% 0.59
Oct 2 18:00:00 - 18:59:59 409 2.4% 0.111.7 MB 3.0% 0.47
Oct 2 19:00:00 - 19:59:59 426 2.5% 0.121.2 MB 2.2% 0.34
Oct 2 20:00:00 - 20:59:59 434 2.5% 0.121.4 MB 2.5% 0.39
Oct 2 21:00:00 - 21:59:59 405 2.4% 0.111.2 MB 2.1% 0.33
Oct 2 22:00:00 - 22:59:59 334 1.9% 0.091.8 MB 3.2% 0.50
Oct 2 23:00:00 - 23:58:06 376 2.2% 0.111.3 MB 2.4% 0.38
TOTAL: 23:57:03 17230 100.0% 0.2055.3 MB 100.0% 0.66
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2855 2144 0 0 4 0 0 0 707
2news.snarked.org 2188 112 0 0 4 0 0 0 2072
3newsfeed.neva.ru 1311 826 0 3 4 0 0 0 478
4ripco-out.feeder.erje.net 577 574 0 0 0 0 0 0 3
5newsfeed.fu-berlin.de 220 0 0 0 0 0 0 0 220
6news.lightlink.com 100 2 0 0 0 0 0 0 98
7news.cmpublishers.com 8 1 0 0 0 0 0 0 7
8news-in.rahul.net 4 0 0 0 0 0 0 0 4
TOTAL: 8 7263 3659 0 3 12 0 0 0 3589

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2483
alt.binaries.ftd 356
alt.astrology.pro 69
fido7.su.pol.news 53
perl.cpan.uploads 43
alt.bestjobsusa.computer.jobs 41
gmane.linux.alsa.devel 32
alt.my.job.sucks 23
alt.jobs.jobsearch 21
linux.debian.changes 21
alt.binaries.3d.poser 20
it.tlc.cellulari.android 18
at.jobs 18
att.jobs 18
mi.jobs 18
free.it.scienza.fisica 18
perl.perl6.users 18
it.comp.os.win.windows10 16
fr.rec.loisirs-creatifs 16
aus.jobs 14
TOTAL: 143 3659

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
pl.praca.oferowana 6
fr.sci.geosciences 3
christnet.theology 1
alt.penthouse.sex.masterbation 1
bit.listserv.catholic 1
TOTAL: 5 12

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4799
Too many newsgroups 1326
Binary in non-binary group 204
EMP (phl) 116
Attached HTML file 68
Scoring filter (8) 28
Scoring filter (9) 18
HTML post 12
Scoring filter (16) 10
Scoring filter (10) 8
Scoring filter (18) 4
Scoring filter (13) 4
Scoring filter (11) 4
TOTAL: 13 6601

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feed.uzoreto.com 6
ripco-out.feeder.erje.net 1
TOTAL: 2 7
Bad Message-ID's offered
ripco-out.feeder.erje.net 20
TOTAL: 1 20
Including strange strings
newsfeed.fu-berlin.de 197
newsfeed.neva.ru 75
ripco-out.feed.uzoreto.com 17
news.snarked.org 3
TOTAL: 4 292
TOTAL: 3 319

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 10112 1998 5326 12 0 0 19%47:36:14
2rahul.net 27438 820 14916 30 0 2 2%47:33:28
3news.snarked.org 40428 80 23800 16 0 280 0%47:40:02
4news.cmpublishers.com 39798 66 33320 656 0 10548 0%47:47:12
5news.uzoreto.com 19940 56 18642 48 0 0 0%47:39:54
6fu-berlin.de 31084 42 31042 0 0 0 0%47:39:34
7feeder.erje.net 34122 36 32552 44 0 32 0%47:36:42
8newsfeed.neva.ru 27402 16 26608 152 0 38 0%47:38:28
9remote6hme0.ripco.com 0 0 0 0 0 6 0%47:40:02
TOTAL: 9 230324 3114 186206 958 0 10906 1%428:51:36
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com5.6 MB33.1 KB5.6 MB0.0 KB/s2.9 KB47:36:14
2rahul.net3.0 MB67.5 KB3.0 MB0.0 KB/s3.7 KB47:33:28
3fu-berlin.de338.2 KB0.0 KB338.2 KB0.0 KB/s8.1 KB47:39:34
4news.snarked.org239.9 KB32.0 KB271.9 KB0.0 KB/s2.8 KB47:40:02
5news.uzoreto.com239.8 KB164.3 KB404.1 KB0.0 KB/s3.9 KB47:39:54
6news.cmpublishers.com196.9 KB1.6 MB1.8 MB0.0 KB/s2.6 KB47:47:12
7feeder.erje.net157.1 KB134.5 KB291.5 KB0.0 KB/s3.6 KB47:36:42
8newsfeed.neva.ru47.8 KB421.1 KB468.9 KB0.0 KB/s2.8 KB47:38:28
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
TOTAL: 99.8 MB2.5 MB12.2 MB0.0 KB/s3.1 KB428:51:36
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 11923.2 MB 448 2 000:07:02
2shell3 14 4241.1 MB 30 2 001:32:50
375-151-74-61-independence.hfc.comcastbusiness.net 8 45.4 KB 4 0 000:13:58
TOTAL: 3 308 16204.3 MB 482 4 0119:17:01

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 11923.2 MB 448 2 000:07:02
2? 14 4241.1 MB 30 2 001:32:50
3*.hfc.comcastbusiness.net 8 45.4 KB 4 0 000:13:58
4*.direct-adsl.nl 6 00.0 KB 0 0 000:41:33
5*.fbx.proxad.net 16 00.0 KB 0 0 000:32:35
6*.cm-12-2c.dynamic.ziggo.nl 32 00.0 KB 0 0 0116:02:46
7*.7-3.cable.virginm.net 4 00.0 KB 0 0 000:05:55
8unresolved 2 00.0 KB 0 0 000:00:19
TOTAL: 8 368 16204.3 MB 482 4 0119:17:01

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
5469a4b0.cm-12-2c.dynamic.ziggo.nl 28.998 38.446 0.000116:02:46
shell3 7.380 2.976 0.00001:32:50
ip5657965a.direct-adsl.nl 0.954 0.412 0.00000:41:33
vvs78-1-88-173-43-113.fbx.proxad.net 1.602 0.346 0.00000:32:35
75-151-74-61-independence.hfc.comcastbusiness.net 1.650 1.274 0.00000:13:58
ftupet.com 46.066 7.476 0.00000:07:02
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 0.430 0.144 0.00000:05:55
107.170.196.89 0.196 0.042 0.00000:00:19
TOTAL: 8 87.276 51.116 0.000119:17:01

Curious NNRP server explorers [Top 20]:

SystemConn
5469a4b0.cm-12-2c.dynamic.ziggo.nl 32
vvs78-1-88-173-43-113.fbx.proxad.net 16
ip5657965a.direct-adsl.nl 6
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 4
107.170.196.89 2
TOTAL: 5 60

NNRP unrecognized commands (by host):

SystemConn
shell3 6
TOTAL: 1 6

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 6
TOTAL: 1 6

NNRP client timeouts [Top 20]:

SystemConnPeer
ip5657965a.direct-adsl.nl 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1444 89.1%
2comp 112 6.9%
3alt 26 1.6%
4chi 16 1.0%
5houston 8 0.5%
6news 8 0.5%
7sci 4 0.2%
8misc 2 0.1%
TOTAL: 8 1620100.0%

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

NewsgroupCount
1rec.arts.tv 886
2rec.food.cooking 462
3rec.bicycles.tech 80
4comp.dcom.telecom 48
5comp.sys.mac.system 40
6comp.sys.mac.apps 20
7alt.stupidity 16
8chi.general 12
9rec.radio.broadcasting 8
10houston.wanted 8
11rec.radio.swap 6
12sci.electronics.repair 4
13news.admin.net-abuse.email 4
14alt.sports.football.pro.kc-chiefs 4
15chi.weather 4
16comp.risks 2
17rec.bicycles.misc 2
18news.software.readers 2
19alt.education.distance 2
20alt.games.warcraft 2
21alt.sources 2
22comp.protocols.dns.bind 2
23news.lists.misc 2
24misc.consumers.frugal-living 2
TOTAL: 24 1620