Daily Usenet report for news.ripco.com

Nov 28 00:01:02 -- Nov 28 23:57:39

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Nov 28 08:10:45 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 09:16:24 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 09:25:44 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 10:55:34 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 12:23:24 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 13:12:59 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 17:29:03 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 18:26:59 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 22:51:54 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping
Nov 28 23:35:14 remote5bge0 overchan[1927]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
innd 17899 31.8%2.8 MB 32.2%
inn 17541 31.2%3.3 MB 38.3%
nnrpd 17188 30.6%1.8 MB 20.5%
innfeed 3578 6.4%794.5 KB 8.9%
overchan 10 0.0%1.5 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 56218 100.0%8.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 167634 52.5%
Cache misses 151906 47.5%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 319540 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.002 0.0%58 0.000 0.034 0.143
article cleanup00:00:00.446 0.0%34744 0.000 0.013 0.061
article logging00:00:00.792 0.0%35136 0.000 0.023 0.180
article parse00:00:01.326 0.0%89640 0.000 0.015 0.038
article write00:01:33.620 0.1%24320 1.383 3.850 140.475
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.414 0.0%328104 0.000 0.001 0.006
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:02.688 0.0%321088 0.002 0.008 0.016
history sync00:00:00.208 0.0%792 0.000 0.263 1.500
history write00:00:03.680 0.0%34744 0.000 0.106 4.902
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:56:54.994 99.8%366848 242.843 470.535 1366.841
nntp read00:00:02.802 0.0%365666 0.001 0.008 0.018
overview write00:00:00.230 0.0%24320 0.000 0.009 0.088
perl filter00:00:51.778 0.0%34742 1.248 1.490 2.303
python filter00:00:01.280 0.0%34746 0.000 0.037 0.143
site send00:00:02.476 0.0%72934 0.000 0.034 0.069
TOTAL: 48:02:07.24647:59:36.736 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.112 0.0%24366 0.000 0.005 0.037
article prepare00:00:00.006 0.0%6592 0.000 0.001 0.036
article read00:00:00.340 0.0%1128 0.000 0.301 72.000
backlog stats00:00:00.256 0.0%382846 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.970 0.0%238052 0.000 0.008 0.021
data write00:00:04.550 0.0%213880 0.009 0.021 0.042
idle47:37:31.762100.0%382846 244.548 447.835 1160.344
status file00:00:01.892 0.0%336 1.000 5.631 43.000
TOTAL: 47:38:03.19847:37:40.888100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:08:18.576 98.3%17948 18.365 629.517 56071.526
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:07.946 0.1%822330 0.000 0.010 0.059
readart00:00:20.498 0.2%2414 1.775 8.491 65.500
TOTAL: 03:11:36.68803:08:47.020 98.5%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1146 25796 7934 14940 2922 30%94:38:48
2feeder3.feed-ams.uzoreto.com 574 20962 7428 9118 4416 35%47:18:38
3newsfeed.neva.ru 590 21944 4080 16328 1536 18%273:32:24
4news.snarked.org 52 23226 3442 19566 218 14%56:55:54
5ripco-out.news.tnetconsulting.net 2 27614 552 26910 152 1%46:07:40
6newsfeed.fu-berlin.de 2 7694 448 7232 14 5%33:37:56
7news-in.rahul.net 574 12078 86 11980 12 0%47:18:56
8news.lightlink.com 2 7056 80 6964 12 1%52:29:26
9ripco-out.feeder.erje.net 574 27926 30 26910 986 0%47:18:12
10news.cmpublishers.com 50 8002 18 7972 12 0%48:00:04
11localhost 150 4 4 0 0 100%00:00:00
TOTAL: 11 3716 182302 24102 147920 10280 13%747:17:58
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com21.3 MB0.0 KB6.8 MB28.1 MB 75%2.7 KB
2feeder3.feed-ams.uzoreto.com19.1 MB0.0 KB8.6 MB27.6 MB 68%2.4 KB
3newsfeed.neva.ru13.0 MB3.0 KB5.7 MB18.7 MB 69%3.4 KB
4news.snarked.org10.6 MB0.0 KB970.4 KB11.6 MB 91%3.2 KB
5ripco-out.news.tnetconsulting.net10.0 MB0.0 KB3.0 MB12.9 MB 76%18.8 KB
6newsfeed.fu-berlin.de3.3 MB0.0 KB473.4 KB3.7 MB 87%8.3 KB
7news-in.rahul.net2.2 MB0.0 KB265.5 KB2.4 MB 89%25.6 KB
8news.lightlink.com206.9 KB0.0 KB489.5 KB696.3 KB 29%7.6 KB
9ripco-out.feeder.erje.net43.1 KB0.0 KB7.6 MB7.7 MB 0%7.7 KB
10news.cmpublishers.com41.5 KB0.0 KB28.3 KB69.8 KB 59%2.3 KB
11localhost6.4 KB0.0 KB0.0 KB6.4 KB100%1.6 KB
TOTAL: 1179.8 MB3.0 KB33.8 MB113.6 MB 70%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 28 00:01:02 - 00:59:59 249 2.1% 0.07916.5 KB 2.2% 0.26
Nov 28 01:00:00 - 01:59:59 374 3.1% 0.101.7 MB 4.3% 0.49
Nov 28 02:00:00 - 02:59:59 399 3.3% 0.111.1 MB 2.6% 0.30
Nov 28 03:00:00 - 03:59:59 487 4.0% 0.141.5 MB 3.7% 0.42
Nov 28 04:00:00 - 04:59:59 606 5.0% 0.172.5 MB 6.2% 0.70
Nov 28 05:00:00 - 05:59:59 621 5.1% 0.171.8 MB 4.5% 0.52
Nov 28 06:00:00 - 06:59:59 540 4.4% 0.151.6 MB 4.1% 0.47
Nov 28 07:00:00 - 07:59:59 607 5.0% 0.172.1 MB 5.2% 0.60
Nov 28 08:00:00 - 08:59:59 715 5.9% 0.202.6 MB 6.4% 0.74
Nov 28 09:00:00 - 09:59:59 688 5.7% 0.192.2 MB 5.5% 0.63
Nov 28 10:00:00 - 10:59:59 633 5.2% 0.182.3 MB 5.8% 0.66
Nov 28 11:00:00 - 11:59:59 653 5.4% 0.182.0 MB 5.0% 0.57
Nov 28 12:00:00 - 12:59:59 738 6.1% 0.202.2 MB 5.4% 0.62
Nov 28 13:00:00 - 13:59:59 675 5.6% 0.192.1 MB 5.1% 0.59
Nov 28 14:00:00 - 14:59:59 571 4.7% 0.161.9 MB 4.7% 0.53
Nov 28 15:00:00 - 15:59:59 580 4.8% 0.161.8 MB 4.5% 0.52
Nov 28 16:00:00 - 16:59:59 696 5.7% 0.191.8 MB 4.6% 0.52
Nov 28 17:00:00 - 17:59:59 469 3.9% 0.131.3 MB 3.3% 0.38
Nov 28 18:00:00 - 18:59:59 372 3.1% 0.101.2 MB 3.0% 0.35
Nov 28 19:00:00 - 19:59:59 374 3.1% 0.101.1 MB 2.8% 0.32
Nov 28 20:00:00 - 20:59:59 326 2.7% 0.09925.0 KB 2.3% 0.26
Nov 28 21:00:00 - 21:59:59 313 2.6% 0.091.3 MB 3.4% 0.38
Nov 28 22:00:00 - 22:59:59 212 1.7% 0.06761.2 KB 1.9% 0.21
Nov 28 23:00:00 - 23:57:39 239 2.0% 0.071.4 MB 3.5% 0.42
TOTAL: 23:56:37 12137 100.0% 0.1440.1 MB 100.0% 0.48
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1feeder3.feed-ams.uzoreto.com 2224 1853 0 0 1 0 0 0 370
2ripco-out.feed.uzoreto.com 1463 1051 0 0 5 0 0 0 407
3newsfeed.neva.ru 813 462 0 1 2 0 0 0 348
4ripco-out.feeder.erje.net 493 487 0 0 0 0 0 0 6
5newsfeed.fu-berlin.de 163 0 0 0 0 0 0 0 163
6news.snarked.org 116 44 0 0 0 0 0 0 72
7ripco-out.news.tnetconsulting.net 81 44 0 0 0 0 0 0 37
8news.lightlink.com 40 5 0 0 0 0 0 0 35
9news-in.rahul.net 6 0 0 0 0 0 0 0 6
10news.cmpublishers.com 5 1 0 0 0 0 0 0 4
TOTAL: 10 5404 3947 0 1 8 0 0 0 1448

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 3101
alt.binaries.ftd 287
alt.bestjobsusa.computer.jobs 39
scinet.stats 32
alt.binaries.pictures.rail 29
uk.net.news.moderation 26
free.willey 25
fido7.su.pol.news 18
alt.my.job.sucks 17
free.it.scienza.fisica 17
it.tlc.cellulari.android 15
alt.binaries.nospam.breasts.natural 15
alt.binaries.pictures.purebeauty 13
alt.binaries.pictures.wallpaper 12
alt.jobs.jobsearch 12
alt.binaries.nospam.female.bodyhair 11
alt.binaries.pictures.earlmiller 11
at.jobs 10
alt.binaries.mac.osx.apps 10
alt.binaries.test.yenc 9
TOTAL: 125 3947

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

