Daily Usenet report for news.ripco.com

Oct 12 00:01:03 -- Oct 12 23:57:45

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Oct 12 02:12:07 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 02:12:33 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 09:16:38 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 09:53:19 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 10:28:41 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 11:47:36 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 12:49:49 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 12:52:46 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 13:09:31 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 18:55:06 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping
Oct 12 22:03:25 remote5bge0 overchan[10815]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 33858 43.1%4.2 MB 36.1%
inn 22028 28.0%3.8 MB 32.6%
innd 19039 24.2%2.9 MB 24.8%
innfeed 3618 4.6%782.7 KB 6.6%
overchan 11 0.0%1.4 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 78556 100.0%11.7 MB100.0%

History cache:

ReasonCount%Count
Cache misses 197562 52.6%
Do not exist 178388 47.4%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 375950 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%248 0.000 0.000 0.000
article cleanup00:00:00.472 0.0%43742 0.000 0.011 0.052
article logging00:00:00.728 0.0%44156 0.000 0.016 0.250
article parse00:00:01.640 0.0%101902 0.000 0.016 0.044
article write00:00:50.818 0.0%32384 0.479 1.569 59.398
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.278 0.0%379384 0.000 0.001 0.004
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.782 0.0%378344 0.000 0.013 0.838
history sync00:00:00.360 0.0%798 0.000 0.451 7.250
history write00:00:05.836 0.0%43738 0.019 0.133 6.493
hiswrite/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
idle47:59:25.436 99.9%416326 222.949 414.976 1257.017
nntp read00:00:02.232 0.0%413868 0.001 0.005 0.013
overview write00:00:00.206 0.0%32384 0.000 0.006 0.034
perl filter00:00:57.068 0.0%43736 1.046 1.305 2.375
python filter00:00:01.106 0.0%43742 0.000 0.025 0.071
site send00:00:01.898 0.0%97094 0.000 0.020 0.038
TOTAL: 48:02:10.09248:01:32.860100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.448 0.0%32930 0.000 0.014 0.045
article prepare00:00:00.008 0.0%14216 0.000 0.001 0.036
article read00:00:00.416 0.0%3042 0.000 0.137 12.167
backlog stats00:00:00.186 0.0%369004 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:03.016 0.0%249302 0.004 0.012 0.024
data write00:00:03.108 0.0%217682 0.004 0.014 0.028
idle47:36:28.998100.0%369004 244.991 464.464 1381.078
status file00:00:06.786 0.0%352 1.000 19.278 104.000
TOTAL: 47:36:54.16647:36:42.966100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:13.668 0.0%994762 0.000 0.014 0.500
idle50:56:40.198 98.4%1031720 0.000 177.762300063.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:01:40.740 0.1%3816544 0.000 0.026 2.111
readart00:00:21.298 0.0%3172 0.000 6.714 38.000
TOTAL: 51:45:40.48650:58:55.904 98.5%----

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 52536 14644 32930 4962 27%94:37:10
2news.snarked.org 66 41724 10218 27840 3666 24%58:30:40
3newsfeed.neva.ru 570 58792 6324 50722 1746 10%281:44:38
4newsfeed.fu-berlin.de 4 9686 482 9178 26 4%33:57:30
5news-in.rahul.net 574 19374 182 19156 36 0%47:19:14
6news.lightlink.com 2 8896 146 8616 134 1%44:22:30
7ripco-out.feeder.erje.net 574 35272 80 34356 836 0%47:17:08
8news.cmpublishers.com 50 9016 56 8950 10 0%48:00:06
9localhost 150 6 6 0 0 100%00:00:00
TOTAL: 9 3138 235302 32138 191748 11416 13%655:48:56
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com38.7 MB0.0 KB11.1 MB49.8 MB 77%2.6 KB
2news.snarked.org29.9 MB0.0 KB11.4 MB41.3 MB 72%3.0 KB
3newsfeed.neva.ru18.6 MB13.0 KB5.9 MB24.5 MB 75%3.1 KB
4news-in.rahul.net7.5 MB0.0 KB2.8 MB10.3 MB 72%48.3 KB
5news.lightlink.com2.8 MB0.0 KB5.1 MB7.9 MB 35%29.0 KB
6newsfeed.fu-berlin.de2.2 MB0.0 KB1.4 MB3.7 MB 61%7.4 KB
7ripco-out.feeder.erje.net175.7 KB0.0 KB7.9 MB8.1 MB 2%9.1 KB
8news.cmpublishers.com165.4 KB0.0 KB18.1 KB183.5 KB 90%2.8 KB
9localhost20.1 KB0.0 KB0.0 KB20.1 KB100%3.3 KB
TOTAL: 9100.0 MB13.0 KB45.6 MB145.6 MB 68%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 12 00:01:03 - 00:59:59 534 3.3% 0.151.4 MB 2.7% 0.40
Oct 12 01:00:00 - 01:59:59 532 3.3% 0.151.9 MB 3.7% 0.53
Oct 12 02:00:00 - 02:59:59 613 3.8% 0.171.7 MB 3.3% 0.47
Oct 12 03:00:00 - 03:59:59 677 4.2% 0.192.1 MB 4.1% 0.59
Oct 12 04:00:00 - 04:59:59 831 5.1% 0.232.3 MB 4.6% 0.66
Oct 12 05:00:00 - 05:59:59 817 5.1% 0.232.6 MB 5.1% 0.73
Oct 12 06:00:00 - 06:59:59 738 4.6% 0.202.3 MB 4.6% 0.66
Oct 12 07:00:00 - 07:59:59 701 4.3% 0.192.0 MB 3.9% 0.56
Oct 12 08:00:00 - 08:59:59 811 5.0% 0.232.7 MB 5.4% 0.78
Oct 12 09:00:00 - 09:59:59 963 6.0% 0.272.4 MB 4.7% 0.68
Oct 12 10:00:00 - 10:59:59 878 5.4% 0.242.8 MB 5.6% 0.81
Oct 12 11:00:00 - 11:59:59 966 6.0% 0.273.0 MB 5.9% 0.85
Oct 12 12:00:00 - 12:59:59 829 5.1% 0.232.4 MB 4.7% 0.68
Oct 12 13:00:00 - 13:59:59 890 5.5% 0.253.3 MB 6.5% 0.94
Oct 12 14:00:00 - 14:59:59 907 5.6% 0.252.7 MB 5.4% 0.78
Oct 12 15:00:00 - 15:59:59 776 4.8% 0.222.6 MB 5.0% 0.73
Oct 12 16:00:00 - 16:59:59 716 4.4% 0.202.5 MB 5.0% 0.72
Oct 12 17:00:00 - 17:59:59 632 3.9% 0.182.1 MB 4.1% 0.60
Oct 12 18:00:00 - 18:59:59 470 2.9% 0.131.3 MB 2.6% 0.37
Oct 12 19:00:00 - 19:59:59 448 2.8% 0.121.4 MB 2.8% 0.41
Oct 12 20:00:00 - 20:59:59 340 2.1% 0.091.3 MB 2.6% 0.37
Oct 12 21:00:00 - 21:59:59 414 2.6% 0.121.4 MB 2.8% 0.41
Oct 12 22:00:00 - 22:59:59 389 2.4% 0.111.4 MB 2.8% 0.41
Oct 12 23:00:00 - 23:57:45 280 1.7% 0.081023.4 KB 2.0% 0.30
TOTAL: 23:56:42 16152 100.0% 0.1950.6 MB 100.0% 0.60
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2493 1700 0 0 1 0 0 0 792
2news.snarked.org 1792 88 0 0 7 0 0 0 1697
3newsfeed.neva.ru 919 499 0 2 2 0 0 0 416
4ripco-out.feeder.erje.net 419 412 0 0 0 0 0 0 7
5newsfeed.fu-berlin.de 170 0 0 0 0 0 0 0 170
6news.lightlink.com 60 7 0 0 0 0 0 0 53
7news-in.rahul.net 18 0 0 0 0 0 0 0 18
8news.cmpublishers.com 5 2 0 0 0 0 0 0 3
TOTAL: 8 5876 2708 0 2 10 0 0 0 3156

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1860
alt.binaries.ftd 239
uk.net.news.moderation 44
gmane.linux.alsa.devel 40
fido7.su.pol.news 35
alt.binaries.pictures.erotica.vintage 23
linux.debian.maint.python 22
perl.cpan.uploads 20
it.tlc.cellulari.android 18
mozilla.dev.security.policy 14
it.comp.os.win.windows10 14
alt.binaries.nl 13
free.willey 12
free.ucp 12
alt.binaries.drwho 11
alt.slack.goathead 11
gmane.comp.gis.openstreetmap.tagging 10
alt.binaries.sounds.karaoke 9
it.comp.os.win.windows7 8
alt.binaries.test.yenc 8
TOTAL: 157 2708

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

