Daily Usenet report for news.ripco.com

Oct 7 00:01:03 -- Oct 7 23:57:22

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Oct 7 06:16:41 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 06:22:48 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 06:22:48 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 06:22:48 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 12:27:40 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 12:44:08 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 14:02:01 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 14:13:14 remote5bge0 nnrpd[11215]: [ID 333254 news.error] fd 0 can't setsockopt(KEEPALIVE) Invalid argument
Oct 7 14:13:14 remote5bge0 nnrpd[11215]: [ID 333254 news.error] fd 1 can't setsockopt(KEEPALIVE) Invalid argument
Oct 7 15:26:23 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 16:55:08 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 17:05:34 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping
Oct 7 20:54:09 remote5bge0 overchan[9243]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 33970 43.6%4.2 MB 36.9%
inn 21061 27.0%3.5 MB 30.9%
innd 19296 24.8%3.0 MB 26.0%
innfeed 3594 4.6%733.3 KB 6.2%
overchan 11 0.0%1.6 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 77934 100.0%11.5 MB100.0%

History cache:

ReasonCount%Count
Cache misses 181838 51.1%
Do not exist 173934 48.9%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 355772 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%52 0.000 0.000 0.000
article cleanup00:00:00.448 0.0%41640 0.000 0.011 0.056
article logging00:00:00.802 0.0%42090 0.000 0.019 0.281
article parse00:00:01.556 0.0%98656 0.000 0.016 0.045
article write00:00:47.496 0.0%27676 0.571 1.716 15.075
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.288 0.0%357056 0.000 0.001 0.006
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.714 0.0%356276 0.002 0.008 0.015
history sync00:00:00.392 0.0%800 0.000 0.490 6.333
history write00:00:04.408 0.0%41632 0.012 0.106 2.006
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:39:35.796 99.9%391786 230.397 437.932 1216.527
nntp read00:00:02.256 0.0%389398 0.000 0.006 0.021
overview write00:00:00.182 0.0%27676 0.000 0.007 0.061
perl filter00:00:54.846 0.0%41622 0.977 1.318 2.343
python filter00:00:01.090 0.0%41626 0.000 0.026 0.065
site send00:00:02.072 0.0%82932 0.000 0.025 0.052
TOTAL: 47:42:08.63447:41:34.346100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.078 0.0%27692 0.000 0.003 0.024
article prepare00:00:00.002 0.0%10168 0.000 0.000 0.017
article read00:00:00.358 0.0%2198 0.000 0.163 8.875
backlog stats00:00:00.150 0.0%324372 0.000 0.000 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.248 0.0%216298 0.000 0.006 0.018
data write00:00:02.782 0.0%189284 0.003 0.015 0.035
idle47:35:53.316100.0%324372 240.041 528.262 1299.961
status file00:00:04.402 0.0%328 5.000 13.421 65.000
TOTAL: 47:36:10.83047:36:02.336100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:04.928 0.0%371252 0.000 0.013 0.231
idle43:05:28.474 99.2%405394 18.429 382.661300412.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:30.328 0.0%1676292 0.000 0.018 9.394
readart00:00:32.448 0.0%20652 0.055 1.571 71.000
TOTAL: 43:25:37.55043:06:36.178 99.3%----

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 49718 12654 31296 5768 25%94:38:24
2news.snarked.org 54 39112 8276 26848 3988 21%61:58:30
3newsfeed.neva.ru 576 51290 5454 43266 2570 10%279:41:58
4newsfeed.fu-berlin.de 2 13082 668 12380 34 5%45:53:16
5news.lightlink.com 2 10166 386 9400 380 3%50:22:10
6news-in.rahul.net 574 17954 210 17716 28 1%47:20:04
7news.cmpublishers.com 50 9498 162 9310 26 1%48:00:00
8ripco-out.feeder.erje.net 574 32882 106 31542 1234 0%47:17:08
9localhost 148 4 4 0 0 100%00:00:00
TOTAL: 9 3128 223706 27920 181758 14028 12%675:11:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com34.3 MB0.0 KB11.6 MB45.9 MB 74%2.6 KB
2news.snarked.org25.5 MB0.0 KB12.8 MB38.3 MB 66%3.2 KB
3newsfeed.neva.ru17.4 MB21.5 KB9.5 MB26.9 MB 64%3.4 KB
4news-in.rahul.net4.0 MB0.0 KB1.3 MB5.4 MB 75%23.2 KB
5newsfeed.fu-berlin.de3.7 MB0.0 KB968.6 KB4.6 MB 79%6.7 KB
6news.lightlink.com1.6 MB0.0 KB12.4 MB14.0 MB 11%18.8 KB
7news.cmpublishers.com391.9 KB0.0 KB54.6 KB446.5 KB 87%2.4 KB
8ripco-out.feeder.erje.net242.7 KB0.0 KB11.3 MB11.6 MB 2%8.9 KB
9localhost7.4 KB0.0 KB0.0 KB7.4 KB100%1.8 KB
TOTAL: 987.2 MB21.5 KB59.9 MB147.2 MB 59%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 7 00:01:03 - 00:59:59 280 2.0% 0.081.2 MB 2.7% 0.33
Oct 7 01:00:00 - 01:59:59 279 2.0% 0.08765.2 KB 1.7% 0.21
Oct 7 02:00:00 - 02:59:59 428 3.1% 0.121.4 MB 3.1% 0.38
Oct 7 03:00:00 - 03:59:59 551 4.0% 0.151.6 MB 3.6% 0.44
Oct 7 04:00:00 - 04:59:59 593 4.3% 0.161.8 MB 4.3% 0.52
Oct 7 05:00:00 - 05:59:59 544 3.9% 0.151.6 MB 3.8% 0.46
Oct 7 06:00:00 - 06:59:59 630 4.5% 0.172.1 MB 4.8% 0.60
Oct 7 07:00:00 - 07:59:59 672 4.9% 0.191.8 MB 4.1% 0.51
Oct 7 08:00:00 - 08:59:59 564 4.1% 0.161.7 MB 3.8% 0.47
Oct 7 09:00:00 - 09:59:59 745 5.4% 0.212.1 MB 4.9% 0.61
Oct 7 10:00:00 - 10:59:59 744 5.4% 0.212.4 MB 5.4% 0.67
Oct 7 11:00:00 - 11:59:59 806 5.8% 0.222.2 MB 5.1% 0.63
Oct 7 12:00:00 - 12:59:59 862 6.2% 0.242.3 MB 5.4% 0.67
Oct 7 13:00:00 - 13:59:59 882 6.4% 0.242.7 MB 6.1% 0.75
Oct 7 14:00:00 - 14:59:59 990 7.1% 0.282.9 MB 6.7% 0.82
Oct 7 15:00:00 - 15:59:59 817 5.9% 0.232.9 MB 6.7% 0.82
Oct 7 16:00:00 - 16:59:59 678 4.9% 0.192.2 MB 5.0% 0.62
Oct 7 17:00:00 - 17:59:59 596 4.3% 0.171.9 MB 4.5% 0.55
Oct 7 18:00:00 - 18:59:59 501 3.6% 0.142.2 MB 5.2% 0.64
Oct 7 19:00:00 - 19:59:59 382 2.8% 0.111.5 MB 3.4% 0.42
Oct 7 20:00:00 - 20:59:59 329 2.4% 0.091.0 MB 2.3% 0.29
Oct 7 21:00:00 - 21:59:59 300 2.2% 0.08872.6 KB 2.0% 0.24
Oct 7 22:00:00 - 22:59:59 253 1.8% 0.071.0 MB 2.3% 0.29
Oct 7 23:00:00 - 23:57:22 427 3.1% 0.121.4 MB 3.1% 0.40
TOTAL: 23:56:19 13853 100.0% 0.1643.4 MB 100.0% 0.52
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2892 2283 0 0 2 0 0 0 607
2news.snarked.org 1985 195 0 0 3 0 0 0 1787
3newsfeed.neva.ru 1343 772 0 4 1 0 0 0 566
4ripco-out.feeder.erje.net 617 615 0 0 0 0 0 0 2
5news.lightlink.com 174 2 0 0 0 0 0 0 172
6newsfeed.fu-berlin.de 170 0 0 0 0 0 0 0 170
7news-in.rahul.net 14 0 0 0 0 0 0 0 14
8news.cmpublishers.com 13 0 0 0 0 0 0 0 13
TOTAL: 8 7208 3867 0 4 6 0 0 0 3331

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2628
alt.binaries.ftd 381
perl.cpan.uploads 271
alt.astrology.pro 65
alt.binaries.pictures.wallpaper 36
it.tlc.cellulari.android 26
it.comp.os.win.windows10 25
mi.jobs 23
free.willey 21
fido7.su.pol.news 21
free.ucp 19
alt.binaries.pictures.rail 18
de.soc.arbeitslos 16
free.it.scienza.fisica 14
alt.binaries.e-books 14
alt.binaries.nl 12
alt.binaries.drwho 11
alt.binaries.test.yenc 11
alt.binaries.e-book 11
alt.alan.c.sanderson.looks.like.a.dork 10
TOTAL: 142 3867

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