GroupsCount
pl.praca.oferowana 4
christnet.bible 3
bit.listserv.catholic 1
TOTAL: 3 8

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 1402
EMP (md5) 700
EMP (phl) 180
Binary in non-binary group 174
Scoring filter (8) 16
Scoring filter (9) 10
Scoring filter (10) 6
HTML post 6
Scoring filter (11) 6
Scoring filter (12) 4
U2 violation - invalid distribution 2
TOTAL: 11 2506

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1rahul.net 19190 682 10252 4 0 0 3%47:41:38
2news.lightlink.com 9452 508 5562 14 0 0 5%47:40:02
3feeder.erje.net 25858 138 22734 42 0 2 0%47:50:34
4news.uzoreto.com 8832 28 8590 8 0 0 0%47:40:14
5news.snarked.org 34342 24 20348 8 0 38 0%47:40:12
6newsfeed.neva.ru 20580 20 20088 2 0 0 0%47:40:12
7news.tnetconsulting.net 38180 14 23728 0 0 24 0%47:40:06
8news.cmpublishers.com 34914 12 23902 126 0 78 0%47:40:12
9fu-berlin.de 21758 12 21746 0 0 0 0%47:40:12
10remote6hme0.ripco.com 6 0 0 6 0 0 0%01:12:08
TOTAL: 10 213112 1438 156950 210 0 142 0%430:25:30
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com7.2 MB74.1 KB7.3 MB0.0 KB/s14.3 KB47:40:02
2rahul.net5.5 MB44.3 KB5.6 MB0.0 KB/s8.3 KB47:41:38
3feeder.erje.net421.9 KB127.2 KB549.1 KB0.0 KB/s3.1 KB47:50:34
4news.uzoreto.com153.9 KB13.3 KB167.2 KB0.0 KB/s4.6 KB47:40:14
5news.snarked.org105.5 KB8.7 KB114.2 KB0.0 KB/s3.6 KB47:40:12
6news.tnetconsulting.net77.5 KB0.0 KB77.5 KB0.0 KB/s5.5 KB47:40:06
7fu-berlin.de73.2 KB0.0 KB73.2 KB0.0 KB/s6.1 KB47:40:12
8newsfeed.neva.ru54.9 KB36.4 KB91.3 KB0.0 KB/s4.1 KB47:40:12
9news.cmpublishers.com28.3 KB392.7 KB421.0 KB0.0 KB/s3.1 KB47:40:12
10remote6hme0.ripco.com0.0 KB26.6 KB26.6 KB0.0 KB/s4.4 KB01:12:08
TOTAL: 1013.6 MB723.4 KB14.4 MB0.0 KB/s8.9 KB430:25:30
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 10283.0 MB 410 2 000:08:39
2shell3.ripco.com 10 3561.0 MB 30 2 003:02:47
TOTAL: 2 296 13844.1 MB 440 4 003:11:27

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 10283.0 MB 410 2 000:08:39
2*.ripco.com 10 3561.0 MB 30 2 003:02:47
TOTAL: 2 296 13844.1 MB 440 4 003:11:26

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3.ripco.com 3.692 2.388 0.00003:02:47
ftupet.com 46.602 21.492 0.00000:08:39
192.241.234.214 0.200 0.054 0.00000:00:00
TOTAL: 3 50.494 23.934 0.00003:11:27

NNRP no permission clients:

SystemConn
192.241.234.214 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 1198 86.6%
2comp 92 6.6%
3misc 60 4.3%
4sci 24 1.7%
5chi 4 0.3%
6news 4 0.3%
7alt 2 0.1%
TOTAL: 7 1384100.0%

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

NewsgroupCount
1rec.arts.tv 616
2rec.food.cooking 494
3rec.bicycles.tech 66
4comp.sys.mac.system 60
5misc.phone.mobile.iphone 60
6sci.electronics.repair 24
7comp.mobile.ipad 18
8comp.mobile.android 12
9rec.radio.broadcasting 8
10rec.radio.shortwave 6
11rec.gardens.edible 4
12chi.weather 4
13rec.puzzles 2
14comp.sys.mac.apps 2
15news.software.readers 2
16news.admin.net-abuse.usenet 2
17rec.radio.swap 2
18alt.video.dvd 2
TOTAL: 18 1384