Daily Usenet report for news.ripco.com

Oct 11 00:01:04 -- Oct 11 23:57:53

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Oct 11 05:36:11 remote5bge0 overchan[717]: [ID 702911 news.error] input line too long, skipping
Oct 11 10:14:33 remote5bge0 overchan[717]: [ID 702911 news.error] input line too long, skipping
Oct 11 10:19:40 remote5bge0 overchan[717]: [ID 702911 news.error] input line too long, skipping
Oct 11 10:26:01 remote5bge0 overchan[717]: [ID 702911 news.error] input line too long, skipping
Oct 11 14:03:15 remote5bge0 overchan[717]: [ID 702911 news.error] input line too long, skipping
Oct 11 14:08:01 remote5bge0 overchan[717]: [ID 702911 news.error] input line too long, skipping
Oct 11 18:24:49 remote5bge0 overchan[717]: [ID 702911 news.error] input line too long, skipping
Oct 11 18:40:50 remote5bge0 overchan[717]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 87590 66.7%11.3 MB 60.4%
inn 22630 17.2%4.0 MB 21.3%
innd 17541 13.4%2.7 MB 14.4%
innfeed 3602 2.7%757.0 KB 4.0%
overchan 8 0.0%1.0 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 131373 100.0%18.7 MB100.0%

History cache:

