Daily Usenet report for news.ripco.com

Oct 4 00:01:03 -- Oct 4 23:57:53

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Oct 4 02:15:32 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 03:27:52 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 05:21:52 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 06:28:29 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 07:43:51 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 08:03:55 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 08:53:38 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 11:43:27 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 12:04:55 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 13:52:51 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping
Oct 4 19:12:15 remote5bge0 overchan[26478]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 34164 41.5%4.2 MB 34.4%
inn 24827 30.2%4.3 MB 34.9%
innd 19629 23.9%3.0 MB 24.6%
innfeed 3598 4.4%765.9 KB 6.1%
overchan 11 0.0%1.6 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 82231 100.0%12.2 MB100.0%

History cache:

ReasonCount%Count
Cache misses 216612 52.1%
Do not exist 198814 47.9%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 415426 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%120 0.000 0.000 0.000
article cleanup00:00:00.498 0.0%49338 0.000 0.010 0.043
article logging00:00:00.818 0.0%49814 0.000 0.016 0.198
article parse00:00:01.846 0.0%116772 0.000 0.016 0.043
article write00:00:42.542 0.0%35754 0.461 1.190 14.263
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.288 0.0%424010 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.370 0.0%417622 0.002 0.008 0.015
history sync00:00:00.246 0.0%784 0.000 0.314 1.667
history write00:00:04.624 0.0%49316 0.000 0.094 1.814
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:59:07.292 99.9%462708 211.121 373.340 1192.060
nntp read00:00:02.714 0.0%460960 0.002 0.006 0.019
overview write00:00:00.252 0.0%35754 0.000 0.007 0.029
perl filter00:01:03.644 0.0%49312 1.004 1.291 2.174
python filter00:00:01.164 0.0%49320 0.000 0.024 0.063
site send00:00:02.832 0.0%107192 0.006 0.026 0.057
TOTAL: 48:01:55.09648:01:12.130100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.106 0.0%35660 0.000 0.003 0.023
article prepare00:00:00.006 0.0%13392 0.000 0.000 0.042
article read00:00:00.432 0.0%2848 0.000 0.152 36.000
backlog stats00:00:00.166 0.0%408852 0.000 0.000 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.522 0.0%276174 0.000 0.006 0.015
data write00:00:03.696 0.0%241040 0.007 0.015 0.032
idle47:52:14.290100.0%408852 265.557 421.508 1242.624
status file00:00:10.102 0.0%332 5.000 30.428 199.286
TOTAL: 47:52:40.64447:52:30.320100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:01.162 0.0%71296 0.000 0.016 0.500
idle19:24:19.420 99.8%106488 8.333 656.031 33176.353
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:06.834 0.0%802186 0.000 0.009 0.032
readart00:00:19.814 0.0%2566 0.000 7.722 36.000
TOTAL: 19:26:52.76219:24:47.230 99.8%----

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 56948 15596 35890 5462 27%94:38:48
2news.snarked.org 64 46872 11106 31006 4760 23%59:01:46
3newsfeed.neva.ru 574 64484 7552 54960 1972 11%279:57:30
4newsfeed.fu-berlin.de 4 18140 938 17090 112 5%51:42:14
5news.lightlink.com 2 10166 380 9610 176 3%44:22:30
6news-in.rahul.net 574 21338 108 21230 0 0%47:19:36
7news.cmpublishers.com 52 10352 88 10250 14 0%48:00:02
8ripco-out.feeder.erje.net 574 38996 76 37808 1112 0%47:17:06
9localhost 152 8 8 0 0 100%00:00:00
TOTAL: 9 3144 267304 35852 217844 13608 13%672:19:32
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com42.0 MB0.0 KB12.6 MB54.6 MB 76%2.7 KB
2news.snarked.org32.8 MB0.0 KB15.7 MB48.5 MB 67%3.1 KB
3newsfeed.neva.ru23.3 MB54.4 KB6.9 MB30.2 MB 77%3.3 KB
4news.lightlink.com13.4 MB0.0 KB5.8 MB19.3 MB 69%35.5 KB
5newsfeed.fu-berlin.de4.6 MB0.0 KB549.9 KB5.2 MB 89%5.0 KB
6news-in.rahul.net4.3 MB0.0 KB0.0 KB4.3 MB100%41.0 KB
7news.cmpublishers.com214.6 KB0.0 KB30.7 KB245.3 KB 87%2.4 KB
8ripco-out.feeder.erje.net152.3 KB0.0 KB11.8 MB11.9 MB 1%10.3 KB
9localhost20.3 KB0.0 KB0.0 KB20.3 KB100%2.5 KB
TOTAL: 9120.9 MB54.4 KB53.3 MB174.3 MB 69%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 4 00:01:03 - 00:59:59 465 2.6% 0.131.7 MB 2.8% 0.49
Oct 4 01:00:00 - 01:59:59 491 2.8% 0.141.7 MB 2.8% 0.49
Oct 4 02:00:00 - 02:59:59 686 3.9% 0.192.0 MB 3.3% 0.57
Oct 4 03:00:00 - 03:59:59 777 4.4% 0.222.3 MB 3.7% 0.64
Oct 4 04:00:00 - 04:59:59 863 4.8% 0.242.5 MB 4.2% 0.72
Oct 4 05:00:00 - 05:59:59 885 5.0% 0.252.4 MB 3.9% 0.67
Oct 4 06:00:00 - 06:59:59 840 4.7% 0.233.2 MB 5.2% 0.90
Oct 4 07:00:00 - 07:59:59 895 5.0% 0.252.3 MB 3.8% 0.65
Oct 4 08:00:00 - 08:59:59 989 5.6% 0.273.7 MB 6.2% 1.07
Oct 4 09:00:00 - 09:59:59 1016 5.7% 0.282.6 MB 4.2% 0.73
Oct 4 10:00:00 - 10:59:59 1052 5.9% 0.294.4 MB 7.2% 1.25
Oct 4 11:00:00 - 11:59:59 1072 6.0% 0.304.5 MB 7.4% 1.27
Oct 4 12:00:00 - 12:59:59 982 5.5% 0.272.9 MB 4.9% 0.84
Oct 4 13:00:00 - 13:59:59 968 5.4% 0.273.1 MB 5.1% 0.88
Oct 4 14:00:00 - 14:59:59 896 5.0% 0.252.7 MB 4.5% 0.78
Oct 4 15:00:00 - 15:59:59 875 4.9% 0.242.9 MB 4.8% 0.83
Oct 4 16:00:00 - 16:59:59 1001 5.6% 0.283.0 MB 5.0% 0.86
Oct 4 17:00:00 - 17:59:59 596 3.3% 0.172.9 MB 4.8% 0.82
Oct 4 18:00:00 - 18:59:59 548 3.1% 0.151.8 MB 3.0% 0.51
Oct 4 19:00:00 - 19:59:59 467 2.6% 0.131.4 MB 2.3% 0.39
Oct 4 20:00:00 - 20:59:59 455 2.6% 0.132.1 MB 3.4% 0.59
Oct 4 21:00:00 - 21:59:59 329 1.8% 0.091.7 MB 2.9% 0.49
Oct 4 22:00:00 - 22:59:59 291 1.6% 0.081.1 MB 1.8% 0.31
Oct 4 23:00:00 - 23:57:53 370 2.1% 0.111.7 MB 2.8% 0.51
TOTAL: 23:56:50 17809 100.0% 0.2160.5 MB 100.0% 0.72
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2762 2161 0 0 10 0 0 0 591
2news.snarked.org 2364 109 0 0 5 0 0 0 2250
3newsfeed.neva.ru 1039 678 0 6 9 0 0 0 346
4ripco-out.feeder.erje.net 556 552 0 0 0 0 0 0 4
5newsfeed.fu-berlin.de 199 0 0 0 0 0 0 0 199
6news.lightlink.com 92 8 0 0 0 0 0 0 84
7news.cmpublishers.com 6 0 0 0 0 0 0 0 6
TOTAL: 7 7018 3508 0 6 24 0 0 0 3480

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2354
alt.binaries.ftd 374
perl.cpan.uploads 72
it.comp.os.win.windows10 55
fido7.su.pol.news 46
alt.bestjobsusa.computer.jobs 40
alt.astrology.pro 36
gmane.linux.alsa.devel 29
att.jobs 24
alt.my.job.sucks 22
free.it.scienza.fisica 22
alt.jobs.jobsearch 21
at.jobs 17
aus.jobs 16
alt.binaries.e-book 16
alt.binaries.pictures.earlmiller 15
alt.binaries.pictures.rail 15
alt.binaries.nl 12
free.ucp 12
it.tlc.cellulari.android 11
TOTAL: 137 3508

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

