Daily Usenet report for news.ripco.com

Oct 6 00:01:03 -- Oct 6 23:58:05

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Oct 6 00:13:51 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping
Oct 6 06:01:19 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping
Oct 6 08:55:40 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping
Oct 6 09:02:35 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping
Oct 6 09:19:50 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping
Oct 6 14:21:33 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping
Oct 6 15:10:16 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping
Oct 6 15:14:59 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping
Oct 6 19:36:19 remote5bge0 overchan[426]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
innd 28586 36.7%4.4 MB 38.8%
inn 27991 36.0%4.3 MB 38.5%
nnrpd 17592 22.6%1.8 MB 16.1%
innfeed 3646 4.7%756.1 KB 6.6%
overchan 9 0.0%1.3 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 77826 100.0%11.3 MB100.0%

History cache:

ReasonCount%Count
Do not exist 254958 66.5%
Cache misses 128328 33.5%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 383286 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%158 0.000 0.000 0.000
article cleanup00:00:00.518 0.0%55574 0.000 0.009 0.044
article logging00:00:00.768 0.0%55868 0.000 0.014 0.490
article parse00:00:02.104 0.0%134198 0.000 0.016 0.032
article write00:00:33.798 0.0%25882 0.368 1.306 14.145
artlog/artparse00:00:00.000 0.0%8 0.000 0.000 0.000
data move00:00:00.330 0.0%396108 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.162 0.0%383806 0.002 0.008 0.016
history sync00:00:00.208 0.0%804 0.000 0.259 1.333
history write00:00:04.482 0.0%55580 0.027 0.081 0.288
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:39:40.338 99.9%447804 184.789 383.159 1115.268
nntp read00:00:02.586 0.0%446670 0.000 0.006 0.014
overview write00:00:00.138 0.0%25882 0.000 0.005 0.036
perl filter00:01:07.444 0.0%55570 1.020 1.214 2.134
python filter00:00:01.342 0.0%55572 0.000 0.024 0.058
site send00:00:01.396 0.0%77634 0.000 0.018 0.061
TOTAL: 47:42:12.38447:41:38.614100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.272 0.0%26064 0.000 0.010 0.062
article prepare00:00:00.000 0.0%2000 0.000 0.000 0.000
article read00:00:00.184 0.0%414 0.000 0.444 71.000
backlog stats00:00:00.162 0.0%297458 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.026 0.0%198552 0.000 0.010 0.023
data write00:00:02.548 0.0%173160 0.005 0.015 0.028
idle47:37:40.682100.0%297458 308.793 576.420 1617.835
status file00:00:02.340 0.0%348 0.000 6.724 44.000
TOTAL: 47:37:59.05847:37:48.214100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:14:42.980 98.1%17816 19.072 251.627 9591.929
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:03.950 0.1%476350 0.000 0.008 1.000
readart00:00:18.786 0.4%2116 2.333 8.878 36.000
TOTAL: 01:16:11.67601:15:05.716 98.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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feeder3.feed-ams.uzoreto.com 574 23988 10818 8844 4326 45%47:19:14
2ripco-out.feed.uzoreto.com 1148 21146 6110 13340 1696 28%94:58:02
3newsfeed.neva.ru 578 41956 4938 15268 21750 11%281:51:10
4news.snarked.org 62 24112 2312 21592 208 9%61:13:06
5news-in.rahul.net 574 14442 918 13508 16 6%47:21:20
6newsfeed.fu-berlin.de 2 10770 404 10348 18 3%43:28:08
7news.cmpublishers.com 50 8036 256 7772 8 3%48:00:06
8news.lightlink.com 2 9056 134 8616 306 1%49:48:38
9ripco-out.feeder.erje.net 574 28186 20 27006 1160 0%47:19:28
10localhost 148 2 2 0 0 100%00:00:00
TOTAL: 10 3712 181694 25912 126294 29488 14%721:19:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feeder3.feed-ams.uzoreto.com27.2 MB0.0 KB10.4 MB37.6 MB 72%2.5 KB
2ripco-out.feed.uzoreto.com16.7 MB0.0 KB4.9 MB21.6 MB 77%2.8 KB
3newsfeed.neva.ru14.9 MB0.0 KB85.5 MB100.4 MB 14%3.9 KB
4news-in.rahul.net8.5 MB0.0 KB199.6 KB8.7 MB 97%9.6 KB
5news.snarked.org7.3 MB5.6 KB1012.0 KB8.3 MB 87%3.4 KB
6newsfeed.fu-berlin.de2.6 MB0.0 KB214.1 KB2.8 MB 92%6.9 KB
7news.lightlink.com1.0 MB0.0 KB11.5 MB12.5 MB 8%29.1 KB
8news.cmpublishers.com789.7 KB0.0 KB16.5 KB806.2 KB 97%3.1 KB
9ripco-out.feeder.erje.net101.3 KB0.0 KB10.7 MB10.8 MB 0%9.4 KB
10localhost3.6 KB0.0 KB0.0 KB3.6 KB100%1.8 KB
TOTAL: 1079.1 MB5.6 KB124.4 MB203.5 MB 38%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 6 00:01:03 - 00:59:59 284 2.2% 0.08848.7 KB 2.1% 0.24
Oct 6 01:00:00 - 01:59:59 373 2.9% 0.101.4 MB 3.5% 0.40
Oct 6 02:00:00 - 02:59:59 375 2.9% 0.101.0 MB 2.6% 0.29
Oct 6 03:00:00 - 03:59:59 544 4.2% 0.151.5 MB 3.8% 0.43
Oct 6 04:00:00 - 04:59:59 537 4.1% 0.151.9 MB 4.8% 0.54
Oct 6 05:00:00 - 05:59:59 525 4.1% 0.151.6 MB 4.0% 0.45
Oct 6 06:00:00 - 06:59:59 591 4.6% 0.161.7 MB 4.4% 0.50
Oct 6 07:00:00 - 07:59:59 571 4.4% 0.161.4 MB 3.5% 0.39
Oct 6 08:00:00 - 08:59:59 648 5.0% 0.182.2 MB 5.4% 0.62
Oct 6 09:00:00 - 09:59:59 657 5.1% 0.181.8 MB 4.5% 0.50
Oct 6 10:00:00 - 10:59:59 685 5.3% 0.192.3 MB 5.8% 0.66
Oct 6 11:00:00 - 11:59:59 660 5.1% 0.181.8 MB 4.5% 0.50
Oct 6 12:00:00 - 12:59:59 692 5.3% 0.191.9 MB 4.8% 0.54
Oct 6 13:00:00 - 13:59:59 791 6.1% 0.222.2 MB 5.6% 0.63
Oct 6 14:00:00 - 14:59:59 847 6.5% 0.242.3 MB 5.8% 0.65
Oct 6 15:00:00 - 15:59:59 816 6.3% 0.232.5 MB 6.3% 0.72
Oct 6 16:00:00 - 16:59:59 758 5.9% 0.212.6 MB 6.6% 0.75
Oct 6 17:00:00 - 17:59:59 599 4.6% 0.171.8 MB 4.4% 0.50
Oct 6 18:00:00 - 18:59:59 408 3.1% 0.111.3 MB 3.2% 0.36
Oct 6 19:00:00 - 19:59:59 296 2.3% 0.08884.7 KB 2.2% 0.25
Oct 6 20:00:00 - 20:59:59 329 2.5% 0.091.2 MB 3.1% 0.35
Oct 6 21:00:00 - 21:59:59 361 2.8% 0.101.4 MB 3.5% 0.40
Oct 6 22:00:00 - 22:59:59 323 2.5% 0.09944.2 KB 2.3% 0.26
Oct 6 23:00:00 - 23:58:05 286 2.2% 0.081.4 MB 3.5% 0.41
TOTAL: 23:57:02 12956 100.0% 0.1539.7 MB 100.0% 0.47
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 11048 5814 0 0 0 0 0 0 5234
2feeder3.feed-ams.uzoreto.com 2179 1471 0 0 1 0 0 0 707
3ripco-out.feed.uzoreto.com 852 415 0 0 3 0 0 0 434
4ripco-out.feeder.erje.net 579 569 0 0 0 0 0 0 10
5news.lightlink.com 147 0 0 0 0 0 0 0 147
6news.snarked.org 108 29 0 1 2 0 0 0 76
7newsfeed.fu-berlin.de 106 0 0 0 0 0 0 0 106
8news.cmpublishers.com 8 0 0 0 0 0 0 0 8
9news-in.rahul.net 8 0 0 0 0 0 0 0 8
TOTAL: 9 15035 8298 0 1 6 0 0 0 6730

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.boneless 5412
free.usenet 2083
alt.binaries.ftd 347
free.willey 68
alt.binaries.pictures.rail 36
de.soc.arbeitslos 24
alt.binaries.pictures.wallpaper 24
fido7.su.pol.news 17
perl.cpan.uploads 17
it.tlc.cellulari.android 15
it.comp.os.win.windows10 14
alt.binaries.nospam.female.bodyhair.pubes 13
alt.binaries.pictures.scenic 12
fr.usenet.abus.nocem 12
scot.general 11
alt.binaries.3d.poser 9
alt.binaries.e-book 8
alt.binaries.test.yenc 7
muc.lists.freebsd.fs 6
alt.binaries.pictures.purebeauty 6
TOTAL: 116 8298

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

