Daily Usenet report for news.ripco.com

Oct 16 00:01:03 -- Oct 16 23:58:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Oct 16 04:20:41 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 05:41:51 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 08:52:10 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 09:01:23 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 09:20:47 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 09:23:47 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 12:12:44 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 12:17:18 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 12:22:52 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 12:24:49 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 14:44:24 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 14:47:29 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 17:01:16 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 17:14:49 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping
Oct 16 20:00:38 remote5bge0 overchan[23117]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 33808 44.4%4.2 MB 36.9%
inn 22106 29.0%3.9 MB 34.1%
innd 16541 21.7%2.6 MB 22.4%
innfeed 3629 4.8%765.2 KB 6.5%
overchan 15 0.0%2.3 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 76101 100.0%11.4 MB100.0%

History cache:

ReasonCount%Count
Cache misses 194024 52.4%
Do not exist 176330 47.6%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 370354 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%146 0.000 0.000 0.000
article cleanup00:00:00.470 0.0%43842 0.000 0.011 0.037
article logging00:00:00.706 0.0%44328 0.000 0.016 0.423
article parse00:00:01.662 0.0%101922 0.000 0.016 0.038
article write00:00:39.592 0.0%34298 0.375 1.154 17.018
artlog/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
data move00:00:00.252 0.0%376492 0.000 0.001 0.004
hishave/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.336 0.0%371394 0.001 0.012 0.647
history sync00:00:00.410 0.0%800 0.000 0.512 3.667
history write00:00:06.736 0.0%43822 0.022 0.154 8.339
hiswrite/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
idle47:59:45.072 99.9%413408 190.951 417.953 1005.235
nntp read00:00:02.244 0.0%411010 0.001 0.005 0.013
overview write00:00:00.208 0.0%34298 0.000 0.006 0.029
perl filter00:00:57.570 0.0%43820 1.024 1.314 2.932
python filter00:00:01.128 0.0%43830 0.000 0.026 0.072
site send00:00:01.992 0.0%102854 0.005 0.019 0.063
TOTAL: 48:02:16.49048:01:42.378100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.122 0.0%34258 0.000 0.004 0.025
article prepare00:00:00.010 0.0%18432 0.000 0.001 0.028
article read00:00:00.454 0.0%3788 0.000 0.120 14.400
backlog stats00:00:00.184 0.0%400640 0.000 0.000 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.216 0.0%268564 0.002 0.008 0.020
data write00:00:03.670 0.0%234838 0.005 0.016 0.032
idle47:33:44.264100.0%400640 251.378 427.377 1150.246
status file00:00:05.164 0.0%350 0.000 14.754 32.000
TOTAL: 47:34:08.37647:33:56.084100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.252 0.0%6600 0.016 0.038 0.114
idle163:05:51.270100.0%41526 0.000 14139.365283946.816
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:27.880 0.0%1596544 0.000 0.017 0.200
readart00:00:19.510 0.0%3464 0.000 5.632 38.500
TOTAL: 163:08:40.366163:06:38.912100.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 50312 15476 30072 4764 30%95:00:18
2news.snarked.org 48 40414 10214 28594 1606 25%55:24:46
3newsfeed.neva.ru 558 58726 7096 49750 1880 12%280:50:50
4newsfeed.fu-berlin.de 2 17598 766 16790 42 4%51:09:46
5news.lightlink.com 2 7414 280 6950 184 3%45:01:30
6news-in.rahul.net 574 17478 214 17258 6 1%47:25:06
7news.cmpublishers.com 50 9636 98 9530 8 1%48:00:08
8ripco-out.feeder.erje.net 574 37062 50 35998 1014 0%47:17:56
9localhost 154 10 10 0 0 100%00:00:02
TOTAL: 9 3110 238650 34204 194942 9504 14%670:10:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com38.2 MB0.0 KB9.2 MB47.5 MB 80%2.4 KB
2news.snarked.org30.6 MB8.0 KB4.7 MB35.2 MB 86%3.1 KB
3newsfeed.neva.ru19.5 MB47.5 KB7.1 MB26.7 MB 73%3.0 KB
4news-in.rahul.net10.4 MB0.0 KB437.2 KB10.9 MB 96%50.6 KB
5news.lightlink.com5.4 MB0.0 KB7.3 MB12.8 MB 42%28.1 KB
6newsfeed.fu-berlin.de2.6 MB0.0 KB111.6 KB2.7 MB 95%3.4 KB
7news.cmpublishers.com237.2 KB8.0 KB14.9 KB260.1 KB 91%2.5 KB
8ripco-out.feeder.erje.net144.6 KB0.0 KB8.7 MB8.9 MB 1%8.5 KB
9localhost19.4 KB0.0 KB0.0 KB19.4 KB100%1.9 KB
TOTAL: 9107.2 MB63.5 KB37.6 MB144.9 MB 73%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 16 00:01:03 - 00:59:59 329 1.9% 0.091.2 MB 2.3% 0.36
Oct 16 01:00:00 - 01:59:59 442 2.6% 0.121.5 MB 2.7% 0.42
Oct 16 02:00:00 - 02:59:59 607 3.5% 0.171.5 MB 2.8% 0.43
Oct 16 03:00:00 - 03:59:59 753 4.4% 0.211.8 MB 3.3% 0.51
Oct 16 04:00:00 - 04:59:59 785 4.6% 0.222.3 MB 4.4% 0.67
Oct 16 05:00:00 - 05:59:59 888 5.2% 0.252.9 MB 5.4% 0.82
Oct 16 06:00:00 - 06:59:59 909 5.3% 0.252.6 MB 4.9% 0.75
Oct 16 07:00:00 - 07:59:59 875 5.1% 0.242.2 MB 4.1% 0.62
Oct 16 08:00:00 - 08:59:59 1009 5.9% 0.283.2 MB 5.9% 0.90
Oct 16 09:00:00 - 09:59:59 965 5.6% 0.272.6 MB 4.8% 0.74
Oct 16 10:00:00 - 10:59:59 962 5.6% 0.273.2 MB 5.9% 0.90
Oct 16 11:00:00 - 11:59:59 1015 5.9% 0.282.6 MB 4.9% 0.75
Oct 16 12:00:00 - 12:59:59 950 5.6% 0.262.8 MB 5.2% 0.79
Oct 16 13:00:00 - 13:59:59 896 5.2% 0.253.3 MB 6.2% 0.94
Oct 16 14:00:00 - 14:59:59 908 5.3% 0.252.8 MB 5.2% 0.79
Oct 16 15:00:00 - 15:59:59 904 5.3% 0.253.2 MB 6.0% 0.91
Oct 16 16:00:00 - 16:59:59 793 4.6% 0.223.5 MB 6.6% 1.00
Oct 16 17:00:00 - 17:59:59 651 3.8% 0.182.0 MB 3.8% 0.57
Oct 16 18:00:00 - 18:59:59 501 2.9% 0.141.4 MB 2.7% 0.41
Oct 16 19:00:00 - 19:59:59 419 2.4% 0.121.2 MB 2.3% 0.35
Oct 16 20:00:00 - 20:59:59 427 2.5% 0.121.7 MB 3.1% 0.48
Oct 16 21:00:00 - 21:59:59 462 2.7% 0.131.4 MB 2.6% 0.40
Oct 16 22:00:00 - 22:59:59 321 1.9% 0.091.0 MB 2.0% 0.30
Oct 16 23:00:00 - 23:58:02 346 2.0% 0.101.4 MB 2.7% 0.42
TOTAL: 23:56:59 17117 100.0% 0.2053.5 MB 100.0% 0.64
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2387 1745 0 0 0 0 0 0 642
2newsfeed.neva.ru 1018 640 0 9 0 0 0 0 369
3news.snarked.org 805 67 0 1 4 0 0 0 733
4ripco-out.feeder.erje.net 507 503 0 0 0 0 0 0 4
5newsfeed.fu-berlin.de 172 0 0 0 0 0 0 0 172
6news.lightlink.com 94 0 0 0 0 0 0 0 94
7news.cmpublishers.com 3 1 0 1 0 0 0 0 1
8news-in.rahul.net 3 0 0 0 0 0 0 0 3
TOTAL: 8 4989 2956 0 11 4 0 0 0 2018

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1852
alt.binaries.ftd 246
alt.astrology.pro 100
perl.cpan.uploads 56
fido7.su.pol.news 52
it.comp.os.win.windows10 50
alt.binaries.e-book 26
alt.binaries.3d.poser 25
it.tlc.cellulari.android 24
free.uk.astrology 23
free.willey 22
alt.binaries.e-books 22
linux.debian.maint.python 18
uk.net.news.moderation 18
uk.radio.amateur.moderated 16
gmane.linux.alsa.devel 15
alt.binaries.pictures.earlmiller 14
alt.bestjobsusa.computer.jobs 14
alt.binaries.nl 13
mozilla.support.calendar 13
TOTAL: 164 2956

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

