Daily Usenet report for news.ripco.com

Sep 14 00:01:01 -- Sep 14 23:57:34

Unknown entries from news log file:

First 25 / 25 lines (100.0%)

Sep 14 00:16:43 remote5bge0 overchan[3001]: [ID 702911 news.error] input line too long, skipping
Sep 14 05:20:07 s_local@remote5bge0 innd: [ID 702911 news.notice] ripco-out.feed.uzoreto.com:21 checkpoint seconds -23 accepted 13 refused 16 rejected 3 duplicate 0 accepted size 28625 duplicate size 0 rejected size 5893
Sep 14 05:20:07 s_local@remote5bge0 innd: [ID 702911 news.notice] ripco-out.feed.uzoreto.com:21 closed seconds -23 accepted 13 refused 16 rejected 3 duplicate 0 accepted size 28625 duplicate size 0 rejected size 5893
Sep 14 05:20:07 remote5bge0 innd: [ID 702911 news.notice] ripco-out.feed.uzoreto.com:21 checkpoint seconds -23 accepted 13 refused 16 rejected 3 duplicate 0 accepted size 28625 duplicate size 0 rejected size 5893
Sep 14 05:20:07 remote5bge0 innd: [ID 702911 news.notice] ripco-out.feed.uzoreto.com:21 closed seconds -23 accepted 13 refused 16 rejected 3 duplicate 0 accepted size 28625 duplicate size 0 rejected size 5893
Sep 14 05:20:07 s_local@remote5bge0 innd: [ID 702911 news.notice] ripco-out.feed.uzoreto.com:22 checkpoint seconds -22 accepted 7 refused 14 rejected 9 duplicate 0 accepted size 17320 duplicate size 0 rejected size 13495
Sep 14 05:20:07 remote5bge0 innd: [ID 702911 news.notice] ripco-out.feed.uzoreto.com:22 checkpoint seconds -22 accepted 7 refused 14 rejected 9 duplicate 0 accepted size 17320 duplicate size 0 rejected size 13495
Sep 14 05:20:07 s_local@remote5bge0 innd: [ID 702911 news.notice] ripco-out.feed.uzoreto.com:22 closed seconds -22 accepted 7 refused 14 rejected 9 duplicate 0 accepted size 17320 duplicate size 0 rejected size 13495
Sep 14 05:20:07 remote5bge0 innd: [ID 702911 news.notice] ripco-out.feed.uzoreto.com:22 closed seconds -22 accepted 7 refused 14 rejected 9 duplicate 0 accepted size 17320 duplicate size 0 rejected size 13495
Sep 14 05:20:07 s_local@remote5bge0 innd: [ID 702911 news.notice] feeder3.feed-ams.uzoreto.com:24 checkpoint seconds -23 accepted 6 refused 29 rejected 7 duplicate 0 accepted size 18392 duplicate size 0 rejected size 8457
Sep 14 05:20:07 s_local@remote5bge0 innd: [ID 702911 news.notice] feeder3.feed-ams.uzoreto.com:24 closed seconds -23 accepted 6 refused 29 rejected 7 duplicate 0 accepted size 18392 duplicate size 0 rejected size 8457
Sep 14 05:20:07 remote5bge0 innd: [ID 702911 news.notice] feeder3.feed-ams.uzoreto.com:24 checkpoint seconds -23 accepted 6 refused 29 rejected 7 duplicate 0 accepted size 18392 duplicate size 0 rejected size 8457
Sep 14 05:20:07 remote5bge0 innd: [ID 702911 news.notice] feeder3.feed-ams.uzoreto.com:24 closed seconds -23 accepted 6 refused 29 rejected 7 duplicate 0 accepted size 18392 duplicate size 0 rejected size 8457
Sep 14 05:20:19 s_local@remote5bge0 innd: [ID 702911 news.notice] news-in.rahul.net:11 checkpoint seconds -7 accepted 3 refused 8 rejected 0 duplicate 0 accepted size 7486 duplicate size 0 rejected size 0
Sep 14 05:20:19 s_local@remote5bge0 innd: [ID 702911 news.notice] news-in.rahul.net:11 closed seconds -7 accepted 3 refused 8 rejected 0 duplicate 0 accepted size 7486 duplicate size 0 rejected size 0
Sep 14 05:20:19 remote5bge0 innd: [ID 702911 news.notice] news-in.rahul.net:11 checkpoint seconds -7 accepted 3 refused 8 rejected 0 duplicate 0 accepted size 7486 duplicate size 0 rejected size 0
Sep 14 05:20:19 remote5bge0 innd: [ID 702911 news.notice] news-in.rahul.net:11 closed seconds -7 accepted 3 refused 8 rejected 0 duplicate 0 accepted size 7486 duplicate size 0 rejected size 0
Sep 14 05:20:21 s_local@remote5bge0 innd: [ID 702911 news.notice] ripco-out.feeder.erje.net:27 checkpoint seconds -23 accepted 0 refused 52 rejected 4 duplicate 0 accepted size 0 duplicate size 0 rejected size 58636
Sep 14 05:20:21 s_local@remote5bge0 innd: [ID 702911 news.notice] ripco-out.feeder.erje.net:27 closed seconds -23 accepted 0 refused 52 rejected 4 duplicate 0 accepted size 0 duplicate size 0 rejected size 58636
Sep 14 05:20:21 remote5bge0 innd: [ID 702911 news.notice] ripco-out.feeder.erje.net:27 checkpoint seconds -23 accepted 0 refused 52 rejected 4 duplicate 0 accepted size 0 duplicate size 0 rejected size 58636
Sep 14 05:20:21 remote5bge0 innd: [ID 702911 news.notice] ripco-out.feeder.erje.net:27 closed seconds -23 accepted 0 refused 52 rejected 4 duplicate 0 accepted size 0 duplicate size 0 rejected size 58636
Sep 14 06:49:00 remote5bge0 overchan[3001]: [ID 702911 news.error] input line too long, skipping
Sep 14 09:02:25 remote5bge0 overchan[3001]: [ID 702911 news.error] input line too long, skipping
Sep 14 10:57:17 remote5bge0 overchan[3001]: [ID 702911 news.error] input line too long, skipping
Sep 14 22:53:44 remote5bge0 overchan[3001]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
innd 20063 33.6%3.1 MB 34.9%
nnrpd 18506 31.0%1.9 MB 22.0%
inn 17508 29.3%3.0 MB 34.4%
innfeed 3622 6.1%780.3 KB 8.6%
overchan 5 0.0%0.6 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 59706 100.0%8.8 MB100.0%

