Daily Usenet report for news.ripco.com

Dec 25 00:01:01 -- Dec 25 23:57:18

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Dec 25 05:37:37 remote5bge0 overchan[28785]: [ID 702911 news.error] input line too long, skipping
Dec 25 09:04:48 remote5bge0 overchan[28785]: [ID 702911 news.error] input line too long, skipping
Dec 25 09:06:26 remote5bge0 overchan[28785]: [ID 702911 news.error] input line too long, skipping
Dec 25 11:29:31 remote5bge0 overchan[28785]: [ID 702911 news.error] input line too long, skipping
Dec 25 11:42:04 remote5bge0 overchan[28785]: [ID 702911 news.error] input line too long, skipping
Dec 25 13:39:47 remote5bge0 overchan[28785]: [ID 702911 news.error] input line too long, skipping
Dec 25 15:10:20 remote5bge0 overchan[28785]: [ID 702911 news.error] input line too long, skipping
Dec 25 16:29:44 remote5bge0 overchan[28785]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
innd 17720 34.3%2.8 MB 35.3%
nnrpd 17228 33.3%1.8 MB 22.8%
inn 13067 25.3%2.5 MB 31.7%
innfeed 3664 7.1%813.5 KB 10.2%
overchan 8 0.0%1.2 KB 0.0%
controlchan 8 0.0%1.7 KB 0.0%
TOTAL: 6 51695 100.0%7.8 MB100.0%

History cache:

