Daily Usenet report for news.ripco.com

Oct 3 00:01:01 -- Oct 3 23:57:36

Unknown entries from news log file:

First 36 / 36 lines (100.0%)

Oct 3 01:21:42 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 03:25:41 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 03:35:37 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 04:41:02 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 09:00:36 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 09:14:06 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 09:50:22 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 09:56:53 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 10:17:43 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 10:34:15 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 10:37:16 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 10:59:36 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 11:12:00 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 12:33:13 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 13:19:05 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 13:51:27 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 14:32:12 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 14:46:29 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 14:54:35 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 15:38:56 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 15:54:44 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 16:01:35 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 16:38:44 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 16:50:09 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 17:09:58 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 18:07:47 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 20:34:37 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 20:40:29 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 20:50:14 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 21:25:45 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 21:37:33 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 21:44:40 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 21:55:51 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 22:21:31 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 23:02:15 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping
Oct 3 23:11:03 remote5bge0 overchan[94]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
innd 18113 31.9%2.8 MB 31.9%
inn 17902 31.5%3.5 MB 39.2%
nnrpd 17186 30.2%1.8 MB 20.0%
innfeed 3630 6.4%797.9 KB 8.8%
overchan 36 0.1%5.7 KB 0.1%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 56869 100.0%8.9 MB100.0%

History cache:

