Daily Usenet report for news.ripco.com

Nov 16 00:01:02 -- Nov 16 23:57:58

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Nov 16 06:59:55 remote5bge0 overchan[13159]: [ID 702911 news.error] input line too long, skipping
Nov 16 10:04:34 remote5bge0 overchan[13159]: [ID 702911 news.error] input line too long, skipping
Nov 16 13:29:00 remote5bge0 overchan[13159]: [ID 702911 news.error] input line too long, skipping
Nov 16 13:43:55 remote5bge0 overchan[13159]: [ID 702911 news.error] input line too long, skipping
Nov 16 13:55:06 remote5bge0 overchan[13159]: [ID 702911 news.error] input line too long, skipping
Nov 16 18:27:31 remote5bge0 overchan[13159]: [ID 702911 news.error] input line too long, skipping
Nov 16 18:35:34 remote5bge0 overchan[13159]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 27060 34.9%4.2 MB 37.3%
innd 27023 34.9%4.1 MB 37.0%
nnrpd 19688 25.4%2.1 MB 19.0%
innfeed 3690 4.8%769.3 KB 6.7%
overchan 7 0.0%1.0 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 77470 100.0%11.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 183446 58.4%
Cache misses 130572 41.6%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 314018 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%82 0.000 0.000 0.000
article cleanup00:00:00.480 0.0%53958 0.000 0.009 0.039
article logging00:00:01.014 0.0%54244 0.000 0.019 1.408
article parse00:00:02.128 0.0%130002 0.002 0.016 0.037
article write00:00:32.988 0.0%24106 0.262 1.368 15.020
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.302 0.0%340508 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:02.910 0.0%315716 0.001 0.009 0.019
history sync00:00:00.330 0.0%796 0.000 0.415 2.000
history write00:00:05.012 0.0%53952 0.034 0.093 1.082
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:58:53.860 99.9%384352 277.346 449.416 995.765
nntp read00:00:02.380 0.0%381456 0.002 0.006 0.018
overview write00:00:00.142 0.0%24106 0.000 0.006 0.033
perl filter00:01:11.668 0.0%53950 1.043 1.328 2.117
python filter00:00:01.364 0.0%53952 0.000 0.025 0.067
site send00:00:01.444 0.0%72264 0.000 0.020 0.243
TOTAL: 48:02:04.51848:00:56.022100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.084 0.0%24086 0.000 0.003 0.028
article prepare00:00:00.008 0.0%20032 0.000 0.000 0.017
article read00:00:00.466 0.0%4274 0.000 0.109 9.375
backlog stats00:00:00.178 0.0%277610 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.588 0.0%172834 0.002 0.009 0.019
data write00:00:02.498 0.0%149314 0.002 0.017 0.038
idle47:37:34.222100.0%277610 322.098 617.608 1739.206
status file00:00:01.862 0.0%392 0.000 4.750 34.000
TOTAL: 47:37:51.01447:37:40.906100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:10:25.304 97.9%17940 19.089 636.862 19768.586
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:05.774 0.0%640728 0.000 0.009 31.000
readart00:00:20.054 0.2%2182 2.917 9.191 36.000
TOTAL: 03:14:36.27403:10:51.132 98.1%----

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
1news.snarked.org 46 24380 7030 16962 388 28%53:19:40
2ripco-out.feed.uzoreto.com 1148 32304 6190 20310 5804 19%94:37:56
3feeder3.feed-ams.uzoreto.com 574 17388 6048 9118 2222 34%47:19:54
4newsfeed.neva.ru 382 42114 3646 18972 19496 8%187:30:44
5newsfeed.fu-berlin.de 2 13682 560 13102 20 4%48:18:50
6news.lightlink.com 2 6650 266 6120 264 4%49:20:12
7news-in.rahul.net 574 13330 194 13034 102 1%47:19:34
8ripco-out.feeder.erje.net 572 28104 76 26658 1370 0%47:26:14
9news.cmpublishers.com 52 8180 36 8126 18 0%47:59:14
10localhost 148 2 2 0 0 100%00:00:02
TOTAL: 10 3500 186134 24048 132402 29684 12%623:12:20
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org20.6 MB0.0 KB1.9 MB22.5 MB 91%3.1 KB
2ripco-out.feed.uzoreto.com16.0 MB9.6 KB12.7 MB28.7 MB 55%2.5 KB
3feeder3.feed-ams.uzoreto.com14.4 MB12.2 KB5.3 MB19.7 MB 73%2.4 KB
4newsfeed.neva.ru12.7 MB0.0 KB82.7 MB95.4 MB 13%4.2 KB
5news-in.rahul.net5.3 MB0.0 KB3.7 MB9.0 MB 58%31.1 KB
6newsfeed.fu-berlin.de4.5 MB0.0 KB225.3 KB4.8 MB 95%8.4 KB
7news.lightlink.com1.7 MB0.0 KB10.2 MB11.9 MB 13%22.9 KB
8ripco-out.feeder.erje.net627.4 KB0.0 KB14.4 MB15.0 MB 4%10.6 KB
9news.cmpublishers.com182.4 KB2.8 KB62.6 KB247.8 KB 73%4.6 KB
10localhost3.8 KB0.0 KB0.0 KB3.8 KB100%1.9 KB
TOTAL: 1076.0 MB24.7 KB131.2 MB207.2 MB 36%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 16 00:01:02 - 00:59:59 384 3.2% 0.111.4 MB 3.7% 0.40
Nov 16 01:00:00 - 01:59:59 393 3.3% 0.111.3 MB 3.5% 0.38
Nov 16 02:00:00 - 02:59:59 464 3.9% 0.131.3 MB 3.4% 0.37
Nov 16 03:00:00 - 03:59:59 478 4.0% 0.131.3 MB 3.4% 0.36
Nov 16 04:00:00 - 04:59:59 578 4.8% 0.162.3 MB 6.3% 0.67
Nov 16 05:00:00 - 05:59:59 470 3.9% 0.131.3 MB 3.6% 0.38
Nov 16 06:00:00 - 06:59:59 509 4.2% 0.141.3 MB 3.5% 0.37
Nov 16 07:00:00 - 07:59:59 670 5.6% 0.191.6 MB 4.3% 0.45
Nov 16 08:00:00 - 08:59:59 652 5.4% 0.182.4 MB 6.5% 0.69
Nov 16 09:00:00 - 09:59:59 710 5.9% 0.202.3 MB 6.3% 0.66
Nov 16 10:00:00 - 10:59:59 759 6.3% 0.212.1 MB 5.5% 0.59
Nov 16 11:00:00 - 11:59:59 625 5.2% 0.171.9 MB 5.0% 0.53
Nov 16 12:00:00 - 12:59:59 623 5.2% 0.172.0 MB 5.5% 0.58
Nov 16 13:00:00 - 13:59:59 707 5.9% 0.202.2 MB 5.9% 0.62
Nov 16 14:00:00 - 14:59:59 575 4.8% 0.161.6 MB 4.3% 0.46
Nov 16 15:00:00 - 15:59:59 543 4.5% 0.151.8 MB 4.8% 0.51
Nov 16 16:00:00 - 16:59:59 501 4.2% 0.141.4 MB 3.7% 0.40
Nov 16 17:00:00 - 17:59:59 492 4.1% 0.141.3 MB 3.4% 0.36
Nov 16 18:00:00 - 18:59:59 426 3.5% 0.121.4 MB 3.8% 0.40
Nov 16 19:00:00 - 19:59:59 406 3.4% 0.111.6 MB 4.2% 0.44
Nov 16 20:00:00 - 20:59:59 306 2.5% 0.09902.5 KB 2.4% 0.25
Nov 16 21:00:00 - 21:59:59 292 2.4% 0.08977.4 KB 2.6% 0.27
Nov 16 22:00:00 - 22:59:59 275 2.3% 0.081022.3 KB 2.7% 0.28
Nov 16 23:00:00 - 23:57:58 190 1.6% 0.05658.4 KB 1.7% 0.19
TOTAL: 23:56:56 12028 100.0% 0.1437.3 MB 100.0% 0.44
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 9792 4820 0 0 2 0 0 0 4970
2ripco-out.feed.uzoreto.com 2905 2314 0 1 2 0 0 0 588
3feeder3.feed-ams.uzoreto.com 1117 732 0 2 2 0 0 0 381
4ripco-out.feeder.erje.net 685 675 0 0 0 0 0 0 10
5news.snarked.org 195 99 0 0 1 0 0 0 95
6news.lightlink.com 160 1 0 0 0 0 0 0 159
7newsfeed.fu-berlin.de 117 0 0 0 0 0 0 0 117
8news-in.rahul.net 51 2 0 0 0 0 0 0 49
9news.cmpublishers.com 10 0 0 1 0 0 0 0 9
TOTAL: 9 15032 8643 0 4 7 0 0 0 6378

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.boneless 4363
free.usenet 3315
alt.binaries.ftd 366
scot.general 50
it.tlc.cellulari.android 43
alt.binaries.nl 41
alt.binaries.pictures.rail 30
free.it.scienza.fisica 26
fido7.su.pol.news 25
alt.binaries.pictures.earlmiller 18
alt.binaries.pictures.wallpaper 16
alt.binaries.e-book 14
alt.bestjobsusa.computer.jobs 13
perl.cpan.uploads 12
alt.binaries.pictures.erotica.vintage 11
alt.binaries.mac.osx.apps 9
free.willey 9
alt.binaries.test.yenc 9
alt.binaries.mp3.audiobooks 8
alt.testing 8
TOTAL: 135 8643

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