GroupsCount
pl.praca.oferowana 12
fr.comp.securite 5
alt.penthouse.sex.voyeurism 2
bit.listserv.catholic 2
alt.christnet.religion 1
alt.penthouse.sex.sissy.slut 1
alt.christnet.christnews 1
TOTAL: 7 24

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4582
Too many newsgroups 1528
Binary in non-binary group 148
EMP (phl) 110
Scoring filter (8) 40
Attached HTML file 16
Scoring filter (9) 16
Scoring filter (16) 10
Scoring filter (10) 10
Scoring filter (18) 6
Scoring filter (12) 4
Scoring filter (17) 2
HTML post 2
TOTAL: 13 6474

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 20
ripco-out.feed.uzoreto.com 4
TOTAL: 2 24
Including strange strings
newsfeed.fu-berlin.de 148
newsfeed.neva.ru 56
ripco-out.feed.uzoreto.com 31
news.snarked.org 1
TOTAL: 4 236
TOTAL: 2 260

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 10956 1742 6032 12 0 0 15%47:50:48
2rahul.net 26478 762 15586 20 0 0 2%47:40:02
3news.snarked.org 40552 50 24334 0 0 82 0%47:53:00
4fu-berlin.de 32234 36 32198 0 0 0 0%47:40:02
5feeder.erje.net 33284 32 32748 82 0 0 0%47:35:32
6news.cmpublishers.com 45916 24 34628 416 0 18 0%47:40:02
7news.uzoreto.com 20520 18 19464 18 0 0 0%47:40:02
8newsfeed.neva.ru 28414 14 27778 114 0 0 0%47:40:02
9remote6hme0.ripco.com 0 0 0 0 0 8 0%47:40:02
TOTAL: 9 238354 2678 192768 662 0 108 1%429:19:32
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com5.2 MB32.2 KB5.2 MB0.0 KB/s3.1 KB47:50:48
2rahul.net3.0 MB41.0 KB3.0 MB0.0 KB/s4.0 KB47:40:02
3fu-berlin.de257.8 KB0.0 KB257.8 KB0.0 KB/s7.2 KB47:40:02
4news.snarked.org143.7 KB0.0 KB143.7 KB0.0 KB/s2.9 KB47:53:00
5feeder.erje.net90.9 KB361.7 KB452.6 KB0.0 KB/s4.0 KB47:35:32
6news.cmpublishers.com64.3 KB1.3 MB1.3 MB0.0 KB/s3.1 KB47:40:02
7news.uzoreto.com55.0 KB69.5 KB124.5 KB0.0 KB/s3.5 KB47:40:02
8newsfeed.neva.ru28.4 KB297.9 KB326.4 KB0.0 KB/s2.5 KB47:40:02
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
TOTAL: 98.8 MB2.0 MB10.9 MB0.0 KB/s3.3 KB429:19:32
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 11763.3 MB 508 6 000:07:03
2shell3 20 204498.7 KB 28 2 000:56:53
375-151-74-61-independence.hfc.comcastbusiness.net 8 45.2 KB 4 0 000:14:33
TOTAL: 3 314 13843.8 MB 540 8 019:26:42

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 11763.3 MB 508 6 000:07:03
2? 20 204498.7 KB 28 2 000:56:53
3*.hfc.comcastbusiness.net 8 45.2 KB 4 0 000:14:33
4*.fbx.proxad.net 16 00.0 KB 0 0 005:00:56
5*.7-3.cable.virginm.net 2 00.0 KB 0 0 000:10:16
6unresolved 230 00.0 KB 0 0 012:29:20
7*.cpe.metrocast.net 16 00.0 KB 0 0 000:27:38
TOTAL: 7 578 13843.8 MB 540 8 019:26:42

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
108.62.56.66 22.972 5.484 0.00009:43:30
vvs78-1-88-173-43-113.fbx.proxad.net 1.656 0.408 0.00005:00:56
66.11.163.217 1.362 1.456 0.00002:45:49
shell3 3.832 1.814 0.00000:56:53
d-72-9-26-236.cpe.metrocast.net 1.768 0.682 0.00000:27:38
75-151-74-61-independence.hfc.comcastbusiness.net 1.670 1.322 0.00000:14:33
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 0.254 0.156 0.00000:10:16
ftupet.com 47.290 7.410 0.00000:07:03
TOTAL: 8 80.804 18.732 0.00019:26:42