ReasonCount%Count
Cache misses 197028 51.3%
Do not exist 186840 48.7%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 383868 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%134 0.000 0.000 0.000
article cleanup00:00:00.518 0.0%44890 0.000 0.012 0.041
article logging00:00:00.800 0.0%45350 0.000 0.018 0.217
article parse00:00:01.698 0.0%108254 0.000 0.016 0.046
article write00:00:55.858 0.0%34844 0.484 1.603 40.311
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.370 0.0%392454 0.000 0.001 0.006
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:04.172 0.0%386448 0.001 0.011 0.888
history sync00:00:00.378 0.0%800 0.000 0.472 3.000
history write00:00:06.144 0.0%44886 0.000 0.137 6.627
hiswrite/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
idle47:56:40.070 99.8%456026 188.539 378.487 1111.883
nntp read00:00:02.568 0.0%426376 0.002 0.006 0.016
overview write00:00:00.278 0.0%34844 0.000 0.008 0.047
perl filter00:01:06.086 0.0%44886 1.066 1.472 11.581
python filter00:00:01.346 0.0%44894 0.000 0.030 0.189
site send00:00:02.940 0.0%104502 0.008 0.028 0.306
TOTAL: 48:01:56.84047:59:03.226 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.134 0.0%34888 0.000 0.004 0.021
article prepare00:00:00.004 0.0%14352 0.000 0.000 0.050
article read00:00:00.386 0.0%3008 0.000 0.128 73.000
backlog stats00:00:00.236 0.0%398136 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.098 0.0%270068 0.001 0.008 0.019
data write00:00:04.084 0.0%235648 0.005 0.017 0.038
idle47:53:06.942100.0%398136 238.605 432.985 1081.879
status file00:00:05.544 0.0%338 0.000 16.402 100.000
TOTAL: 47:53:32.70847:53:19.428100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:02.808 0.0%203202 0.000 0.014 0.215
idle107:56:10.850 99.2%264666 0.000 1468.156281469.481
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:46.966 0.0%2730332 0.000 0.017 1.800
readart00:00:19.856 0.0%3130 0.000 6.344 42.000
TOTAL: 108:47:08.084107:57:20.480 99.2%----

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 53684 16038 33126 4520 29%94:37:24
2news.snarked.org 50 41036 10164 28780 2092 24%53:16:40
3newsfeed.neva.ru 568 56330 7036 47430 1864 12%278:16:18
4newsfeed.fu-berlin.de 0 14168 750 13366 52 5%40:00:52
5news.lightlink.com 2 8180 206 7580 394 2%44:22:30
6news-in.rahul.net 574 18704 192 18510 2 1%47:21:44
7news.cmpublishers.com 52 9892 98 9776 18 0%47:57:18
8ripco-out.feeder.erje.net 574 36850 40 35842 968 0%47:17:32
9localhost 152 8 8 0 0 100%00:00:00
TOTAL: 9 3120 238852 34532 194410 9910 14%653:10:18
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com44.1 MB0.0 KB9.6 MB53.7 MB 82%2.7 KB
2news.snarked.org28.6 MB0.0 KB6.9 MB35.5 MB 80%3.0 KB
3newsfeed.neva.ru21.0 MB13.2 KB7.4 MB28.4 MB 73%3.3 KB
4news-in.rahul.net8.2 MB0.0 KB119.4 KB8.3 MB 98%43.7 KB
5news.lightlink.com5.1 MB0.0 KB16.4 MB21.5 MB 23%36.7 KB
6newsfeed.fu-berlin.de2.7 MB0.0 KB2.3 MB5.0 MB 53%6.4 KB
7news.cmpublishers.com225.6 KB0.0 KB27.3 KB252.9 KB 89%2.2 KB
8ripco-out.feeder.erje.net154.5 KB0.0 KB9.9 MB10.0 MB 1%10.2 KB
9localhost21.0 KB0.0 KB0.0 KB21.0 KB100%2.6 KB
TOTAL: 9110.0 MB13.2 KB52.7 MB162.7 MB 67%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 11 00:01:04 - 00:59:59 471 2.7% 0.131.5 MB 2.6% 0.42
Oct 11 01:00:00 - 01:59:59 503 2.9% 0.141.7 MB 3.0% 0.47
Oct 11 02:00:00 - 02:59:59 628 3.6% 0.171.7 MB 3.0% 0.48
Oct 11 03:00:00 - 03:59:59 703 4.0% 0.202.2 MB 4.0% 0.63
Oct 11 04:00:00 - 04:59:59 746 4.3% 0.212.4 MB 4.2% 0.67
Oct 11 05:00:00 - 05:59:59 812 4.7% 0.232.1 MB 3.9% 0.61
Oct 11 06:00:00 - 06:59:59 816 4.7% 0.232.9 MB 5.2% 0.83
Oct 11 07:00:00 - 07:59:59 907 5.2% 0.252.5 MB 4.4% 0.70
Oct 11 08:00:00 - 08:59:59 945 5.4% 0.262.9 MB 5.3% 0.83
Oct 11 09:00:00 - 09:59:59 1063 6.1% 0.302.6 MB 4.8% 0.75
Oct 11 10:00:00 - 10:59:59 1236 7.1% 0.344.0 MB 7.2% 1.14
Oct 11 11:00:00 - 11:59:59 912 5.2% 0.253.3 MB 5.9% 0.94
Oct 11 12:00:00 - 12:59:59 969 5.6% 0.272.8 MB 5.1% 0.81
Oct 11 13:00:00 - 13:59:59 933 5.4% 0.262.7 MB 4.9% 0.78
Oct 11 14:00:00 - 14:59:59 832 4.8% 0.232.7 MB 4.9% 0.77
Oct 11 15:00:00 - 15:59:59 964 5.5% 0.273.3 MB 5.9% 0.94
Oct 11 16:00:00 - 16:59:59 782 4.5% 0.222.4 MB 4.3% 0.68
Oct 11 17:00:00 - 17:59:59 743 4.3% 0.212.3 MB 4.2% 0.66
Oct 11 18:00:00 - 18:59:59 542 3.1% 0.152.4 MB 4.3% 0.68
Oct 11 19:00:00 - 19:59:59 379 2.2% 0.111.5 MB 2.6% 0.41
Oct 11 20:00:00 - 20:59:59 424 2.4% 0.121.5 MB 2.7% 0.43
Oct 11 21:00:00 - 21:59:59 391 2.2% 0.111.2 MB 2.1% 0.34
Oct 11 22:00:00 - 22:59:59 324 1.9% 0.091.3 MB 2.3% 0.36
Oct 11 23:00:00 - 23:57:53 371 2.1% 0.111.7 MB 3.0% 0.49
TOTAL: 23:56:49 17396 100.0% 0.2055.5 MB 100.0% 0.66
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2273 1633 0 0 7 0 0 0 633
2news.snarked.org 1094 70 0 0 2 0 0 0 1022
3newsfeed.neva.ru 975 550 0 2 2 0 0 0 421
4ripco-out.feeder.erje.net 484 483 0 0 0 0 0 0 1
5news.lightlink.com 202 0 0 0 0 0 0 0 202
6newsfeed.fu-berlin.de 196 0 0 0 0 0 0 0 196
7news.cmpublishers.com 9 0 0 0 0 0 0 0 9
8news-in.rahul.net 1 0 0 0 0 0 0 0 1
TOTAL: 8 5234 2736 0 2 11 0 0 0 2485

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1803
alt.binaries.ftd 297
fido7.su.pol.news 52
it.comp.os.win.windows10 38
uk.net.news.moderation 37
perl.cpan.uploads 36
it.tlc.cellulari.android 32
alt.binaries.e-book 17
mozilla.dev.security.policy 16
mi.jobs 16
mozilla.dev.platform 15
alt.bestjobsusa.computer.jobs 13
muc.lists.freebsd.fs 12
alt.binaries.nl 12
alt.binaries.pictures.aviation 12
free.ucp 12
gmane.linux.alsa.devel 9
alt.jobs.jobsearch 9
alt.binaries.test.yenc 9
alt.music.pink-floyd.publius 9
TOTAL: 142 2736

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