GroupsCount
pl.praca.oferowana 3
christnet.bible 2
alt.christnet.christnews 1
bit.listserv.catholic 1
TOTAL: 4 7

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 9358
EMP (md5) 1930
Too many newsgroups 880
EMP (phl) 176
Scoring filter (8) 64
Scoring filter (9) 34
Scoring filter (10) 12
Scoring filter (11) 8
Scoring filter (13) 4
U2 violation - invalid distribution 4
Scoring filter (14) 4
Scoring filter (18) 2
Poison newsgroup 2
TOTAL: 13 12478

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 108
newsfeed.neva.ru 30
feeder3.feed-ams.uzoreto.com 3
news.snarked.org 1
ripco-out.feed.uzoreto.com 1
TOTAL: 5 143
TOTAL: 2 161

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 8230 1746 4324 14 0 0 21%47:38:48
2rahul.net 19806 726 10908 16 0 2 3%47:44:52
3news.cmpublishers.com 33342 214 21570 1952 0 78 0%47:43:06
4feeder.erje.net 21732 46 21122 96 0 0 0%47:40:04
5news.uzoreto.com 11878 28 11306 132 0 0 0%47:40:04
6fu-berlin.de 21722 22 21700 0 0 0 0%47:40:04
7news.snarked.org 28222 18 16738 4 0 64 0%47:40:04
8newsfeed.neva.ru 0 0 0 0 0 20278 0%47:40:04
9remote6hme0.ripco.com 0 0 0 0 0 6 0%47:40:02
TOTAL: 9 144932 2800 107668 2214 0 20428 1%429:07:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com5.6 MB306.3 KB5.9 MB0.0 KB/s3.4 KB47:38:48
2rahul.net3.2 MB144.3 KB3.4 MB0.0 KB/s4.6 KB47:44:52
3news.cmpublishers.com735.7 KB5.3 MB6.0 MB0.0 KB/s2.9 KB47:43:06
4feeder.erje.net233.7 KB289.9 KB523.6 KB0.0 KB/s3.7 KB47:40:04
5fu-berlin.de111.8 KB0.0 KB111.8 KB0.0 KB/s5.1 KB47:40:04
6news.uzoreto.com110.8 KB642.2 KB753.0 KB0.0 KB/s4.7 KB47:40:04
7news.snarked.org96.8 KB18.2 KB115.0 KB0.0 KB/s5.2 KB47:40:04
8newsfeed.neva.ru0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:04
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
TOTAL: 910.0 MB6.7 MB16.7 MB0.0 KB/s3.4 KB429:07:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 8242.1 MB 384 2 000:07:09
2shell3 6 5321.7 MB 52 0 003:04:22
TOTAL: 2 292 13563.7 MB 436 2 003:14:15

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 8242.1 MB 384 2 000:07:09
2? 6 5321.7 MB 52 0 003:04:44
TOTAL: 2 292 13563.7 MB 436 2 003:11:53

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 2.792 1.668 0.00003:04:22
ftupet.com 44.906 7.844 0.00000:07:09
d54c176a7.access.telenet.be 33.828 7.766 0.00000:01:13
84-105-134-55.cable.dynamic.v4.ziggo.nl 29.212 6.620 0.00000:01:03
? 0.456 0.118 0.00000:00:22
92-249-193-162.pool.digikabel.hu 0.200 0.042 0.00000:00:01
185.100.87.250 0.254 0.062 0.00000:00:01
h185-20-99-125.host.redstation.co.uk 0.200 0.044 0.00000:00:00
115.133.21.148 0.206 0.050 0.00000:00:00
TOTAL: 9 112.054 24.214 0.00003:14:15