GroupsCount
christnet.bible 4
alt.penthouse.sex.sissy.slut 1
bit.listserv.catholic 1
TOTAL: 3 6

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4738
Too many newsgroups 1026
Binary in non-binary group 240
Attached HTML file 72
EMP (phl) 66
Scoring filter (10) 20
Scoring filter (8) 18
Scoring filter (16) 14
Scoring filter (9) 6
Scoring filter (11) 2
Scoring filter (17) 2
TOTAL: 11 6204

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feed.uzoreto.com 4
TOTAL: 1 4
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Including strange strings
newsfeed.fu-berlin.de 155
newsfeed.neva.ru 61
ripco-out.feed.uzoreto.com 8
news.lightlink.com 1
TOTAL: 4 225
TOTAL: 3 247

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 9354 1044 5312 6 0 28 11%47:43:18
2rahul.net 22026 828 12664 2 0 0 3%47:40:04
3news.snarked.org 32288 48 19234 20 0 110 0%47:35:44
4feeder.erje.net 27184 22 25890 126 0 0 0%47:40:04
5fu-berlin.de 24964 22 24942 0 0 0 0%47:40:04
6news.cmpublishers.com 34134 16 26926 278 0 4 0%47:40:04
7newsfeed.neva.ru 22474 10 21966 98 0 0 0%47:35:22
8news.uzoreto.com 15024 6 14666 16 0 0 0%47:40:04
9remote6hme0.ripco.com 0 0 0 0 0 6 0%47:40:04
TOTAL: 9 187448 1996 151600 546 0 148 1%428:54:48
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com4.0 MB10.2 KB4.0 MB0.0 KB/s3.9 KB47:43:18
2rahul.net2.7 MB47.3 KB2.8 MB0.0 KB/s3.4 KB47:40:04
3fu-berlin.de213.8 KB0.0 KB213.8 KB0.0 KB/s9.7 KB47:40:04
4news.snarked.org194.0 KB65.4 KB259.4 KB0.0 KB/s3.8 KB47:35:44
5feeder.erje.net123.4 KB242.1 KB365.5 KB0.0 KB/s2.5 KB47:40:04
6news.cmpublishers.com77.4 KB873.4 KB950.8 KB0.0 KB/s3.2 KB47:40:04
7newsfeed.neva.ru57.0 KB264.8 KB321.8 KB0.0 KB/s3.0 KB47:35:22
8news.uzoreto.com18.3 KB114.3 KB132.6 KB0.0 KB/s6.0 KB47:40:04
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:04
TOTAL: 97.4 MB1.6 MB9.0 MB0.0 KB/s3.6 KB428:54:48
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 10242.8 MB 424 4 000:06:41
2shell3 4 224682.6 KB 16 0 001:47:26
3172-221-041-102.dhcp.chtrptr.net 16 88.8 KB 8 0 000:00:32
4pool-71-114-26-93.washdc.fios.verizon.net 2 414.4 KB 4 0 000:17:00
575-151-74-61-independence.hfc.comcastbusiness.net 8 43.7 KB 4 0 000:16:15
TOTAL: 5 316 12643.5 MB 456 4 043:25:28

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 10242.8 MB 424 4 000:06:41
2? 4 224682.6 KB 16 0 001:47:26
3*.dhcp.chtrptr.net 16 88.8 KB 8 0 000:00:32
4*.washdc.fios.verizon.net 2 414.4 KB 4 0 000:17:00
5*.hfc.comcastbusiness.net 8 43.7 KB 4 0 000:16:15
6*.19-1.cable.virginm.net 8 00.0 KB 0 0 000:00:54
7*.7-3.cable.virginm.net 26 00.0 KB 0 0 012:53:24
8unresolved 74 00.0 KB 0 0 006:51:34
9*.dyn.iinet.net.au 64 00.0 KB 0 0 021:11:37
TOTAL: 9 488 12643.5 MB 456 4 043:25:28

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
124-170-179-60.dyn.iinet.net.au 7.362 2.848 0.00021:11:37
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 6.948 9.152 0.00012:53:24
103.209.252.45 6.792 1.890 0.00004:55:42
shell3 1.996 1.300 0.00001:47:26
66.11.163.217 0.900 0.972 0.00001:43:27
pool-71-114-26-93.washdc.fios.verizon.net 1.380 0.386 0.00000:17:00
75-151-74-61-independence.hfc.comcastbusiness.net 1.586 1.346 0.00000:16:15
199.229.249.191 0.700 0.384 0.00000:12:23
ftupet.com 45.906 7.462 0.00000:06:41
cpc137266-soli7-2-0-cust639.19-1.cable.virginm.net 0.804 0.164 0.00000:00:54
172-221-041-102.dhcp.chtrptr.net 10.186 1.836 0.00000:00:32
185.245.86.29 0.202 0.040 0.00000:00:00
? 0.442 0.094 0.00000:00:00
TOTAL: 13 85.204 27.874 0.00043:25:28