History cache:

ReasonCount%Count
Cache misses 144446 50.0%
Do not exist 144284 50.0%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 288730 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%152 0.000 0.000 0.000
article cleanup00:00:00.300 0.0%34658 0.000 0.009 0.056
article logging00:00:00.458 0.0%34952 0.000 0.013 0.067
article parse00:00:01.302 0.0%82110 0.000 0.016 0.045
article write00:00:33.836 0.0%24882 0.258 1.360 14.649
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.222 0.0%293122 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.084 0.0%289512 0.000 0.007 0.019
history sync00:00:00.210 0.0%806 0.000 0.261 7.333
history write00:00:02.578 0.0%34658 0.000 0.074 1.163
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle2433:41:10.766100.0%327226 279.362 26774.37210225801.633
nntp read00:00:01.842 0.0%323960 0.000 0.006 0.017
overview write00:00:00.162 0.0%24882 0.000 0.007 0.054
perl filter00:00:45.472 0.0%34656 1.012 1.312 1.929
python filter00:00:00.842 0.0%34658 0.000 0.024 0.078
site send00:00:01.760 0.0%74602 0.000 0.024 0.043
TOTAL: 2433:43:24.2842433:42:41.834100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.090 0.0%25086 0.000 0.004 0.028
article prepare00:00:00.004 0.0%5984 0.000 0.001 0.050
article read00:00:00.256 0.0%1216 0.000 0.211 70.000
backlog stats00:00:00.196 0.0%303216 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.592 0.0%197338 0.000 0.008 0.017
data write00:00:03.314 0.0%173418 0.007 0.019 0.041
idle47:38:34.984100.0%303216 323.767 565.653 1531.638
status file00:00:01.876 0.0%334 0.000 5.617 62.000
TOTAL: 47:38:51.56447:38:42.312100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:39:37.342 94.6%17962 19.667 132.354 5456.179
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.430 0.2%398644 0.000 0.011 1.000
readart00:00:20.236 0.8%2228 3.227 9.083 36.500
TOTAL: 00:41:53.64200:40:02.008 95.6%----