GroupsCount
pl.praca.oferowana 2
christnet.bible 2
alt.penthouse.sex.masterbation 2
christnet.christianlife 1
christnet.theology 1
alt.penthouse.sex.sissy.slut 1
bit.listserv.catholic 1
TOTAL: 7 10

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3886
Too many newsgroups 1510
Binary in non-binary group 206
EMP (phl) 178
Scoring filter (16) 36
Scoring filter (8) 32
Scoring filter (9) 14
Attached HTML file 12
Scoring filter (11) 10
Scoring filter (18) 4
HTML post 4
Scoring filter (12) 2
Scoring filter (10) 2
TOTAL: 13 5896

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feed.uzoreto.com 1
TOTAL: 1 1
Bad Message-ID's offered
ripco-out.feeder.erje.net 20
ripco-out.feed.uzoreto.com 4
TOTAL: 2 24
Huge articles
newsfeed.fu-berlin.de 2
TOTAL: 1 2
Including strange strings
newsfeed.fu-berlin.de 147
newsfeed.neva.ru 46
ripco-out.feed.uzoreto.com 10
news.snarked.org 2
ripco-out.feeder.erje.net 2
TOTAL: 5 207
TOTAL: 4 234

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 9714 1778 5524 18 0 34 18%47:40:04
2rahul.net 24316 1126 14316 42 0 64 4%47:39:02
3news.snarked.org 35792 56 21936 0 0 334 0%47:37:26
4feeder.erje.net 29964 42 28998 52 0 62 0%47:36:50
5fu-berlin.de 29658 28 29630 0 0 48 0%47:34:12
6news.uzoreto.com 18154 26 17190 30 0 0 0%47:35:36
7news.cmpublishers.com 41492 12 31618 278 0 138 0%47:37:16
8newsfeed.neva.ru 26462 6 25730 60 0 62 0%47:40:06
9remote6hme0.ripco.com 0 0 0 0 0 8 0%47:40:04
TOTAL: 9 215552 3074 174942 480 0 750 1%428:40:36
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com5.2 MB270.9 KB5.5 MB0.0 KB/s3.1 KB47:40:04
2rahul.net3.9 MB97.4 KB4.0 MB0.0 KB/s3.5 KB47:39:02
3news.snarked.org336.6 KB0.0 KB336.6 KB0.0 KB/s6.0 KB47:37:26
4feeder.erje.net136.6 KB240.1 KB376.7 KB0.0 KB/s4.0 KB47:36:50
5news.uzoreto.com125.0 KB115.8 KB240.8 KB0.0 KB/s4.3 KB47:35:36
6fu-berlin.de82.4 KB0.0 KB82.4 KB0.0 KB/s2.9 KB47:34:12
7news.cmpublishers.com22.2 KB807.8 KB830.0 KB0.0 KB/s2.9 KB47:37:16
8newsfeed.neva.ru20.1 KB347.4 KB367.5 KB0.0 KB/s5.6 KB47:40:06
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:04
TOTAL: 99.8 MB1.8 MB11.6 MB0.0 KB/s3.4 KB428:40:36
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 13303.5 MB 530 6 000:07:49
2shell3 22 4941.4 MB 48 0 002:04:23
375-151-74-61-independence.hfc.comcastbusiness.net 8 44.7 KB 4 0 000:13:07
496-66-38-54-static.hfc.comcastbusiness.net 6 2152.9 KB 2 0 000:25:40
5136.sub-174-230-24.myvzw.com 6 23.2 KB 2 0 000:25:11
TOTAL: 5 328 18325.1 MB 586 6 051:45:30

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 13303.5 MB 530 6 000:07:49
2? 22 4941.4 MB 48 0 002:04:23
3*.hfc.comcastbusiness.net 14 6157.6 KB 6 0 000:38:47
4*.sub-174-230-24.myvzw.com 6 23.2 KB 2 0 000:25:11
5*.dsl.bell.ca 4 00.0 KB 0 0 000:10:25
6*.ip158.fastwebnet.it 8 00.0 KB 0 0 000:01:28
7*.fbx.proxad.net 40 00.0 KB 0 0 012:44:06
8*.eu-west-2.compute.amazonaws.com 14 00.0 KB 0 0 000:01:44
9*.norrkoping.se 2 00.0 KB 0 0 000:00:21
10*.7-3.cable.virginm.net 12 00.0 KB 0 0 032:06:16
11unresolved 84 00.0 KB 0 0 002:11:01
12*.stwserver.net 64 00.0 KB 0 0 001:13:52
13*.maine.res.rr.com 2 00.0 KB 0 0 000:00:00
TOTAL: 13 558 18325.1 MB 586 6 051:45:30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 17.370 30.346 0.00032:06:16
vvs78-1-88-173-43-113.fbx.proxad.net 4.070 0.932 0.00012:44:06
shell3 8.450 3.432 0.00002:04:23
155.94.193.194 6.682 1.692 0.00001:50:33
mkh188.stwserver.net 6.518 1.504 0.00001:13:52
96-66-38-54-static.hfc.comcastbusiness.net 1.076 0.366 0.00000:25:40
136.sub-174-230-24.myvzw.com 5.316 0.790 0.00000:25:11
84.85.228.144 2.156 0.540 0.00000:20:27
75-151-74-61-independence.hfc.comcastbusiness.net 1.550 1.322 0.00000:13:07
nrbaon0431w-lp130-01-174-95-36-160.dsl.bell.ca 0.396 0.078 0.00000:10:25
ftupet.com 47.314 7.410 0.00000:07:49
ec2-3-8-10-211.eu-west-2.compute.amazonaws.com 1.412 0.308 0.00000:01:44
93-58-107-164.ip158.fastwebnet.it 1.010 0.434 0.00000:01:28
pc-194-68-144-221.norrkoping.se 0.202 0.040 0.00000:00:21
cpe-67-244-42-104.maine.res.rr.com 0.206 0.040 0.00000:00:00
TOTAL: 15 103.728 49.234 0.00051:45:30