NNRP no permission clients:

SystemConn
d54c176a7.access.telenet.be 336
84-105-134-55.cable.dynamic.v4.ziggo.nl 290
92-249-193-162.pool.digikabel.hu 2
185.100.87.250 2
115.133.21.148 2
h185-20-99-125.host.redstation.co.uk 2
TOTAL: 6 634

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1000 73.7%
2misc 146 10.8%
3comp 142 10.5%
4sci 30 2.2%
5alt 18 1.3%
6soc 16 1.2%
7chi 4 0.3%
TOTAL: 7 1356100.0%

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

NewsgroupCount
1rec.arts.tv 568
2rec.food.cooking 350
3misc.phone.mobile.iphone 106
4comp.sys.mac.system 62
5comp.mobile.android 50
6rec.bicycles.tech 42
7misc.consumers.frugal-living 40
8sci.electronics.repair 30
9rec.gardens.edible 26
10soc.history.what-if 16
11comp.mail.pine 16
12alt.online-service.comcast 14
13rec.radio.broadcasting 6
14comp.sys.apple2 4
15comp.sys.mac.vintage 4
16rec.radio.swap 4
17chi.weather 4
18alt.cellular.t-mobile 2
19rec.heraldry 2
20comp.mobile.ipad 2
21comp.security.ssh 2
22comp.protocols.dns.bind 2
23rec.outdoors.rv-travel 2
24alt.internet.wireless 2
TOTAL: 24 1356