Curious NNRP server explorers [Top 20]:

SystemConn
103.209.252.45 64
124-170-179-60.dyn.iinet.net.au 64
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 26
cpc137266-soli7-2-0-cust639.19-1.cable.virginm.net 8
199.229.249.191 4
66.11.163.217 4
185.245.86.29 2
TOTAL: 7 172

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

SystemConnPeer
124-170-179-60.dyn.iinet.net.au 92 0
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 18 0
shell3 2 0
TOTAL: 3 112 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1204 95.3%
2comp 26 2.1%
3alt 12 0.9%
4chi 10 0.8%
5fr 4 0.3%
6ba 2 0.2%
7sci 2 0.2%
8misc 2 0.2%
9news 2 0.2%
TOTAL: 9 1264100.0%

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

NewsgroupCount
1rec.food.cooking 572
2rec.arts.tv 540
3rec.bicycles.tech 84
4comp.sys.mac.system 26
5chi.weather 10
6alt.test 8
7rec.radio.broadcasting 4
8rec.radio.swap 4
9alt.religion.christianity 2
10fr.rec.jeux.video.materiel 2
11news.admin.net-abuse.misc 2
12sci.electronics.repair 2
13fr.comp.lang.python 2
14alt.tv.xena 2
15ba.general 2
16misc.consumers.frugal-living 2
TOTAL: 16 1264