Daily Usenet report for news.ripco.com

Sep 9 00:01:02 -- Sep 9 23:57:48

Unknown entries from news log file:

First 24 / 24 lines (100.0%)

Sep 9 02:42:23 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 05:27:04 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 05:28:52 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 08:09:55 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 08:11:08 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 08:32:11 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 08:44:26 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 09:01:40 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 09:23:01 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 09:24:55 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 09:27:34 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 10:11:43 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 10:18:29 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 10:34:49 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 12:50:52 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 12:59:22 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 13:45:54 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 13:48:33 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 14:21:51 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 19:09:56 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 19:51:07 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 21:33:56 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 23:13:19 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping
Sep 9 23:38:03 remote5bge0 overchan[3007]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 18640 32.1%3.6 MB 39.9%
innd 18631 32.0%2.9 MB 31.8%
nnrpd 17247 29.7%1.8 MB 19.6%
innfeed 3592 6.2%800.8 KB 8.6%
overchan 24 0.0%4.0 KB 0.0%
controlchan 6 0.0%1.1 KB 0.0%
TOTAL: 6 58140 100.0%9.1 MB100.0%

History cache:

ReasonCount%Count
Cache misses 174406 51.3%
Do not exist 165850 48.7%
Positive hits 0 0.0%
Negative hits 0 0.0%
TOTAL: 4 340256 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.472 0.0%36860 0.000 0.013 0.067
article logging00:00:04.206 0.0%37292 0.000 0.113 22.205
article parse00:00:01.432 0.0%94708 0.000 0.015 0.036
article write00:01:32.146 0.1%28528 1.653 3.230 106.577
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.438 0.0%354446 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.518 0.0%341650 0.002 0.007 0.020
history sync00:00:00.238 0.0%758 0.000 0.314 4.000
history write00:00:03.548 0.0%36862 0.027 0.096 2.462
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:36:32.710 99.8%389492 242.902 440.042 1121.776
nntp read00:00:03.330 0.0%389710 0.002 0.009 0.017
overview write00:00:00.258 0.0%28528 0.000 0.009 0.079
perl filter00:00:56.014 0.0%36860 1.223 1.520 3.723
python filter00:00:01.456 0.0%36866 0.000 0.039 0.091
site send00:00:02.076 0.0%85552 0.006 0.024 0.067
TOTAL: 47:41:54.22647:39:20.842 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.334 0.0%28604 0.000 0.012 0.060
article prepare00:00:00.006 0.0%17640 0.000 0.000 0.011
article read00:00:00.444 0.0%3618 0.000 0.123 73.000
backlog stats00:00:00.312 0.0%434070 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.616 0.0%277272 0.002 0.009 0.019
data write00:00:04.682 0.0%248848 0.009 0.019 0.036
idle47:52:46.954100.0%434070 206.144 397.095 939.571
status file00:00:13.302 0.0%350 1.000 38.006 813.571
TOTAL: 47:53:27.60447:53:08.650100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:10:56.670 97.4%18006 0.333 636.270150001.750
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:08.710 0.1%838474 0.000 0.010 0.042
readart00:00:18.148 0.2%2322 0.250 7.816 71.000
TOTAL: 03:16:00.04803:11:23.528 97.6%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
grisbi-control@grisbi.org002002
TOTAL002002

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1148 37204 13094 19318 4792 35%94:39:40
2news.snarked.org 66 27654 6756 20494 404 24%64:09:36
3newsfeed.neva.ru 612 33308 5898 26244 1166 17%276:22:34
4feeder3.feed-ams.uzoreto.com 574 9364 1580 7098 686 16%47:20:06
5newsfeed.fu-berlin.de 2 14912 596 14268 48 3%49:03:28
6ripco-out.news.tnetconsulting.net 4 8764 146 8598 20 1%18:00:56
7news-in.rahul.net 574 15192 144 15042 6 0%47:20:54
8news.lightlink.com 2 7190 98 6796 296 1%44:03:02
9ripco-out.feeder.erje.net 576 29714 38 28976 700 0%47:18:16
10news.cmpublishers.com 50 7884 10 7858 16 0%48:00:04
11localhost 152 6 6 0 0 100%00:00:00
TOTAL: 11 3760 191192 28366 154692 8134 14%736:18:36
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com31.8 MB0.0 KB9.7 MB41.6 MB 76%2.4 KB
2news.snarked.org22.8 MB0.0 KB1.9 MB24.7 MB 92%3.5 KB
3newsfeed.neva.ru17.5 MB0.0 KB3.2 MB20.8 MB 84%3.0 KB
4feeder3.feed-ams.uzoreto.com4.2 MB0.0 KB1.5 MB5.8 MB 73%2.6 KB
5news-in.rahul.net3.8 MB0.0 KB17.9 KB3.8 MB 99%26.0 KB
6ripco-out.news.tnetconsulting.net3.5 MB0.0 KB114.3 KB3.6 MB 96%22.4 KB
7newsfeed.fu-berlin.de2.3 MB0.0 KB1.2 MB3.5 MB 66%5.6 KB
8news.lightlink.com1.3 MB0.0 KB11.5 MB12.8 MB 10%33.3 KB
9ripco-out.feeder.erje.net80.2 KB0.0 KB4.7 MB4.8 MB 1%6.6 KB
10news.cmpublishers.com20.5 KB0.0 KB52.5 KB73.0 KB 28%2.8 KB
11localhost12.1 KB0.0 KB0.0 KB12.1 KB100%2.0 KB
TOTAL: 1187.5 MB0.0 KB33.9 MB121.4 MB 72%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 9 00:01:02 - 00:59:59 317 2.2% 0.091.3 MB 2.8% 0.37
Sep 9 01:00:00 - 01:59:59 415 2.9% 0.121.5 MB 3.3% 0.43
Sep 9 02:00:00 - 02:59:59 579 4.1% 0.161.6 MB 3.6% 0.46
Sep 9 03:00:00 - 03:59:59 612 4.3% 0.171.6 MB 3.6% 0.46
Sep 9 04:00:00 - 04:59:59 786 5.5% 0.222.0 MB 4.5% 0.58
Sep 9 05:00:00 - 05:59:59 685 4.8% 0.191.8 MB 4.0% 0.51
Sep 9 06:00:00 - 06:59:59 623 4.4% 0.171.9 MB 4.1% 0.53
Sep 9 07:00:00 - 07:59:59 776 5.4% 0.222.2 MB 4.9% 0.62
Sep 9 08:00:00 - 08:59:59 781 5.5% 0.222.2 MB 4.9% 0.63
Sep 9 09:00:00 - 09:59:59 836 5.9% 0.232.8 MB 6.1% 0.79
Sep 9 10:00:00 - 10:59:59 734 5.1% 0.201.9 MB 4.3% 0.55
Sep 9 11:00:00 - 11:59:59 671 4.7% 0.191.8 MB 3.9% 0.51
Sep 9 12:00:00 - 12:59:59 783 5.5% 0.222.5 MB 5.4% 0.70
Sep 9 13:00:00 - 13:59:59 816 5.7% 0.233.4 MB 7.6% 0.98
Sep 9 14:00:00 - 14:59:59 702 4.9% 0.201.8 MB 3.9% 0.50
Sep 9 15:00:00 - 15:59:59 731 5.1% 0.202.1 MB 4.7% 0.60
Sep 9 16:00:00 - 16:59:59 662 4.6% 0.182.2 MB 4.8% 0.62
Sep 9 17:00:00 - 17:59:59 627 4.4% 0.172.7 MB 6.0% 0.77
Sep 9 18:00:00 - 18:59:59 456 3.2% 0.131.7 MB 3.8% 0.49
Sep 9 19:00:00 - 19:59:59 335 2.3% 0.091.1 MB 2.4% 0.31
Sep 9 20:00:00 - 20:59:59 354 2.5% 0.102.1 MB 4.7% 0.60
Sep 9 21:00:00 - 21:59:59 329 2.3% 0.09871.3 KB 1.9% 0.24
Sep 9 22:00:00 - 22:59:59 310 2.2% 0.09945.3 KB 2.0% 0.26
Sep 9 23:00:00 - 23:57:48 336 2.4% 0.101.2 MB 2.7% 0.35
TOTAL: 23:56:46 14256 100.0% 0.1745.1 MB 100.0% 0.54
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2407 1705 0 0 5 0 0 0 697
2newsfeed.neva.ru 632 336 0 0 0 0 0 0 296
3ripco-out.feeder.erje.net 350 348 0 0 0 0 0 0 2
4feeder3.feed-ams.uzoreto.com 346 171 0 0 1 0 0 0 174
5news.snarked.org 245 101 0 0 0 0 0 0 144
6news.lightlink.com 190 0 0 0 0 0 0 0 190
7newsfeed.fu-berlin.de 162 0 0 0 0 0 0 0 162
8ripco-out.news.tnetconsulting.net 41 16 0 0 0 0 0 0 25
9news.cmpublishers.com 8 0 0 0 0 0 0 0 8
10news-in.rahul.net 3 0 0 0 0 0 0 0 3
TOTAL: 10 4384 2677 0 0 6 0 0 0 1701

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1920
alt.binaries.ftd 146
fido7.su.pol.news 45
alt.bestjobsusa.computer.jobs 34
scinet.stats 31
de.soc.arbeitslos 27
scot.general 25
alt.binaries.pictures.earlmiller 20
alt.jobs.jobsearch 19
free.software 19
free.willey 18
alt.binaries.e-book 18
at.jobs 15
alt.my.job.sucks 15
alt.binaries.pictures.rail 14
alt.binaries.adan 10
free.it.scienza.fisica 10
alt.binaries.pictures.purebeauty 9
aus.jobs 9
it.tlc.cellulari.android 9
TOTAL: 137 2677

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