GroupsCount
alt.penthouse.sex.masterbation 2
christnet.theology 1
alt.penthouse.sex.sissy.slut 1
TOTAL: 3 4

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1994
Too many newsgroups 1040
Binary in non-binary group 192
EMP (phl) 164
Scoring filter (8) 64
Scoring filter (16) 22
HTML post 18
Scoring filter (9) 14
Scoring filter (11) 12
Attached HTML file 12
Scoring filter (10) 6
Scoring filter (18) 4
Angle-bracket bot 2
Scoring filter (17) 2
TOTAL: 14 3546

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Huge articles
newsfeed.fu-berlin.de 2
TOTAL: 1 2
Including strange strings
newsfeed.fu-berlin.de 159
newsfeed.neva.ru 76
ripco-out.feed.uzoreto.com 5
news.snarked.org 3
TOTAL: 4 243
No colon-space in header
ripco-out.feed.uzoreto.com 1
TOTAL: 1 1
TOTAL: 4 264

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 10034 2214 5132 18 0 6 22%47:40:02
2rahul.net 26004 996 14590 20 0 8 3%47:51:08
3news.uzoreto.com 19366 106 17970 226 0 0 0%47:44:38
4news.snarked.org 39920 96 23648 12 0 112 0%47:51:26
5feeder.erje.net 33882 56 32320 154 0 8 0%47:36:46
6news.cmpublishers.com 43136 50 33256 458 0 14 0%47:40:02
7fu-berlin.de 31268 40 31228 0 0 0 0%47:48:00
8newsfeed.neva.ru 28134 20 26832 150 0 0 0%47:41:54
9remote6hme0.ripco.com 0 0 0 0 0 8 0%47:40:02
TOTAL: 9 231744 3578 184976 1038 0 156 1%429:33:58
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com6.8 MB164.5 KB7.0 MB0.0 KB/s3.2 KB47:40:02
2rahul.net3.6 MB44.0 KB3.6 MB0.0 KB/s3.6 KB47:51:08
3news.uzoreto.com357.2 KB806.6 KB1.1 MB0.0 KB/s3.5 KB47:44:38
4feeder.erje.net252.4 KB469.6 KB722.0 KB0.0 KB/s3.4 KB47:36:46
5news.cmpublishers.com230.8 KB1.2 MB1.4 MB0.0 KB/s2.8 KB47:40:02
6fu-berlin.de222.5 KB0.0 KB222.5 KB0.0 KB/s5.6 KB47:48:00
7news.snarked.org218.3 KB26.9 KB245.1 KB0.0 KB/s2.3 KB47:51:26
8newsfeed.neva.ru56.9 KB382.3 KB439.1 KB0.0 KB/s2.6 KB47:41:54
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
TOTAL: 911.7 MB3.0 MB14.7 MB0.0 KB/s3.3 KB429:33:58
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 14764.0 MB 448 6 000:06:48
2shell3 26 5021.3 MB 58 4 002:47:43
375-151-74-61-independence.hfc.comcastbusiness.net 8 45.5 KB 4 0 000:13:51
TOTAL: 3 320 19825.3 MB 510 10 0163:08:30

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 14764.0 MB 448 6 000:06:48
2? 26 5021.3 MB 58 4 002:47:43
3*.hfc.comcastbusiness.net 8 45.5 KB 4 0 000:13:51
4*.dyn.optonline.net 10 00.0 KB 0 0 000:44:51
5*.7-3.cable.virginm.net 2 00.0 KB 0 0 000:03:24
6*.hsi6.kabel-badenwuerttemberg.de 136 00.0 KB 0 0 000:01:06
7unresolved 4 00.0 KB 0 0 0133:19:42
8*.cpe.metrocast.net 8 00.0 KB 0 0 000:15:30
9*.dyn.iinet.net.au 32 00.0 KB 0 0 025:35:32
TOTAL: 9 512 19825.3 MB 510 10 0163:08:30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.11.163.217 0.468 0.242 0.000133:19:42
124-170-186-203.dyn.iinet.net.au 3.262 0.784 0.00025:35:32
shell3 10.426 4.080 0.00002:47:43
ool-4a5aa009.dyn.optonline.net 1.016 0.218 0.00000:44:51
d-72-9-26-236.cpe.metrocast.net 0.846 0.270 0.00000:15:30
75-151-74-61-independence.hfc.comcastbusiness.net 1.632 1.284 0.00000:13:51
ftupet.com 45.850 7.342 0.00000:06:48
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 0.226 0.086 0.00000:03:24
hsi-kbw-109-192-006-015.hsi6.kabel-badenwuerttemberg.de 13.652 2.988 0.00000:01:06
TOTAL: 9 77.378 17.294 0.000163:08:30

