Daily Usenet report for news.ripco.com

Aug 15 00:01:01 -- Aug 15 23:57:36

Unknown entries from news log file:

First 26 / 26 lines (100.0%)

Aug 15 00:17:58 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 00:29:14 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 00:38:47 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 00:42:03 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 00:52:21 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 05:40:01 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 06:34:48 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 08:18:13 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 08:34:09 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 08:52:57 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 09:07:24 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 09:30:48 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 09:35:30 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 10:18:39 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 12:26:09 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 12:35:24 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 12:39:39 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 12:52:59 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 13:20:19 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 16:18:35 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 16:51:22 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 16:54:49 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 17:46:30 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 18:17:54 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 19:46:14 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping
Aug 15 22:08:14 remote5bge0 overchan[29365]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
innd 20705 35.8%3.2 MB 35.4%
nnrpd 17242 29.9%1.8 MB 20.0%
inn 16059 27.8%3.2 MB 35.7%
innfeed 3716 6.4%805.5 KB 8.8%
overchan 26 0.0%3.6 KB 0.0%
controlchan 8 0.0%1.9 KB 0.0%
TOTAL: 6 57756 100.0%8.9 MB100.0%

History cache:

ReasonCount%Count
Cache misses 159682 53.8%
Do not exist 137046 46.2%
Positive hits 0 0.0%
Negative hits 0 0.0%
TOTAL: 4 296728 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%140 0.000 0.000 0.000
article cleanup00:00:00.422 0.0%31794 0.000 0.013 0.042
article logging00:00:00.618 0.0%32190 0.000 0.019 0.238
article parse00:00:01.216 0.0%81738 0.000 0.015 0.057
article write00:01:17.766 0.0%25048 1.704 3.105 89.709
artlog/artparse00:00:00.000 0.0%10 0.000 0.000 0.000
data move00:00:00.308 0.0%310300 0.000 0.001 0.007
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:01.918 0.0%298512 0.001 0.006 0.016
history sync00:00:00.238 0.0%812 0.000 0.293 2.500
history write00:00:03.330 0.0%31802 0.000 0.105 3.641
hiswrite/artparse00:00:00.002 0.0%2 1.000 1.000 1.000
idle47:57:36.880 99.8%343362 294.629 502.842 1288.423
nntp read00:00:02.552 0.0%341916 0.002 0.007 0.016
overview write00:00:00.184 0.0%25048 0.000 0.007 0.032
perl filter00:00:44.350 0.0%31794 1.073 1.395 2.185
python filter00:00:01.094 0.0%31800 0.000 0.034 0.089
site send00:00:02.340 0.0%75098 0.000 0.031 0.075
TOTAL: 48:02:13.39247:59:53.218 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.096 0.0%25018 0.000 0.004 0.038
article prepare00:00:00.004 0.0%23848 0.000 0.000 0.017
article read00:00:00.676 0.0%4556 0.000 0.148 11.833
backlog stats00:00:00.190 0.0%395786 0.000 0.000 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.480 0.0%244306 0.000 0.006 0.018
data write00:00:04.432 0.0%220034 0.009 0.020 0.043
idle47:35:16.214100.0%395786 245.139 432.851 1120.609
status file00:00:05.680 0.0%382 0.750 14.869 356.750
TOTAL: 47:35:47.74047:35:28.772100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:56:04.430 95.0%17746 18.045 189.588 26992.891
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.574 0.1%291108 0.000 0.009 0.059
readart00:00:18.558 0.5%2204 0.333 8.420 37.000
TOTAL: 00:59:00.70600:56:25.562 95.6%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002002
TOTAL002002

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1148 32798 11226 18122 3450 34%94:38:36
2news.snarked.org 776 23268 6346 16408 514 27%56:05:18
3newsfeed.neva.ru 584 31212 3830 26262 1120 12%272:26:56
4feeder3.feed-ams.uzoreto.com 574 9390 1972 6738 680 21%47:10:34
5ripco-out.news.tnetconsulting.net 4 26976 862 26036 78 3%47:12:46
6newsfeed.fu-berlin.de 2 13260 622 12610 28 4%46:05:34
7news.lightlink.com 2 7396 96 7222 78 1%43:52:52
8news-in.rahul.net 574 14036 58 13952 26 0%47:29:26
9ripco-out.feeder.erje.net 574 26418 52 25632 734 0%47:26:08
10localhost 152 6 6 0 0 100%00:00:00
11news.cmpublishers.com 55 7204 6 7198 0 0%47:57:14
TOTAL: 11 4445 191964 25076 160180 6708 13%750:25:24
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com27.3 MB0.0 KB6.8 MB34.0 MB 80%2.4 KB
2news.snarked.org17.5 MB0.0 KB1.2 MB18.7 MB 93%2.8 KB
3ripco-out.news.tnetconsulting.net15.7 MB0.0 KB2.3 MB17.9 MB 87%19.5 KB
4newsfeed.neva.ru12.7 MB0.0 KB2.8 MB15.6 MB 81%3.2 KB
5feeder3.feed-ams.uzoreto.com5.4 MB0.0 KB1.6 MB7.0 MB 77%2.7 KB
6newsfeed.fu-berlin.de4.3 MB0.0 KB1.2 MB5.5 MB 78%8.6 KB
7news.lightlink.com2.8 MB0.0 KB5.8 MB8.6 MB 32%50.6 KB
8news-in.rahul.net711.5 KB0.0 KB1.5 MB2.2 MB 31%27.0 KB
9ripco-out.feeder.erje.net133.6 KB0.0 KB4.1 MB4.2 MB 3%5.5 KB
10news.cmpublishers.com11.8 KB0.0 KB0.0 KB11.8 KB100%2.0 KB
11localhost10.3 KB0.0 KB0.0 KB10.3 KB100%1.7 KB
TOTAL: 1186.6 MB0.0 KB27.2 MB113.8 MB 76%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 15 00:01:01 - 00:59:59 302 2.4% 0.091.1 MB 2.5% 0.31
Aug 15 01:00:00 - 01:59:59 292 2.3% 0.08780.4 KB 1.8% 0.22
Aug 15 02:00:00 - 02:59:59 425 3.4% 0.121.9 MB 4.6% 0.55
Aug 15 03:00:00 - 03:59:59 478 3.8% 0.131.6 MB 3.8% 0.46
Aug 15 04:00:00 - 04:59:59 546 4.4% 0.151.5 MB 3.7% 0.44
Aug 15 05:00:00 - 05:59:59 637 5.1% 0.182.4 MB 5.6% 0.68
Aug 15 06:00:00 - 06:59:59 533 4.3% 0.151.7 MB 3.9% 0.47
Aug 15 07:00:00 - 07:59:59 567 4.5% 0.161.8 MB 4.2% 0.50
Aug 15 08:00:00 - 08:59:59 785 6.3% 0.223.1 MB 7.4% 0.89
Aug 15 09:00:00 - 09:59:59 786 6.3% 0.223.1 MB 7.4% 0.89
Aug 15 10:00:00 - 10:59:59 648 5.2% 0.182.2 MB 5.3% 0.64
Aug 15 11:00:00 - 11:59:59 615 4.9% 0.171.8 MB 4.4% 0.53
Aug 15 12:00:00 - 12:59:59 708 5.7% 0.202.3 MB 5.5% 0.66
Aug 15 13:00:00 - 13:59:59 661 5.3% 0.181.9 MB 4.6% 0.55
Aug 15 14:00:00 - 14:59:59 650 5.2% 0.182.0 MB 4.7% 0.56
Aug 15 15:00:00 - 15:59:59 639 5.1% 0.182.2 MB 5.2% 0.62
Aug 15 16:00:00 - 16:59:59 664 5.3% 0.182.4 MB 5.6% 0.67
Aug 15 17:00:00 - 17:59:59 548 4.4% 0.151.7 MB 4.1% 0.49
Aug 15 18:00:00 - 18:59:59 445 3.6% 0.121.3 MB 3.2% 0.38
Aug 15 19:00:00 - 19:59:59 403 3.2% 0.111.1 MB 2.6% 0.31
Aug 15 20:00:00 - 20:59:59 369 3.0% 0.10956.8 KB 2.2% 0.27
Aug 15 21:00:00 - 21:59:59 254 2.0% 0.071.1 MB 2.6% 0.31
Aug 15 22:00:00 - 22:59:59 306 2.4% 0.091.4 MB 3.3% 0.40
Aug 15 23:00:00 - 23:57:36 232 1.9% 0.07692.8 KB 1.6% 0.20
TOTAL: 23:56:35 12493 100.0% 0.1442.1 MB 100.0% 0.50
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 1745 1280 0 0 4 0 0 0 461
2newsfeed.neva.ru 592 340 0 0 0 0 0 0 252
3ripco-out.feeder.erje.net 367 366 0 0 0 0 0 0 1
4feeder3.feed-ams.uzoreto.com 344 195 0 0 1 0 0 0 148
5news.snarked.org 254 196 0 0 3 0 0 0 55
6newsfeed.fu-berlin.de 156 1 0 0 0 0 0 0 155
7news.lightlink.com 54 0 0 0 0 0 0 0 54
8ripco-out.news.tnetconsulting.net 41 4 0 0 0 0 0 0 37
9news-in.rahul.net 13 0 0 0 0 0 0 0 13
TOTAL: 9 3566 2382 0 0 8 0 0 0 1176

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1730
alt.binaries.ftd 137
it.tlc.cellulari.android 32
fido7.su.pol.news 31
scinet.stats 28
perl.cpan.uploads 22
alt.binaries.e-book 21
free.it.scienza.fisica 16
alt.binaries.pictures.purebeauty 15
alt.paranormal.spells 14
alt.binaries.pictures.earlmiller 13
scot.general 13
uk.net.news.moderation 13
alt.binaries.nospam.breasts.natural 11
alt.binaries.e-books 10
free.willey 9
alt.binaries.pictures.erotica.vintage 8
alt.binaries.pictures.wallpaper 8
alt.binaries.pictures.rail 8
abg.test04 8
TOTAL: 133 2382

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

