Daily Usenet report for news.ripco.com

Oct 31 00:01:01 -- Oct 31 23:58:05

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Oct 31 09:53:09 remote5bge0 overchan[27920]: [ID 702911 news.error] input line too long, skipping
Oct 31 10:38:41 remote5bge0 overchan[27920]: [ID 702911 news.error] input line too long, skipping
Oct 31 11:21:30 remote5bge0 overchan[27920]: [ID 702911 news.error] input line too long, skipping
Oct 31 13:59:54 remote5bge0 overchan[27920]: [ID 702911 news.error] input line too long, skipping
Oct 31 14:41:18 remote5bge0 overchan[27920]: [ID 702911 news.error] input line too long, skipping
Oct 31 14:45:21 remote5bge0 overchan[27920]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
innd 17982 31.8%2.8 MB 31.6%
inn 17705 31.3%3.5 MB 39.0%
nnrpd 17212 30.4%1.8 MB 20.2%
innfeed 3674 6.5%830.2 KB 9.2%
controlchan 10 0.0%2.0 KB 0.0%
overchan 6 0.0%1.0 KB 0.0%
TOTAL: 6 56589 100.0%8.8 MB100.0%

History cache:

ReasonCount%Count
Cache misses 168888 51.7%
Do not exist 157926 48.3%
Positive hits 0 0.0%
Negative hits 0 0.0%
TOTAL: 4 326814 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%84 0.000 0.000 0.000
article cleanup00:00:00.444 0.0%35080 0.000 0.013 0.064
article logging00:00:00.744 0.0%35500 0.000 0.021 0.069
article parse00:00:01.360 0.0%92712 0.000 0.015 0.049
article write00:01:25.836 0.0%26474 1.477 3.242 83.946
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.360 0.0%340782 0.000 0.001 0.009
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.904 0.0%327964 0.002 0.009 0.019
history sync00:00:00.544 0.0%792 0.000 0.687 40.250
history write00:00:03.456 0.0%35082 0.009 0.099 2.149
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:57:01.124 99.8%375388 236.134 459.847 1374.929
nntp read00:00:02.824 0.0%374728 0.002 0.008 0.016
overview write00:00:00.278 0.0%26474 0.000 0.011 0.081
perl filter00:00:53.442 0.0%35078 1.202 1.524 3.000
python filter00:00:01.446 0.0%35082 0.000 0.041 0.136
site send00:00:02.698 0.0%79410 0.000 0.034 0.079
TOTAL: 48:02:06.46247:59:37.460 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.094 0.0%26612 0.000 0.004 0.026
article prepare00:00:00.012 0.0%23888 0.000 0.001 0.017
article read00:00:00.678 0.0%4132 0.000 0.164 9.667
backlog stats00:00:00.272 0.0%437520 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.690 0.0%269944 0.000 0.006 0.019
data write00:00:05.002 0.0%243578 0.007 0.021 0.044
idle47:51:32.392100.0%437520 225.236 393.793 1108.220
status file00:00:02.398 0.0%388 0.750 6.180 51.000
TOTAL: 47:52:01.24847:51:42.538100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:59:45.716 97.4%17976 0.000 399.739 60469.100
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:06.614 0.1%654252 0.000 0.010 0.143
readart00:00:19.898 0.3%2270 2.433 8.766 37.500
TOTAL: 02:03:00.25802:00:12.228 97.7%----

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
news@sfere.anjou.terraraq.org.uk002002
us-control@lists.killfile.org002002
TOTAL004004

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1146 32738 10826 17592 4320 33%94:49:50
2news.snarked.org 54 26892 5904 20734 254 21%58:19:26
3feeder3.feed-ams.uzoreto.com 574 14132 4336 8808 988 30%47:30:02
4newsfeed.neva.ru 596 29286 3844 23744 1698 13%274:15:46
5newsfeed.fu-berlin.de 4 14098 610 13470 18 4%47:47:08
6ripco-out.news.tnetconsulting.net 4 28354 590 27670 94 2%46:49:16
7news.lightlink.com 2 8286 158 7966 162 1%52:29:26
8news-in.rahul.net 574 14154 54 14032 68 0%47:29:06
9news.cmpublishers.com 50 8678 36 8642 0 0%48:00:06
10ripco-out.feeder.erje.net 574 28372 22 27368 982 0%47:16:54
11localhost 150 4 4 0 0 100%00:00:00
TOTAL: 11 3728 204994 26384 170026 8584 12%764:47:00
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com26.5 MB0.0 KB7.8 MB34.2 MB 77%2.3 KB
2news.snarked.org16.9 MB0.0 KB1.3 MB18.2 MB 92%3.0 KB
3ripco-out.news.tnetconsulting.net16.1 MB0.0 KB1.9 MB18.0 MB 89%26.9 KB
4newsfeed.neva.ru13.1 MB0.0 KB7.3 MB20.5 MB 64%3.8 KB
5feeder3.feed-ams.uzoreto.com12.8 MB0.0 KB2.5 MB15.3 MB 83%2.9 KB
6newsfeed.fu-berlin.de6.8 MB0.0 KB832.0 KB7.6 MB 89%12.4 KB
7news.lightlink.com2.8 MB0.0 KB6.7 MB9.5 MB 29%30.4 KB
8news-in.rahul.net1.4 MB0.0 KB3.3 MB4.7 MB 29%39.3 KB
9news.cmpublishers.com123.8 KB0.0 KB0.0 KB123.8 KB100%3.4 KB
10ripco-out.feeder.erje.net98.0 KB0.0 KB6.4 MB6.5 MB 1%6.6 KB
11localhost4.8 KB0.0 KB0.0 KB4.8 KB100%1.2 KB
TOTAL: 1196.7 MB0.0 KB37.9 MB134.6 MB 71%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 31 00:01:01 - 00:59:59 354 2.7% 0.10897.6 KB 1.9% 0.25
Oct 31 01:00:00 - 01:59:59 455 3.4% 0.131.2 MB 2.7% 0.35
Oct 31 02:00:00 - 02:59:59 500 3.8% 0.141.5 MB 3.2% 0.42
Oct 31 03:00:00 - 03:59:59 414 3.1% 0.121.1 MB 2.3% 0.30
Oct 31 04:00:00 - 04:59:59 482 3.6% 0.131.8 MB 3.8% 0.50
Oct 31 05:00:00 - 05:59:59 562 4.3% 0.162.0 MB 4.3% 0.57
Oct 31 06:00:00 - 06:59:59 574 4.3% 0.161.8 MB 4.0% 0.53
Oct 31 07:00:00 - 07:59:59 532 4.0% 0.153.6 MB 7.8% 1.03
Oct 31 08:00:00 - 08:59:59 652 4.9% 0.182.4 MB 5.1% 0.67
Oct 31 09:00:00 - 09:59:59 883 6.7% 0.253.1 MB 6.7% 0.89
Oct 31 10:00:00 - 10:59:59 711 5.4% 0.202.3 MB 4.9% 0.65
Oct 31 11:00:00 - 11:59:59 848 6.4% 0.242.4 MB 5.2% 0.69
Oct 31 12:00:00 - 12:59:59 774 5.9% 0.212.5 MB 5.3% 0.70
Oct 31 13:00:00 - 13:59:59 819 6.2% 0.232.7 MB 5.8% 0.76
Oct 31 14:00:00 - 14:59:59 709 5.4% 0.202.5 MB 5.4% 0.71
Oct 31 15:00:00 - 15:59:59 623 4.7% 0.172.1 MB 4.5% 0.59
Oct 31 16:00:00 - 16:59:59 670 5.1% 0.192.0 MB 4.4% 0.58
Oct 31 17:00:00 - 17:59:59 582 4.4% 0.161.7 MB 3.7% 0.49
Oct 31 18:00:00 - 18:59:59 454 3.4% 0.131.3 MB 2.8% 0.37
Oct 31 19:00:00 - 19:59:59 472 3.6% 0.131.5 MB 3.1% 0.41
Oct 31 20:00:00 - 20:59:59 308 2.3% 0.091.1 MB 2.3% 0.30
Oct 31 21:00:00 - 21:59:59 290 2.2% 0.082.7 MB 5.8% 0.76
Oct 31 22:00:00 - 22:59:59 318 2.4% 0.091.2 MB 2.5% 0.33
Oct 31 23:00:00 - 23:58:05 230 1.7% 0.071.1 MB 2.4% 0.33
TOTAL: 23:57:04 13216 100.0% 0.1546.4 MB 100.0% 0.55
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2192 1800 0 0 7 0 0 0 385
2newsfeed.neva.ru 847 401 0 0 1 0 0 0 445
3feeder3.feed-ams.uzoreto.com 497 279 0 0 2 0 0 0 216
4ripco-out.feeder.erje.net 490 487 0 0 0 0 0 0 3
5newsfeed.fu-berlin.de 157 1 0 0 0 0 0 0 156
6news.snarked.org 128 47 0 0 1 0 0 0 80
7news.lightlink.com 101 1 0 0 0 0 0 0 100
8ripco-out.news.tnetconsulting.net 42 33 0 0 0 0 0 0 9
9news-in.rahul.net 34 0 0 0 0 0 0 0 34
10news.cmpublishers.com 1 0 0 0 0 0 0 0 1
TOTAL: 10 4489 3049 0 0 11 0 0 0 1429

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2173
alt.binaries.ftd 205
uk.net.news.moderation 70
alt.bestjobsusa.computer.jobs 38
scinet.stats 32
linux.debian.maint.emacsen 28
alt.binaries.pictures.purebeauty 28
fido7.su.pol.news 23
alt.binaries.e-book 19
alt.binaries.nospam.breasts.natural 19
alt.binaries.pictures.earlmiller 17
alt.binaries.pictures.rail 17
alt.binaries.pictures.scenic 16
alt.binaries.sounds.lossless.classical 13
att.jobs 13
alt.binaries.pictures.wallpaper 12
alt.binaries.test.yenc 12
aus.jobs 11
alt.jobs.jobsearch 11
free.willey 11
TOTAL: 145 3049

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