GroupsCount
pl.praca.oferowana 5
bit.listserv.catholic 1
TOTAL: 2 6

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1582
Too many newsgroups 904
EMP (phl) 204
Binary in non-binary group 120
Scoring filter (11) 52
Scoring filter (9) 52
Scoring filter (8) 32
Scoring filter (14) 6
Scoring filter (15) 4
Scoring filter (18) 4
Scoring filter (10) 4
HTML post 2
Scoring filter (13) 2
Angle-bracket bot 2
Scoring filter (16) 2
Scoring filter (12) 2
TOTAL: 16 2974

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 46
ripco-out.feed.uzoreto.com 16
feeder3.feed-ams.uzoreto.com 16
TOTAL: 3 78
Including strange strings
newsfeed.fu-berlin.de 140
newsfeed.neva.ru 51
news.snarked.org 11
ripco-out.feed.uzoreto.com 11
feeder3.feed-ams.uzoreto.com 3
TOTAL: 5 216
No colon-space in header
ripco-out.feeder.erje.net 1
TOTAL: 1 1
TOTAL: 3 295

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 10176 1700 5312 18 0 0 16%47:34:10
2rahul.net 23138 752 12942 30 0 0 3%47:40:08
3news.cmpublishers.com 41224 130 26714 1258 0 14 0%47:34:14
4feeder.erje.net 27802 48 27184 220 0 0 0%47:40:08
5news.snarked.org 36194 38 21342 6 0 52 0%47:40:08
6fu-berlin.de 25876 36 25838 0 0 0 0%47:40:08
7news.uzoreto.com 13908 32 13390 42 0 0 0%47:53:12
8news.tnetconsulting.net 43886 26 28066 0 0 26 0%47:40:04
9newsfeed.neva.ru 23136 22 22504 20 0 0 0%47:40:08
10remote6hme0.ripco.com 10 4 0 6 0 0 40%01:42:10
TOTAL: 10 245350 2788 183292 1600 0 92 1%430:44:30
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com6.3 MB197.5 KB6.5 MB0.0 KB/s3.9 KB47:34:10
2rahul.net2.7 MB68.2 KB2.7 MB0.0 KB/s3.6 KB47:40:08
3news.cmpublishers.com561.5 KB3.2 MB3.7 MB0.0 KB/s2.8 KB47:34:14
4feeder.erje.net173.4 KB555.1 KB728.5 KB0.0 KB/s2.7 KB47:40:08
5news.snarked.org150.3 KB208.3 KB358.6 KB0.0 KB/s8.1 KB47:40:08
6fu-berlin.de127.8 KB0.0 KB127.8 KB0.0 KB/s3.5 KB47:40:08
7news.tnetconsulting.net113.1 KB0.0 KB113.1 KB0.0 KB/s4.3 KB47:40:04
8news.uzoreto.com72.4 KB158.2 KB230.7 KB0.0 KB/s3.1 KB47:53:12
9newsfeed.neva.ru57.9 KB68.3 KB126.2 KB0.0 KB/s3.0 KB47:40:08
10remote6hme0.ripco.com10.1 KB21.6 KB31.7 KB0.0 KB/s3.2 KB01:42:10
TOTAL: 1010.2 MB4.4 MB14.6 MB0.0 KB/s3.4 KB430:44:30
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 9522.4 MB 378 2 000:09:59
2shell3.ripco.com 22 416925.0 KB 55 4 003:05:50
TOTAL: 2 308 13683.3 MB 433 6 003:15:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 9522.4 MB 378 2 000:09:59
2*.ripco.com 22 416925.0 KB 55 4 003:05:50
TOTAL: 2 308 13683.3 MB 433 6 003:15:49

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3.ripco.com 5.622 3.166 0.00003:05:50
ftupet.com 45.300 21.208 0.00000:09:59
91.241.19.60 0.200 0.052 0.00000:00:00
worker-18.sfj.censys-scanner.com 0.202 0.048 0.00000:00:00
TOTAL: 4 51.324 24.474 0.00003:15:50

NNRP no permission clients:

SystemConn
91.241.19.60 2
worker-18.sfj.censys-scanner.com 2
TOTAL: 2 4

NNRP unrecognized commands (by host):

SystemConn
shell3.ripco.com 2
TOTAL: 1 2

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 1120 81.8%
2misc 114 8.3%
3alt 51 3.7%
4comp 48 3.5%
5chi 18 1.3%
6sci 18 1.3%
TOTAL: 6 1369100.0%

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

NewsgroupCount
1rec.food.cooking 586
2rec.arts.tv 478
3misc.phone.mobile.iphone 94
4rec.bicycles.tech 46
5alt.comp.os.windows-10 30
6comp.mobile.android 26
7misc.consumers.frugal-living 20
8sci.electronics.repair 18
9comp.sys.mac.system 16
10chi.weather 10
11rec.gardens.edible 8
12alt.stupidity 8
13alt.comp.google 5
14chi.general 4
15chi.eats 4
16comp.sys.mac.apps 4
17alt.tv.southpark 2
18alt.online-service.comcast 2
19comp.sys.sun.hardware 2
20alt.tasteless 2
21rec.radio.swap 2
22alt.vacation.las-vegas 2
TOTAL: 22 1369