ReasonCount%Count
Cache misses 119424 50.4%
Do not exist 117656 49.6%
Positive hits 0 0.0%
Negative hits 0 0.0%
TOTAL: 4 237080 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%62 0.000 0.000 0.000
article cleanup00:00:00.362 0.0%26038 0.000 0.014 0.071
article logging00:00:00.510 0.0%26198 0.000 0.019 0.073
article parse00:00:01.184 0.0%73994 0.000 0.016 0.044
article write00:00:58.682 0.0%18690 1.286 3.140 24.465
artlog/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
data move00:00:00.310 0.0%247340 0.000 0.001 0.007
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.152 0.0%238836 0.001 0.009 0.024
history sync00:00:00.216 0.0%818 0.000 0.264 2.000
history write00:00:02.774 0.0%26040 0.000 0.107 1.765
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:58:04.786 99.9%277482 306.635 622.328 1496.451
nntp read00:00:02.366 0.0%275564 0.001 0.009 0.020
overview write00:00:00.206 0.0%18690 0.000 0.011 0.100
perl filter00:00:43.450 0.0%26034 1.264 1.669 2.920
python filter00:00:01.086 0.0%26036 0.000 0.042 0.127
site send00:00:01.478 0.0%56014 0.000 0.026 0.071
TOTAL: 48:02:21.20247:59:59.562 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.126 0.0%19328 0.000 0.007 0.071
article prepare00:00:00.004 0.0%9992 0.000 0.000 0.012
article read00:00:00.408 0.0%1980 0.000 0.206 10.143
backlog stats00:00:00.250 0.0%298488 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.862 0.0%182662 0.003 0.010 0.029
data write00:00:03.278 0.0%164184 0.004 0.020 0.039
idle47:37:29.700100.0%298488 328.429 574.394 1504.802
status file00:00:01.904 0.0%372 0.000 5.118 29.500
TOTAL: 47:37:56.91047:37:37.532100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:42:51.124 96.7%17770 5.000 347.278 25146.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:07.360 0.1%665200 0.000 0.011 0.053
readart00:00:18.934 0.3%1934 3.227 9.790 43.500
TOTAL: 01:46:20.00801:43:17.418 97.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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
control@usenet-fr.news.eu.org002002
TOTAL002002

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1148 24968 8684 12082 4202 34%94:38:28
2newsfeed.neva.ru 648 27553 4446 22283 824 16%276:16:14
3news.snarked.org 64 17026 2684 14000 342 15%67:55:18
4feeder3.feed-ams.uzoreto.com 574 6996 1756 4644 596 25%47:29:14
5ripco-out.news.tnetconsulting.net 4 16092 460 15608 24 2%37:07:24
6newsfeed.fu-berlin.de 2 9894 398 9494 2 4%45:50:48
7news.lightlink.com 2 4682 226 4296 160 4%44:03:24
8ripco-out.feeder.erje.net 574 19598 62 18470 1066 0%47:18:14
9news-in.rahul.net 574 9424 60 9320 44 0%47:18:10
10news.cmpublishers.com 50 5682 12 5666 4 0%47:59:58
11localhost 148 2 2 0 0 100%00:00:02
TOTAL: 11 3788 141917 18790 115863 7264 13%755:57:14
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com22.8 MB0.0 KB8.6 MB31.3 MB 72%2.5 KB
2newsfeed.neva.ru12.3 MB13.1 KB2.6 MB14.9 MB 82%2.9 KB
3ripco-out.news.tnetconsulting.net12.0 MB0.0 KB978.1 KB13.0 MB 92%27.5 KB
4news.snarked.org8.4 MB0.0 KB1.1 MB9.5 MB 88%3.2 KB
5feeder3.feed-ams.uzoreto.com5.0 MB0.0 KB1.6 MB6.6 MB 75%2.9 KB
6newsfeed.fu-berlin.de4.0 MB0.0 KB10.8 KB4.1 MB 99%10.4 KB
7news.lightlink.com2.9 MB0.0 KB6.8 MB9.6 MB 29%25.6 KB
8news-in.rahul.net1.5 MB0.0 KB3.0 MB4.5 MB 33%44.7 KB
9ripco-out.feeder.erje.net217.4 KB0.0 KB12.6 MB12.8 MB 1%11.6 KB
10news.cmpublishers.com50.4 KB0.0 KB10.2 KB60.6 KB 83%3.8 KB
11localhost4.5 KB0.0 KB0.0 KB4.5 KB100%2.2 KB
TOTAL: 1169.2 MB13.1 KB37.2 MB106.4 MB 65%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 25 00:01:01 - 00:59:59 185 2.0% 0.05508.6 KB 1.4% 0.14
Dec 25 01:00:00 - 01:59:59 206 2.2% 0.06826.3 KB 2.3% 0.23
Dec 25 02:00:00 - 02:59:59 305 3.3% 0.081.0 MB 2.9% 0.29
Dec 25 03:00:00 - 03:59:59 485 5.2% 0.132.8 MB 8.0% 0.81
Dec 25 04:00:00 - 04:59:59 455 4.9% 0.131.2 MB 3.4% 0.35
Dec 25 05:00:00 - 05:59:59 435 4.7% 0.121.7 MB 4.6% 0.47
Dec 25 06:00:00 - 06:59:59 465 5.0% 0.131.4 MB 3.8% 0.39
Dec 25 07:00:00 - 07:59:59 512 5.5% 0.141.6 MB 4.4% 0.45
Dec 25 08:00:00 - 08:59:59 495 5.3% 0.142.1 MB 5.9% 0.60
Dec 25 09:00:00 - 09:59:59 526 5.6% 0.152.5 MB 6.9% 0.70
Dec 25 10:00:00 - 10:59:59 529 5.7% 0.151.7 MB 4.7% 0.48
Dec 25 11:00:00 - 11:59:59 493 5.3% 0.141.4 MB 3.9% 0.40
Dec 25 12:00:00 - 12:59:59 493 5.3% 0.142.0 MB 5.6% 0.57
Dec 25 13:00:00 - 13:59:59 502 5.4% 0.142.2 MB 6.1% 0.62
Dec 25 14:00:00 - 14:59:59 529 5.7% 0.152.0 MB 5.5% 0.56
Dec 25 15:00:00 - 15:59:59 479 5.1% 0.131.5 MB 4.1% 0.42
Dec 25 16:00:00 - 16:59:59 418 4.5% 0.121.6 MB 4.5% 0.46
Dec 25 17:00:00 - 17:59:59 321 3.4% 0.091.2 MB 3.4% 0.35
Dec 25 18:00:00 - 18:59:59 258 2.8% 0.07816.8 KB 2.2% 0.23
Dec 25 19:00:00 - 19:59:59 217 2.3% 0.06640.1 KB 1.8% 0.18
Dec 25 20:00:00 - 20:59:59 179 1.9% 0.05683.2 KB 1.9% 0.19
Dec 25 21:00:00 - 21:59:59 222 2.4% 0.06756.7 KB 2.1% 0.21
Dec 25 22:00:00 - 22:59:59 341 3.7% 0.091.7 MB 4.7% 0.48
Dec 25 23:00:00 - 23:57:18 274 2.9% 0.082.1 MB 6.0% 0.64
TOTAL: 23:56:17 9324 100.0% 0.1135.7 MB 100.0% 0.42
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2110 1593 0 0 3 0 0 0 514
2ripco-out.feeder.erje.net 533 530 0 0 0 0 0 0 3
3newsfeed.neva.ru 425 281 0 1 2 0 0 0 141
4feeder3.feed-ams.uzoreto.com 298 161 0 0 1 0 0 0 136
5news.snarked.org 174 67 0 0 2 0 0 0 105
6news.lightlink.com 83 2 0 0 0 0 0 0 81
7newsfeed.fu-berlin.de 56 0 0 0 0 0 0 0 56
8ripco-out.news.tnetconsulting.net 41 28 0 0 0 0 0 0 13
9news-in.rahul.net 22 0 0 0 0 0 0 0 22
10news.cmpublishers.com 1 0 0 0 0 0 0 0 1
TOTAL: 10 3743 2662 0 1 8 0 0 0 1072

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1890
alt.binaries.ftd 375
fido7.su.pol.news 31
scinet.stats 30
alt.binaries.pictures.purebeauty 28
alt.bestjobsusa.computer.jobs 26
it.tlc.cellulari.android 15
free.willey 13
alt.jobs.jobsearch 11
linux.debian.maint.emacsen 10
alt.binaries.pictures.scenic 9
grisbi.devel 9
alt.binaries.e-book 9
at.jobs 8
alt.binaries.sounds.mp3.christmas 7
alt.my.job.sucks 7
att.jobs 6
alt.binaries.pictures.nude.celebrities.fake 6
alt.binaries.sounds.lossless 5
free.it.scienza.fisica 5
TOTAL: 120 2662

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