Control commands to INND:

CommandNumber
L 2
flush 2
go 4
logmode 2
mode 295
name 2
pause 2
paused 2
reserve 4
TOTAL: 9 315

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1152 26568 8878 15852 1838 33%94:48:14
2news.snarked.org 46 24832 6594 17872 366 26%53:41:22
3newsfeed.neva.ru 606 41888 4458 35094 2336 10%278:37:40
4feeder3.feed-ams.uzoreto.com 578 16640 3708 9224 3708 22%47:38:54
5newsfeed.fu-berlin.de 2 13438 604 12784 50 4%47:38:52
6news-in.rahul.net 578 14412 264 14100 48 1%47:32:18
7news.cmpublishers.com 50 7658 154 7428 76 2%47:49:38
8news.lightlink.com 2 8730 148 8572 10 1%49:38:04
9ripco-out.feeder.erje.net 576 27024 54 25694 1276 0%47:17:02
10localhost 150 2 2 0 0 100%00:00:00
TOTAL: 10 3740 181192 24864 146620 9708 13%714:42:04
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com23.5 MB0.0 KB4.8 MB28.3 MB 82%2.7 KB
2news.snarked.org20.2 MB0.0 KB1.1 MB21.3 MB 94%3.1 KB
3newsfeed.neva.ru14.3 MB5.1 KB12.9 MB27.2 MB 52%4.1 KB
4feeder3.feed-ams.uzoreto.com10.0 MB0.0 KB8.1 MB18.1 MB 55%2.5 KB
5news-in.rahul.net5.7 MB0.0 KB3.1 MB8.8 MB 64%28.8 KB
6newsfeed.fu-berlin.de4.2 MB0.0 KB820.5 KB5.0 MB 84%7.9 KB
7news.lightlink.com801.6 KB0.0 KB242.2 KB1.0 MB 76%6.6 KB
8news.cmpublishers.com497.3 KB0.0 KB165.0 KB662.3 KB 75%2.9 KB
9ripco-out.feeder.erje.net88.8 KB0.0 KB14.4 MB14.5 MB 0%11.1 KB
10localhost3.5 KB0.0 KB0.0 KB3.5 KB100%1.7 KB
TOTAL: 1079.2 MB5.1 KB45.7 MB124.9 MB 63%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 14 00:01:01 - 00:59:59 216 1.7% 0.06703.4 KB 1.8% 0.20
Sep 14 01:00:00 - 01:59:59 347 2.8% 0.10961.1 KB 2.4% 0.27
Sep 14 02:00:00 - 02:59:59 442 3.5% 0.121.2 MB 3.2% 0.35
Sep 14 03:00:00 - 03:59:59 482 3.9% 0.131.4 MB 3.5% 0.39
Sep 14 04:00:00 - 04:59:59 641 5.1% 0.181.7 MB 4.4% 0.49
Sep 14 05:00:00 - 05:59:59 606 4.9% 0.171.7 MB 4.5% 0.50
Sep 14 06:00:00 - 06:59:59 625 5.0% 0.172.0 MB 5.0% 0.56
Sep 14 07:00:00 - 07:59:59 625 5.0% 0.172.0 MB 5.1% 0.57
Sep 14 08:00:00 - 08:59:59 606 4.9% 0.171.9 MB 4.9% 0.54
Sep 14 09:00:00 - 09:59:59 653 5.2% 0.181.8 MB 4.7% 0.52
Sep 14 10:00:00 - 10:59:59 723 5.8% 0.202.1 MB 5.3% 0.59
Sep 14 11:00:00 - 11:59:59 760 6.1% 0.212.1 MB 5.3% 0.59
Sep 14 12:00:00 - 12:59:59 722 5.8% 0.202.1 MB 5.3% 0.59
Sep 14 13:00:00 - 13:59:59 717 5.7% 0.202.7 MB 6.9% 0.76
Sep 14 14:00:00 - 14:59:59 714 5.7% 0.202.1 MB 5.5% 0.61
Sep 14 15:00:00 - 15:59:59 682 5.5% 0.192.8 MB 7.2% 0.80
Sep 14 16:00:00 - 16:59:59 538 4.3% 0.151.4 MB 3.7% 0.41
Sep 14 17:00:00 - 17:59:59 510 4.1% 0.141.5 MB 3.9% 0.44
Sep 14 18:00:00 - 18:59:59 388 3.1% 0.111.3 MB 3.3% 0.36
Sep 14 19:00:00 - 19:59:59 311 2.5% 0.091.1 MB 2.7% 0.30
Sep 14 20:00:00 - 20:59:59 347 2.8% 0.101.4 MB 3.5% 0.39
Sep 14 21:00:00 - 21:59:59 279 2.2% 0.081.1 MB 2.9% 0.32
Sep 14 22:00:00 - 22:59:59 270 2.2% 0.07904.3 KB 2.3% 0.25
Sep 14 23:00:00 - 23:57:34 269 2.2% 0.081.1 MB 2.9% 0.34
TOTAL: 23:56:33 12473 100.0% 0.1439.1 MB 100.0% 0.46
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1feeder3.feed-ams.uzoreto.com 1863 1208 0 0 1 0 0 0 654
2newsfeed.neva.ru 1199 361 0 1 5 0 0 0 832
3ripco-out.feed.uzoreto.com 940 444 0 0 1 0 0 0 495
4ripco-out.feeder.erje.net 641 639 0 0 0 0 0 0 2
5news.snarked.org 185 67 0 0 1 0 0 0 117
6newsfeed.fu-berlin.de 134 0 0 0 0 0 0 0 134
7news.cmpublishers.com 43 4 0 0 0 0 0 0 39
8news-in.rahul.net 24 0 0 0 0 0 0 0 24
9news.lightlink.com 6 0 0 0 0 0 0 0 6
TOTAL: 9 5035 2723 0 1 8 0 0 0 2303

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1801
alt.binaries.ftd 409
de.soc.arbeitslos 41
alt.binaries.pictures.purebeauty 40
alt.binaries.nl 30
perl.cpan.uploads 22
fido7.su.pol.news 20
alt.bestjobsusa.computer.jobs 20
alt.binaries.3d.poser 15
it.tlc.cellulari.android 15
alt.binaries.e-book 15
linux.debian.maint.emacsen 14
free.willey 13
scot.general 13
alt.binaries.pictures.wallpaper 11
fr.comp.os.android 10
free.it.scienza.fisica 10
nl.comp.os.linux.techniek 10
it.comp.os.win.windows10 7
alt.binaries.pictures.erotica.vintage 7
TOTAL: 126 2723

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