GroupsCount
pl.praca.oferowana 4
christnet.bible 3
fr.comp.securite 2
bit.listserv.catholic 1
christnet.theology 1
TOTAL: 5 11

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1084
Too many newsgroups 833
Binary in non-binary group 312
EMP (phl) 166
Scoring filter (8) 18
Scoring filter (9) 10
HTML post 10
Scoring filter (10) 4
TOTAL: 8 2437

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 70
feeder3.feed-ams.uzoreto.com 34
ripco-out.feed.uzoreto.com 24
TOTAL: 3 128
Including strange strings
newsfeed.fu-berlin.de 150
ripco-out.feed.uzoreto.com 36
newsfeed.neva.ru 21
feeder3.feed-ams.uzoreto.com 3
TOTAL: 4 210
No colon-space in header
feeder3.feed-ams.uzoreto.com 1
TOTAL: 1 1
TOTAL: 3 339

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1feeder.erje.net 30962 1618 23900 192 0 0 5%47:36:20
2news.lightlink.com 9896 1414 5306 14 0 0 14%47:40:02
3rahul.net 23686 1140 12394 12 0 0 4%47:39:14
4news.cmpublishers.com 40118 114 24774 1138 0 54 0%47:38:06
5fu-berlin.de 23964 114 23850 0 0 0 0%47:53:40
6news.uzoreto.com 11120 44 10714 72 0 0 0%47:40:32
7news.snarked.org 34942 26 20218 12 0 78 0%47:34:30
8news.tnetconsulting.net 39944 20 25686 2 0 46 0%47:40:02
9newsfeed.neva.ru 23422 16 22510 10 0 0 0%47:42:56
10remote6hme0.ripco.com 10 4 0 6 0 0 40%01:52:10
TOTAL: 10 238064 4510 169352 1458 0 178 1%430:57:32
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net11.7 MB64.9 KB11.8 MB0.1 KB/s10.5 KB47:39:14
2news.lightlink.com4.6 MB127.6 KB4.7 MB0.0 KB/s3.4 KB47:40:02
3feeder.erje.net4.3 MB453.7 KB4.7 MB0.0 KB/s2.7 KB47:36:20
4fu-berlin.de1.2 MB0.0 KB1.2 MB0.0 KB/s10.8 KB47:53:40
5news.cmpublishers.com365.7 KB3.0 MB3.3 MB0.0 KB/s2.7 KB47:38:06
6news.uzoreto.com219.8 KB243.9 KB463.7 KB0.0 KB/s4.0 KB47:40:32
7news.snarked.org137.2 KB14.0 KB151.2 KB0.0 KB/s4.0 KB47:34:30
8news.tnetconsulting.net116.0 KB7.5 KB123.5 KB0.0 KB/s5.6 KB47:40:02
9newsfeed.neva.ru97.4 KB29.0 KB126.4 KB0.0 KB/s4.9 KB47:42:56
10remote6hme0.ripco.com3.7 KB30.8 KB34.6 KB0.0 KB/s3.5 KB01:52:10
TOTAL: 1022.7 MB3.9 MB26.6 MB0.0 KB/s4.6 KB430:57:32
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 9782.5 MB 436 4 000:08:59
2shell3.ripco.com 12 310945.4 KB 40 0 001:53:49
TOTAL: 2 298 12883.5 MB 476 4 002:02:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 9782.5 MB 436 4 000:08:59
2*.ripco.com 12 310945.4 KB 40 0 001:53:49
TOTAL: 2 298 12883.5 MB 476 4 002:02:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3.ripco.com 3.498 2.216 0.00001:53:49
ftupet.com 46.906 21.572 0.00000:08:59
33.20.97.83.ro.ovo.sc 0.200 0.052 0.00000:00:01
worker-18.sfj.censys-scanner.com 0.406 0.098 0.00000:00:00
TOTAL: 4 51.010 23.938 0.00002:02:50

NNRP no permission clients:

SystemConn
worker-18.sfj.censys-scanner.com 4
33.20.97.83.ro.ovo.sc 2
TOTAL: 2 6

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 1154 89.6%
2sci 30 2.3%
3comp 28 2.2%
4alt 26 2.0%
5misc 26 2.0%
6chi 12 0.9%
7houston 6 0.5%
8soc 4 0.3%
9news 2 0.2%
TOTAL: 9 1288100.0%

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

NewsgroupCount
1rec.arts.tv 594
2rec.food.cooking 468
3rec.bicycles.tech 66
4sci.electronics.repair 30
5misc.phone.mobile.iphone 22
6alt.online-service.comcast 18
7rec.gardens.edible 12
8comp.mobile.android 12
9comp.sys.apple2 10
10comp.sys.mac.system 6
11rec.radio.broadcasting 6
12rec.radio.shortwave 6
13chi.weather 4
14houston.forsale 4
15alt.security.alarms 4
16chi.forsale 4
17chi.general 4
18misc.consumers.frugal-living 4
19soc.history.what-if 4
20alt.radio.pirate 2
21houston.wanted 2
22rec.radio.swap 2
23news.groups.proposals 2
24alt.games.warcraft 2
TOTAL: 24 1288