GroupsCount
rec.travel.resorts.all-inclusive 3
pl.praca.oferowana 2
fr.comp.securite 2
bit.listserv.catholic 2
alt.christnet.religion 1
alt.penthouse.sex.sissy.slut 1
TOTAL: 6 11

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3044
Too many newsgroups 868
Binary in non-binary group 338
EMP (phl) 96
Attached HTML file 48
Scoring filter (9) 46
Scoring filter (8) 38
Scoring filter (16) 14
Scoring filter (10) 14
HTML post 8
Scoring filter (18) 4
UUencoded txt 2
TOTAL: 12 4520

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Huge articles
newsfeed.fu-berlin.de 2
TOTAL: 1 2
Including strange strings
newsfeed.fu-berlin.de 168
newsfeed.neva.ru 44
ripco-out.feed.uzoreto.com 13
news.snarked.org 3
TOTAL: 4 228
TOTAL: 3 248

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 9518 1762 5546 10 0 0 18%47:40:10
2rahul.net 26658 1084 15442 34 0 2 4%47:40:10
3news.snarked.org 39510 90 24212 6 0 100 0%47:49:42
4feeder.erje.net 33916 22 32766 72 0 0 0%47:40:10
5fu-berlin.de 31630 20 31610 0 0 0 0%47:52:30
6news.cmpublishers.com 44468 16 34022 332 0 4 0%47:48:12
7newsfeed.neva.ru 28174 12 27530 80 0 0 0%47:40:08
8news.uzoreto.com 18876 10 18304 26 0 0 0%47:35:26
9remote6hme0.ripco.com 0 0 0 0 0 8 0%47:40:08
TOTAL: 9 232750 3016 189432 560 0 114 1%429:26:36
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com5.1 MB14.4 KB5.1 MB0.0 KB/s2.9 KB47:40:10
2rahul.net4.2 MB243.8 KB4.5 MB0.0 KB/s4.1 KB47:40:10
3news.snarked.org304.7 KB12.4 KB317.1 KB0.0 KB/s3.3 KB47:49:42
4feeder.erje.net112.4 KB288.5 KB400.9 KB0.0 KB/s4.3 KB47:40:10
5fu-berlin.de105.1 KB0.0 KB105.1 KB0.0 KB/s5.3 KB47:52:30
6newsfeed.neva.ru75.9 KB143.3 KB219.2 KB0.0 KB/s2.4 KB47:40:08
7news.cmpublishers.com69.8 KB1.0 MB1.1 MB0.0 KB/s3.2 KB47:48:12
8news.uzoreto.com50.8 KB102.4 KB153.2 KB0.0 KB/s4.3 KB47:35:26
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:08
TOTAL: 910.0 MB1.8 MB11.8 MB0.0 KB/s3.4 KB429:26:36
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 14104.0 MB 504 6 000:07:03
2shell3 32 300963.6 KB 40 2 001:25:50
375-151-74-61-independence.hfc.comcastbusiness.net 8 42.6 KB 4 0 000:13:46
TOTAL: 3 326 17144.9 MB 548 8 0108:42:59

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 14104.0 MB 504 6 000:07:03
2? 32 300963.6 KB 40 2 001:25:51
3*.hfc.comcastbusiness.net 8 42.6 KB 4 0 000:13:46
4*.fbx.proxad.net 16 00.0 KB 0 0 000:24:33
5*.7-3.cable.virginm.net 14 00.0 KB 0 0 007:40:57
6unresolved13680 00.0 KB 0 0 098:50:47
TOTAL: 614036 17144.9 MB 548 8 0108:42:59

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.11.163.217 0.856 0.296 0.00097:23:09
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 4.490 6.444 0.00007:40:57
185.149.90.76 1371.990 299.380 0.00001:27:37
shell3 14.744 5.594 0.00001:25:50
vvs78-1-88-173-43-113.fbx.proxad.net 1.590 0.354 0.00000:24:33
75-151-74-61-independence.hfc.comcastbusiness.net 1.556 1.292 0.00000:13:46
ftupet.com 46.786 7.418 0.00000:07:03
? 0.232 0.054 0.00000:00:00
TOTAL: 8 1442.244 320.832 0.000108:42:59

Curious NNRP server explorers [Top 20]:

SystemConn
185.149.90.76 13672
vvs78-1-88-173-43-113.fbx.proxad.net 16
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 14
66.11.163.217 8
TOTAL: 4 13710

NNRP unrecognized commands (by host):

SystemConn
shell3 6
TOTAL: 1 6

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 6
TOTAL: 1 6

NNRP client timeouts [Top 20]:

SystemConnPeer
66.11.163.217 8 0
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 4 0
TOTAL: 2 12 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1600 93.3%
2comp 40 2.3%
3sci 26 1.5%
4alt 26 1.5%
5chi 16 0.9%
6news 4 0.2%
7ba 2 0.1%
TOTAL: 7 1714100.0%

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

NewsgroupCount
1rec.arts.tv 804
2rec.food.cooking 630
3rec.bicycles.tech 142
4sci.electronics.repair 26
5alt.stupidity 12
6comp.sys.mac.system 12
7rec.radio.swap 8
8comp.unix.solaris 8
9rec.music.makers.guitar.acoustic 8
10alt.games.warcraft 8
11chi.weather 8
12comp.security.ssh 6
13chi.general 6
14rec.video.cable-tv 4
15rec.radio.broadcasting 4
16news.admin.net-abuse.email 4
17comp.protocols.dns.bind 4
18comp.sys.sun.hardware 4
19comp.risks 2
20comp.sys.mac.apps 2
21comp.dcom.telecom 2
22chi.jobs 2
23alt.2eggs.sausage.beans.tomatoes.2toast.largetea.cheerslove 2
24alt.video.tape-trading 2
25alt.building.architecture 2
26ba.food 2
TOTAL: 26 1714