Curious NNRP server explorers [Top 20]:

SystemConn
hsi-kbw-109-192-006-015.hsi6.kabel-badenwuerttemberg.de 136
124-170-186-203.dyn.iinet.net.au 32
ool-4a5aa009.dyn.optonline.net 10
d-72-9-26-236.cpe.metrocast.net 8
66.11.163.217 4
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 2
TOTAL: 6 192

NNRP unrecognized commands (by host):

SystemConn
shell3 8
ool-4a5aa009.dyn.optonline.net 6
TOTAL: 2 14

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 8
XFEATURE COMPRESS GZIP TERMINATOR 6
TOTAL: 2 14

NNRP client timeouts [Top 20]:

SystemConnPeer
66.11.163.217 4 0
shell3 4 0
TOTAL: 2 8 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1752 88.4%
2comp 136 6.9%
3alt 34 1.7%
4misc 24 1.2%
5news 16 0.8%
6sci 12 0.6%
7chi 6 0.3%
8tw 2 0.1%
TOTAL: 8 1982100.0%

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

NewsgroupCount
1rec.arts.tv 996
2rec.food.cooking 690
3comp.sys.mac.system 40
4comp.mobile.android 38
5alt.stupidity 26
6rec.bicycles.tech 24
7misc.phone.mobile.iphone 22
8comp.mobile.ipad 16
9news.software.readers 14
10comp.mail.pine 12
11rec.radio.broadcasting 12
12sci.electronics.repair 12
13comp.unix.solaris 12
14rec.radio.swap 8
15rec.gardens.edible 8
16comp.home.automation 6
17comp.sys.ibm.pc.games.rpg 6
18rec.video.cable-tv 4
19rec.heraldry 4
20comp.sys.mac.apps 4
21rec.antiques.radio+phono 4
22chi.weather 4
23rec.arts.tv.x-factor 2
24tw.bbs.comp.386bsd 2
25alt.cable-tv.tci-digital 2
26alt.security.alarms 2
27alt.video.tape-trading 2
28comp.sys.cbm 2
29chi.general 2
30alt.free.newsservers 2
31misc.consumers.frugal-living 2
32news.groups.questions 2
TOTAL: 32 1982