ReasonCount%Count
Cache misses 167082 51.7%
Do not exist 156286 48.3%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 323368 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%128 0.000 0.000 0.000
article cleanup00:00:00.490 0.0%35526 0.000 0.014 0.063
article logging00:00:00.832 0.0%35880 0.000 0.023 0.352
article parse00:00:01.212 0.0%89102 0.000 0.014 0.044
article write00:01:29.292 0.1%26324 1.478 3.392 113.935
artlog/artparse00:00:00.000 0.0%8 0.000 0.000 0.000
data move00:00:00.418 0.0%334930 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.962 0.0%325026 0.000 0.009 0.019
history sync00:00:00.152 0.0%774 0.000 0.196 1.000
history write00:00:04.060 0.0%35532 0.034 0.114 4.250
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:56:46.062 99.8%370264 270.459 466.170 1291.856
nntp read00:00:02.810 0.0%369362 0.002 0.008 0.020
overview write00:00:00.256 0.0%26324 0.000 0.010 0.051
perl filter00:00:51.970 0.0%35524 1.203 1.463 2.853
python filter00:00:01.556 0.0%35526 0.000 0.044 0.103
site send00:00:01.968 0.0%78928 0.000 0.025 0.057
TOTAL: 48:01:53.74447:59:24.040 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.318 0.0%26694 0.000 0.012 0.039
article prepare00:00:00.010 0.0%27776 0.000 0.000 0.010
article read00:00:00.612 0.0%4800 0.000 0.128 12.000
backlog stats00:00:00.308 0.0%435160 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.578 0.0%268660 0.000 0.010 0.019
data write00:00:04.736 0.0%243024 0.007 0.019 0.040
idle47:52:32.878100.0%435160 231.564 396.068 934.878
status file00:00:02.120 0.0%354 0.667 5.989 35.000
TOTAL: 47:53:04.76447:52:43.560100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:05:50.588 98.3%18120 18.206 615.375 36347.105
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:07.312 0.1%665120 0.000 0.011 0.047
readart00:00:21.066 0.2%2516 2.300 8.373 36.500
TOTAL: 03:09:05.63603:06:18.966 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 34046 11312 17056 5678 33%94:47:22
2news.snarked.org 66 25114 5874 18998 242 23%61:18:40
3newsfeed.neva.ru 588 30736 4296 25154 1286 13%271:35:54
4feeder3.feed-ams.uzoreto.com 574 11042 3150 7034 858 28%47:28:34
5newsfeed.fu-berlin.de 2 14070 542 13518 10 3%47:28:50
6ripco-out.news.tnetconsulting.net 4 27234 486 26648 100 1%45:25:24
7news.lightlink.com 2 6186 320 5704 162 5%38:09:06
8ripco-out.feeder.erje.net 574 28650 60 27856 734 0%47:17:52
9news-in.rahul.net 574 15198 60 15078 60 0%47:19:40
10news.cmpublishers.com 50 8100 8 8076 16 0%48:00:00
11localhost 148 2 2 0 0 100%00:00:06
TOTAL: 11 3730 200378 26110 165122 9146 13%748:51:28
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com27.4 MB0.0 KB11.6 MB39.0 MB 70%2.4 KB
2news.snarked.org18.3 MB0.0 KB733.4 KB19.0 MB 96%3.2 KB
3newsfeed.neva.ru13.4 MB3.4 KB3.3 MB16.7 MB 80%3.1 KB
4ripco-out.news.tnetconsulting.net8.4 MB0.0 KB1.9 MB10.3 MB 81%18.0 KB
5feeder3.feed-ams.uzoreto.com7.9 MB0.0 KB1.9 MB9.8 MB 80%2.5 KB
6news.lightlink.com2.1 MB0.0 KB6.3 MB8.4 MB 24%17.8 KB
7newsfeed.fu-berlin.de1.7 MB0.0 KB335.9 KB2.0 MB 83%3.7 KB
8news-in.rahul.net1.3 MB0.0 KB2.0 MB3.3 MB 39%28.1 KB
9ripco-out.feeder.erje.net115.8 KB0.0 KB4.9 MB5.0 MB 2%6.4 KB
10news.cmpublishers.com23.3 KB0.0 KB32.9 KB56.2 KB 41%2.3 KB
11localhost3.4 KB0.0 KB0.0 KB3.4 KB100%1.7 KB
TOTAL: 1180.6 MB3.4 KB32.8 MB113.5 MB 71%3.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 3 00:01:01 - 00:59:59 310 2.4% 0.091.9 MB 4.6% 0.55
Oct 3 01:00:00 - 01:59:59 380 2.9% 0.11987.4 KB 2.3% 0.27
Oct 3 02:00:00 - 02:59:59 454 3.5% 0.131.4 MB 3.3% 0.39
Oct 3 03:00:00 - 03:59:59 554 4.2% 0.151.4 MB 3.5% 0.41
Oct 3 04:00:00 - 04:59:59 591 4.5% 0.161.6 MB 3.9% 0.46
Oct 3 05:00:00 - 05:59:59 546 4.2% 0.151.5 MB 3.7% 0.44
Oct 3 06:00:00 - 06:59:59 564 4.3% 0.161.7 MB 4.1% 0.48
Oct 3 07:00:00 - 07:59:59 752 5.7% 0.211.9 MB 4.7% 0.55
Oct 3 08:00:00 - 08:59:59 654 5.0% 0.181.7 MB 4.2% 0.50
Oct 3 09:00:00 - 09:59:59 641 4.9% 0.182.5 MB 6.2% 0.72
Oct 3 10:00:00 - 10:59:59 750 5.7% 0.212.2 MB 5.4% 0.63
Oct 3 11:00:00 - 11:59:59 724 5.5% 0.202.2 MB 5.3% 0.62
Oct 3 12:00:00 - 12:59:59 783 6.0% 0.222.0 MB 4.8% 0.56
Oct 3 13:00:00 - 13:59:59 665 5.1% 0.182.0 MB 4.7% 0.56
Oct 3 14:00:00 - 14:59:59 752 5.7% 0.212.5 MB 6.1% 0.71
Oct 3 15:00:00 - 15:59:59 627 4.8% 0.172.2 MB 5.3% 0.62
Oct 3 16:00:00 - 16:59:59 719 5.5% 0.202.6 MB 6.3% 0.75
Oct 3 17:00:00 - 17:59:59 540 4.1% 0.151.7 MB 4.0% 0.48
Oct 3 18:00:00 - 18:59:59 512 3.9% 0.141.5 MB 3.6% 0.43
Oct 3 19:00:00 - 19:59:59 367 2.8% 0.10933.0 KB 2.2% 0.26
Oct 3 20:00:00 - 20:59:59 403 3.1% 0.111.1 MB 2.6% 0.31
Oct 3 21:00:00 - 21:59:59 340 2.6% 0.092.5 MB 6.0% 0.70
Oct 3 22:00:00 - 22:59:59 252 1.9% 0.07729.7 KB 1.7% 0.20
Oct 3 23:00:00 - 23:57:36 249 1.9% 0.07632.1 KB 1.5% 0.18
TOTAL: 23:56:35 13129 100.0% 0.1541.3 MB 100.0% 0.49
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2850 2130 0 0 8 0 0 0 712
2newsfeed.neva.ru 678 437 0 1 2 0 0 0 238
3feeder3.feed-ams.uzoreto.com 429 249 0 0 1 0 0 0 179
4ripco-out.feeder.erje.net 365 360 0 0 0 0 0 0 5
5newsfeed.fu-berlin.de 131 0 0 0 0 0 0 0 131
6news.snarked.org 129 48 0 0 2 0 0 0 79
7news.lightlink.com 108 3 0 0 0 0 0 0 105
8ripco-out.news.tnetconsulting.net 44 30 0 0 0 0 0 0 14
9news-in.rahul.net 30 1 0 0 0 0 0 0 29
10news.cmpublishers.com 9 3 0 0 0 0 0 0 6
TOTAL: 10 4773 3261 0 1 13 0 0 0 1498

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2581
alt.binaries.ftd 151
alt.binaries.pictures.rail 36
scinet.stats 27
fido7.su.pol.news 21
alt.binaries.e-book 18
alt.bestjobsusa.computer.jobs 17
free.it.scienza.fisica 15
free.willey 14
scot.general 13
alt.slack.goathead 11
alt.binaries.pictures.wallpaper 10
alt.binaries.pictures.purebeauty 9
alt.binaries.test.yenc 9
ba.forsale 8
it.comp.os.win.windows10 8
linux.debian.maint.hams 8
alt.binaries.sounds.lossless.classical 7
at.jobs 7
alt.binaries.pictures.scenic 7
TOTAL: 190 3261

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