Curious NNRP server explorers [Top 20]:

SystemConn
155.94.193.194 64
mkh188.stwserver.net 64
vvs78-1-88-173-43-113.fbx.proxad.net 40
84.85.228.144 20
ec2-3-8-10-211.eu-west-2.compute.amazonaws.com 14
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 12
93-58-107-164.ip158.fastwebnet.it 8
nrbaon0431w-lp130-01-174-95-36-160.dsl.bell.ca 4
cpe-67-244-42-104.maine.res.rr.com 2
pc-194-68-144-221.norrkoping.se 2
TOTAL: 10 230

NNRP unrecognized commands (by host):

SystemConn
shell3 4
136.sub-174-230-24.myvzw.com 4
TOTAL: 2 8

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 4
XTHREAD DBINIT 4
TOTAL: 2 8

NNRP client timeouts [Top 20]:

SystemConnPeer
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 10 0
96-66-38-54-static.hfc.comcastbusiness.net 2 0
TOTAL: 2 12 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1520 83.0%
2misc 142 7.8%
3comp 72 3.9%
4alt 34 1.9%
5sci 20 1.1%
6chi 14 0.8%
7ba 12 0.7%
8news 12 0.7%
9it 2 0.1%
10seattle 2 0.1%
11soc 2 0.1%
TOTAL: 11 1832100.0%

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

NewsgroupCount
1rec.food.cooking 744
2rec.arts.tv 622
3misc.phone.mobile.iphone 138
4rec.bicycles.tech 122
5comp.mobile.ipad 24
6rec.gardens.edible 22
7sci.electronics.repair 20
8comp.sys.mac.system 14
9alt.online-service.comcast 12
10comp.dcom.telecom 12
11ba.food 12
12comp.sys.mac.apps 10
13news.admin.net-abuse.email 10
14chi.weather 8
15alt.comp.google 6
16alt.games.warcraft 6
17comp.protocols.dns.bind 4
18chi.general 4
19comp.mail.pine 4
20rec.radio.broadcasting 4
21rec.radio.swap 4
22comp.unix.solaris 4
23misc.consumers.frugal-living 4
24alt.books.larry-niven 2
25alt.www.webmaster 2
26soc.culture.indian 2
27alt.solar.photovoltaic 2
28seattle.general 2
29rec.video.cable-tv 2
30it.comp.hardware 2
31alt.vacation.las-vegas 2
32alt.stupidity 2
33chi.jobs 2
34news.groups.proposals 2
TOTAL: 34 1832