GroupsCount
pl.praca.oferowana 6
bit.listserv.catholic 1
rec.travel.resorts.all-inclusive 1
TOTAL: 3 8

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 874
Too many newsgroups 774
EMP (phl) 216
Binary in non-binary group 72
Scoring filter (15) 10
Scoring filter (11) 10
Scoring filter (8) 8
Scoring filter (13) 4
Scoring filter (10) 4
Scoring filter (12) 2
Scoring filter (9) 2
Scoring filter (14) 2
TOTAL: 12 1978

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feeder.erje.net 1
TOTAL: 1 1
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Including strange strings
newsfeed.fu-berlin.de 47
newsfeed.neva.ru 21
ripco-out.feed.uzoreto.com 10
news.snarked.org 2
TOTAL: 4 80
No colon-space in header
ripco-out.feeder.erje.net 1
feeder3.feed-ams.uzoreto.com 1
TOTAL: 2 2
TOTAL: 4 101

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 6110 602 3866 12 0 0 9%47:40:00
2rahul.net 15314 524 8138 28 0 0 3%47:40:02
3feeder.erje.net 22072 308 17070 250 0 0 1%47:40:04
4news.cmpublishers.com 28152 60 17682 480 0 52 0%47:40:04
5news.tnetconsulting.net 27680 40 18002 8 0 492 0%47:45:10
6news.uzoreto.com 8032 34 7692 54 0 0 0%47:40:04
7fu-berlin.de 16544 28 16516 0 0 0 0%47:40:06
8news.snarked.org 25566 26 15638 10 0 270 0%47:50:50
9newsfeed.neva.ru 14578 14 14054 14 0 0 0%47:40:06
10remote6hme0.ripco.com 8 2 0 6 0 0 25%01:32:12
TOTAL: 10 164056 1638 118658 862 0 814 0%430:48:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com4.8 MB96.4 KB4.9 MB0.0 KB/s8.2 KB47:40:00
2rahul.net2.6 MB53.9 KB2.6 MB0.0 KB/s4.9 KB47:40:02
3feeder.erje.net1.1 MB597.4 KB1.7 MB0.0 KB/s3.1 KB47:40:04
4news.tnetconsulting.net249.4 KB15.9 KB265.3 KB0.0 KB/s5.5 KB47:45:10
5news.snarked.org194.4 KB20.5 KB214.9 KB0.0 KB/s6.0 KB47:50:50
6newsfeed.neva.ru171.7 KB24.5 KB196.2 KB0.0 KB/s7.0 KB47:40:06
7news.cmpublishers.com166.5 KB1.4 MB1.5 MB0.0 KB/s2.9 KB47:40:04
8news.uzoreto.com119.4 KB135.0 KB254.4 KB0.0 KB/s2.9 KB47:40:04
9fu-berlin.de67.7 KB0.0 KB67.7 KB0.0 KB/s2.4 KB47:40:06
10remote6hme0.ripco.com2.3 KB27.7 KB30.0 KB0.0 KB/s3.7 KB01:32:12
TOTAL: 109.5 MB2.3 MB11.8 MB0.0 KB/s4.8 KB430:48:38
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 8322.6 MB 418 2 000:08:31
2shell3.ripco.com 10 268823.0 KB 28 0 001:37:28
TOTAL: 2 296 11003.4 MB 446 2 001:46:10

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 8322.6 MB 418 2 000:08:31
2*.ripco.com 10 268823.0 KB 28 0 001:37:28
TOTAL: 2 296 11003.4 MB 446 2 001:45:59

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3.ripco.com 3.342 2.318 0.00001:37:28
ftupet.com 46.158 21.426 0.00000:08:31
212.90.61.180 1.006 0.258 0.00000:00:08
scan-141.security.ipip.net 0.202 0.050 0.00000:00:00
192.241.204.244 0.206 0.052 0.00000:00:00
131.0.23.239 0.204 0.052 0.00000:00:00
worker-18.sfj.censys-scanner.com 0.200 0.052 0.00000:00:00
TOTAL: 7 51.318 24.208 0.00001:46:10

NNRP no permission clients:

SystemConn
212.90.61.180 10
192.241.204.244 2
worker-18.sfj.censys-scanner.com 2
131.0.23.239 2
scan-141.security.ipip.net 2
TOTAL: 5 18

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3.ripco.com 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 992 90.2%
2comp 56 5.1%
3misc 14 1.3%
4sci 14 1.3%
5chi 14 1.3%
6alt 8 0.7%
7news 2 0.2%
TOTAL: 7 1100100.0%

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

NewsgroupCount
1rec.arts.tv 574
2rec.food.cooking 274
3rec.bicycles.tech 130
4comp.sys.mac.system 42
5sci.electronics.repair 14
6misc.phone.mobile.iphone 12
7chi.weather 10
8comp.mobile.android 10
9alt.online-service.comcast 8
10rec.radio.shortwave 6
11chi.general 4
12rec.radio.broadcasting 4
13rec.gardens.edible 2
14misc.consumers.frugal-living 2
15rec.radio.swap 2
16news.admin.hierarchies 2
17comp.sys.ibm.pc.games.rpg 2
18comp.risks 2
TOTAL: 18 1100