GroupsCount
pl.praca.oferowana 5
alt.christnet.religion 1
alt.christnet.christnews 1
bit.listserv.catholic 1
TOTAL: 4 8

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2586
Too many newsgroups 1106
Binary in non-binary group 390
EMP (phl) 98
Scoring filter (8) 46
Scoring filter (10) 36
Scoring filter (9) 20
Scoring filter (18) 16
Scoring filter (14) 4
Scoring filter (11) 2
Scoring filter (12) 2
Scoring filter (16) 2
TOTAL: 12 4308

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 106
newsfeed.neva.ru 31
ripco-out.feed.uzoreto.com 9
feeder3.feed-ams.uzoreto.com 2
TOTAL: 4 148
No colon-space in header
ripco-out.feed.uzoreto.com 1
TOTAL: 1 1
TOTAL: 3 167

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1rahul.net 18804 642 11220 4 0 0 3%47:40:02
2news.lightlink.com 9590 500 5054 10 0 0 5%47:40:02
3fu-berlin.de 22832 38 22794 0 0 0 0%47:40:02
4feeder.erje.net 23648 32 23374 98 0 0 0%47:41:34
5news.uzoreto.com 12186 28 11898 30 0 0 0%47:47:14
6news.snarked.org 29596 18 17976 2 0 28 0%47:40:02
7newsfeed.neva.ru 21118 18 20368 10 0 0 0%47:40:00
8news.cmpublishers.com 34896 10 24328 56 0 216 0%47:40:42
9remote6hme0.ripco.com 0 0 0 0 0 6 0%47:40:02
TOTAL: 9 172670 1286 137012 210 0 250 0%429:09:40
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net2.6 MB8.9 KB2.7 MB0.0 KB/s4.2 KB47:40:02
2news.lightlink.com1.5 MB14.8 KB1.5 MB0.0 KB/s3.1 KB47:40:02
3fu-berlin.de509.6 KB0.0 KB509.6 KB0.0 KB/s13.4 KB47:40:02
4newsfeed.neva.ru180.5 KB45.5 KB225.9 KB0.0 KB/s8.1 KB47:40:00
5feeder.erje.net141.6 KB421.8 KB563.4 KB0.0 KB/s4.3 KB47:41:34
6news.snarked.org110.8 KB3.7 KB114.5 KB0.0 KB/s5.7 KB47:40:02
7news.uzoreto.com94.2 KB95.4 KB189.6 KB0.0 KB/s3.3 KB47:47:14
8news.cmpublishers.com28.7 KB160.3 KB188.9 KB0.0 KB/s2.9 KB47:40:42
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
TOTAL: 95.2 MB750.4 KB5.9 MB0.0 KB/s4.1 KB429:09:40
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 288 9822.7 MB 416 2 000:07:53
2shell3 4 262633.5 KB 32 0 000:32:48
TOTAL: 2 292 12443.3 MB 448 2 000:41:40

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 288 9822.7 MB 416 2 000:07:53
2? 4 262633.5 KB 32 0 000:32:48
TOTAL: 2 292 12443.3 MB 448 2 000:40:41

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 1.644 1.058 0.00000:32:48
ftupet.com 47.096 7.610 0.00000:07:53
d54c176a7.access.telenet.be 28.902 6.190 0.00000:00:52
125.64.94.221 0.202 0.044 0.00000:00:03
46.244.28.16 0.816 0.170 0.00000:00:01
124-169-29-35.dyn.iinet.net.au 0.200 0.038 0.00000:00:01
122.228.19.80 0.200 0.038 0.00000:00:00
worker-18.sfj.corp.censys.io 0.198 0.042 0.00000:00:00
TOTAL: 8 79.258 15.190 0.00000:41:40