Curious NNRP server explorers [Top 20]:

SystemConn
108.62.56.66 224
d-72-9-26-236.cpe.metrocast.net 16
vvs78-1-88-173-43-113.fbx.proxad.net 16
66.11.163.217 6
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 2
TOTAL: 5 264

NNRP unrecognized commands (by host):

SystemConn
shell3 8
TOTAL: 1 8

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 8
TOTAL: 1 8

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 1266 91.5%
2comp 70 5.1%
3chi 20 1.4%
4alt 16 1.2%
5sci 6 0.4%
6ba 2 0.1%
7pl 2 0.1%
8news 2 0.1%
TOTAL: 8 1384100.0%

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

NewsgroupCount
1rec.arts.tv 594
2rec.food.cooking 576
3rec.bicycles.tech 86
4comp.sys.mac.system 36
5comp.dcom.telecom 16
6comp.sys.mac.apps 14
7chi.general 14
8alt.stupidity 12
9rec.radio.broadcasting 6
10sci.electronics.repair 6
11rec.radio.swap 4
12chi.weather 4
13alt.fan.sailor-moon 2
14chi.forsale 2
15alt.vacation.las-vegas 2
16comp.unix.solaris 2
17pl.comp.sys.amiga 2
18news.admin.net-abuse.email 2
19ba.general 2
20comp.protocols.dns.bind 2
TOTAL: 20 1384