GroupsCount
fr.comp.securite 2
alt.christnet.religion 1
alt.christnet.christnews 1
rec.travel.resorts.all-inclusive 1
bit.listserv.catholic 1
TOTAL: 5 6

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 9297
EMP (md5) 2636
Too many newsgroups 1026
EMP (phl) 102
Scoring filter (9) 50
Scoring filter (8) 46
Scoring filter (10) 6
Scoring filter (18) 2
U2 violation - invalid distribution 2
Scoring filter (14) 2
TOTAL: 10 13169

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Including strange strings
newsfeed.fu-berlin.de 93
newsfeed.neva.ru 34
feeder3.feed-ams.uzoreto.com 16
ripco-out.feed.uzoreto.com 4
TOTAL: 4 147
No colon-space in header
feeder3.feed-ams.uzoreto.com 3
ripco-out.feed.uzoreto.com 1
TOTAL: 2 4
TOTAL: 3 169

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1rahul.net 17932 150 10918 0 0 0 0%47:40:04
2news.lightlink.com 11500 82 6170 16 0 2 0%47:45:24
3fu-berlin.de 23550 48 23502 0 0 0 0%47:48:38
4news.snarked.org 35582 30 23154 8 0 186 0%47:34:42
5news.cmpublishers.com 31222 14 25366 106 0 20 0%47:40:04
6feeder.erje.net 23876 14 23816 2 0 0 0%47:34:40
7newsfeed.neva.ru 21002 8 20882 8 0 0 0%47:40:04
8news.uzoreto.com 8708 8 8668 6 0 0 0%47:47:32
9remote6hme0.ripco.com 0 0 0 0 0 6 0%47:40:04
TOTAL: 9 173372 354 142476 146 0 214 0%429:11:12
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net1.0 MB0.0 KB1.0 MB0.0 KB/s7.0 KB47:40:04
2fu-berlin.de528.9 KB0.0 KB528.9 KB0.0 KB/s11.0 KB47:48:38
3news.snarked.org270.9 KB12.2 KB283.2 KB0.0 KB/s7.5 KB47:34:42
4news.lightlink.com245.9 KB188.7 KB434.6 KB0.0 KB/s4.4 KB47:45:24
5newsfeed.neva.ru139.2 KB108.9 KB248.1 KB0.0 KB/s15.5 KB47:40:04
6feeder.erje.net48.6 KB2.4 KB51.0 KB0.0 KB/s3.2 KB47:34:40
7news.cmpublishers.com38.5 KB211.4 KB249.8 KB0.0 KB/s2.1 KB47:40:04
8news.uzoreto.com32.1 KB16.6 KB48.6 KB0.0 KB/s3.5 KB47:47:32
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:04
TOTAL: 92.3 MB540.1 KB2.8 MB0.0 KB/s5.8 KB429:11:12
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 8762.6 MB 424 2 000:07:19
2shell3 4 3621.2 MB 22 0 001:08:23
TOTAL: 2 290 12383.8 MB 446 2 001:16:05

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 8762.6 MB 424 2 000:07:19
2? 4 3621.2 MB 22 0 001:08:23
TOTAL: 2 290 12383.8 MB 446 2 001:15:43

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 1.728 1.200 0.00001:08:23
ftupet.com 45.890 7.398 0.00000:07:19
80.82.65.74 6.018 1.176 0.00000:00:14
124-169-29-35.dyn.iinet.net.au 0.410 0.086 0.00000:00:04
scan-141.security.ipip.net 0.200 0.038 0.00000:00:02
159.203.201.125 0.200 0.038 0.00000:00:00
TOTAL: 6 54.446 9.936 0.00001:16:05

NNRP no permission clients:

SystemConn
80.82.65.74 60
124-169-29-35.dyn.iinet.net.au 4
scan-141.security.ipip.net 2
159.203.201.125 2
TOTAL: 4 68

Newsgroup request counts (by category):

CategoryCountPct
1rec 1168 94.3%
2comp 38 3.1%
3alt 10 0.8%
4misc 6 0.5%
5news 6 0.5%
6chi 4 0.3%
7sci 4 0.3%
8ba 2 0.2%
TOTAL: 8 1238100.0%

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

NewsgroupCount
1rec.arts.tv 834
2rec.food.cooking 204
3rec.bicycles.tech 98
4comp.sys.mac.system 34
5rec.gardens.edible 20
6alt.free.newsservers 6
7misc.consumers.frugal-living 6
8rec.food.drink.tea 4
9rec.radio.broadcasting 4
10comp.unix.bsd.freebsd.misc 4
11rec.radio.swap 4
12sci.electronics.repair 4
13chi.weather 4
14alt.satellite.gps.garmin 4
15news.admin.net-abuse.misc 2
16news.software.readers 2
17ba.food 2
18news.software.nntp 2
TOTAL: 18 1238