GroupsCount
pl.praca.oferowana 3
christnet.bible 2
bit.listserv.catholic 1
christnet.theology 1
alt.games.quake 1
TOTAL: 5 8

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 856
EMP (md5) 840
Binary in non-binary group 130
EMP (phl) 68
Scoring filter (8) 26
Scoring filter (11) 10
Scoring filter (10) 8
bofh violation - invalid distribution 4
Scoring filter (16) 4
Scoring filter (9) 2
Scoring filter (15) 2
TOTAL: 11 1950

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 20
feeder3.feed-ams.uzoreto.com 2
TOTAL: 2 22
Huge articles
newsfeed.fu-berlin.de 2
TOTAL: 1 2
Including strange strings
newsfeed.fu-berlin.de 141
newsfeed.neva.ru 31
ripco-out.feed.uzoreto.com 21
feeder3.feed-ams.uzoreto.com 3
TOTAL: 4 196
No colon-space in header
ripco-out.feed.uzoreto.com 3
feeder3.feed-ams.uzoreto.com 1
TOTAL: 2 4
TOTAL: 4 224

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1feeder.erje.net 28880 2674 20566 804 0 0 9%47:40:00
2rahul.net 23902 736 12616 14 0 0 3%47:44:10
3news.cmpublishers.com 37414 134 23344 1154 0 100 0%47:40:02
4fu-berlin.de 22330 126 22204 0 0 0 0%47:33:30
5news.tnetconsulting.net 36668 84 23902 10 0 26 0%47:48:46
6news.uzoreto.com 11834 60 11280 68 0 0 0%47:45:02
7news.snarked.org 31954 50 18430 12 0 32 0%47:50:08
8newsfeed.neva.ru 22674 14 21012 26 0 0 0%47:40:02
9remote6hme0.ripco.com 12 6 0 6 0 0 50%02:12:04
10news.lightlink.com 0 0 0 0 0 6724 0%47:40:00
TOTAL: 10 215668 3884 153354 2094 0 6882 1%431:33:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net9.8 MB27.8 KB9.8 MB0.1 KB/s13.4 KB47:44:10
2feeder.erje.net6.9 MB2.1 MB9.0 MB0.1 KB/s2.6 KB47:40:00
3news.cmpublishers.com421.0 KB3.2 MB3.6 MB0.0 KB/s2.9 KB47:40:02
4fu-berlin.de390.8 KB0.0 KB390.8 KB0.0 KB/s3.1 KB47:33:30
5news.tnetconsulting.net354.6 KB27.5 KB382.1 KB0.0 KB/s4.1 KB47:48:46
6news.snarked.org278.1 KB33.4 KB311.5 KB0.0 KB/s5.0 KB47:50:08
7news.uzoreto.com195.0 KB180.4 KB375.4 KB0.0 KB/s2.9 KB47:45:02
8newsfeed.neva.ru47.2 KB189.6 KB236.8 KB0.0 KB/s5.9 KB47:40:02
9remote6hme0.ripco.com10.0 KB28.6 KB38.5 KB0.0 KB/s3.2 KB02:12:04
10news.lightlink.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:00
TOTAL: 1018.3 MB5.8 MB24.1 MB0.0 KB/s4.1 KB431:33:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 10722.6 MB 460 2 000:08:15
2shell3.ripco.com 4 58451.7 KB 12 4 000:50:35
TOTAL: 2 290 11303.0 MB 472 6 000:58:51

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 10722.6 MB 460 2 000:08:15
2*.ripco.com 4 58451.7 KB 12 4 000:50:35
TOTAL: 2 290 11303.0 MB 472 6 000:58:51

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3.ripco.com 1.208 0.610 0.00000:50:35
ftupet.com 47.784 20.694 0.00000:08:15
64.225.124.179 0.202 0.050 0.00000:00:00
? 0.196 0.034 0.00000:00:00
TOTAL: 4 49.390 21.388 0.00000:58:51

NNRP no permission clients:

SystemConn
64.225.124.179 2
TOTAL: 1 2

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3.ripco.com 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1068 94.5%
2chi 60 5.3%
3news 2 0.2%
TOTAL: 3 1130100.0%

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

NewsgroupCount
1rec.food.cooking 506
2rec.arts.tv 494
3chi.general 50
4rec.bicycles.tech 50
5rec.bicycles.misc 10
6chi.forsale 6
7chi.weather 4
8rec.boats.paddle 4
9rec.gardens.edible 2
10rec.autos.driving 2
11news.admin.hierarchies 2
TOTAL: 11 1130