GroupsCount
fr.comp.securite 6
christnet.bible 3
pl.praca.oferowana 3
bit.listserv.catholic 1
TOTAL: 4 13

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1416
Too many newsgroups 968
EMP (phl) 150
Binary in non-binary group 68
Scoring filter (8) 18
Scoring filter (10) 8
Scoring filter (9) 4
HTML post 2
Scoring filter (13) 2
TOTAL: 9 2636

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 20
ripco-out.feed.uzoreto.com 2
feeder3.feed-ams.uzoreto.com 2
TOTAL: 3 24
Including strange strings
newsfeed.fu-berlin.de 126
newsfeed.neva.ru 34
ripco-out.feed.uzoreto.com 9
news.snarked.org 7
TOTAL: 4 176
No colon-space in header
feeder3.feed-ams.uzoreto.com 4
TOTAL: 1 4
TOTAL: 3 204

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1feeder.erje.net 30304 2028 22996 514 0 0 6%47:46:24
2news.lightlink.com 10194 1652 5790 34 0 0 16%47:40:02
3rahul.net 24768 690 13136 2 0 0 2%47:33:12
4news.cmpublishers.com 38914 152 24162 1658 0 272 0%47:40:02
5fu-berlin.de 23340 26 23314 0 0 0 0%47:40:02
6newsfeed.neva.ru 22872 26 21822 32 0 0 0%47:40:02
7news.snarked.org 34366 18 20070 22 0 196 0%47:39:34
8news.uzoreto.com 12062 16 11450 28 0 0 0%47:40:02
9news.tnetconsulting.net 40138 14 25654 4 0 6 0%47:50:08
10remote6hme0.ripco.com 8 2 0 6 0 0 25%01:32:06
TOTAL: 10 236966 4624 168394 2300 0 474 1%430:41:34
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com6.5 MB169.6 KB6.6 MB0.0 KB/s4.0 KB47:40:02
2feeder.erje.net5.6 MB1.5 MB7.2 MB0.0 KB/s2.9 KB47:46:24
3rahul.net3.2 MB4.1 KB3.2 MB0.0 KB/s4.8 KB47:33:12
4news.cmpublishers.com610.8 KB4.5 MB5.1 MB0.0 KB/s2.9 KB47:40:02
5fu-berlin.de102.3 KB0.0 KB102.3 KB0.0 KB/s3.9 KB47:40:02
6newsfeed.neva.ru92.7 KB77.2 KB169.9 KB0.0 KB/s2.9 KB47:40:02
7news.snarked.org75.5 KB55.4 KB130.8 KB0.0 KB/s3.3 KB47:39:34
8news.tnetconsulting.net67.5 KB17.1 KB84.6 KB0.0 KB/s4.7 KB47:50:08
9news.uzoreto.com46.6 KB119.1 KB165.7 KB0.0 KB/s3.8 KB47:40:02
10remote6hme0.ripco.com8.5 KB19.3 KB27.9 KB0.0 KB/s3.5 KB01:32:06
TOTAL: 1016.3 MB6.5 MB22.8 MB0.0 KB/s3.4 KB430:41:34
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 10242.4 MB 452 2 000:08:20
2shell3.ripco.com 10 4661.3 MB 36 0 003:00:35
TOTAL: 2 296 14903.7 MB 488 2 003:08:56

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 10242.4 MB 452 2 000:08:20
2*.ripco.com 10 4661.3 MB 36 0 003:00:35
TOTAL: 2 296 14903.7 MB 488 2 003:08:55

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3.ripco.com 3.444 2.316 0.00003:00:35
ftupet.com 46.886 21.228 0.00000:08:20
scan-141.security.ipip.net 0.204 0.048 0.00000:00:00
TOTAL: 3 50.534 23.592 0.00003:08:56

NNRP no permission clients:

SystemConn
scan-141.security.ipip.net 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 1350 90.6%
2comp 44 3.0%
3alt 32 2.1%
4misc 26 1.7%
5sci 20 1.3%
6chi 10 0.7%
7soc 6 0.4%
8news 2 0.1%
TOTAL: 8 1490100.0%

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

NewsgroupCount
1rec.arts.tv 780
2rec.food.cooking 440
3rec.bicycles.tech 94
4comp.sys.mac.system 38
5misc.phone.mobile.iphone 26
6alt.games.warcraft 24
7sci.electronics.repair 20
8rec.radio.shortwave 10
9rec.audio.tubes 10
10rec.radio.broadcasting 8
11chi.weather 6
12soc.history.what-if 6
13rec.gardens.edible 6
14alt.online-service.comcast 6
15comp.sys.mac.apps 4
16rec.radio.swap 2
17chi.forsale 2
18chi.general 2
19news.admin.hierarchies 2
20comp.mobile.android 2
21alt.sports.football.pro.kc-chiefs 2
TOTAL: 21 1490