NNRP no permission clients:

SystemConn
d54c176a7.access.telenet.be 288
46.244.28.16 8
125.64.94.221 2
122.228.19.80 2
124-169-29-35.dyn.iinet.net.au 2
worker-18.sfj.corp.censys.io 2
TOTAL: 6 304

Newsgroup request counts (by category):

CategoryCountPct
1rec 1138 91.5%
2misc 60 4.8%
3comp 18 1.4%
4alt 16 1.3%
5sci 8 0.6%
6chi 4 0.3%
TOTAL: 6 1244100.0%

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

NewsgroupCount
1rec.arts.tv 558
2rec.food.cooking 530
3misc.phone.mobile.iphone 56
4rec.bicycles.tech 36
5sci.electronics.repair 8
6alt.internet.wireless 8
7rec.radio.swap 6
8alt.cellular.t-mobile 4
9rec.radio.broadcasting 4
10comp.mobile.ipad 4
11comp.mail.sendmail 4
12comp.mail.misc 4
13chi.weather 4
14misc.consumers.frugal-living 4
15rec.bicycles.misc 2
16comp.sys.mac.vintage 2
17rec.antiques.radio+phono 2
18alt.dss.hack 2
19alt.online-services.roadrunner 2
20comp.sys.ibm.pc.games.rpg 2
21comp.sys.mac.system 2
TOTAL: 21 1244