Daily Usenet report for news.ripco.com

Nov 12 00:01:03 -- Nov 12 23:57:49

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Nov 12 01:25:59 remote5bge0 overchan[23600]: [ID 702911 news.error] input line too long, skipping
Nov 12 01:25:59 remote5bge0 overchan[23600]: [ID 702911 news.error] input line too long, skipping
Nov 12 01:25:59 remote5bge0 overchan[23600]: [ID 702911 news.error] input line too long, skipping
Nov 12 08:04:04 remote5bge0 overchan[23600]: [ID 702911 news.error] input line too long, skipping
Nov 12 09:52:13 remote5bge0 overchan[23600]: [ID 702911 news.error] input line too long, skipping
Nov 12 09:53:46 remote5bge0 overchan[23600]: [ID 702911 news.error] input line too long, skipping
Nov 12 13:16:46 remote5bge0 overchan[23600]: [ID 702911 news.error] input line too long, skipping
Nov 12 18:25:26 remote5bge0 overchan[23600]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 33900 39.8%4.2 MB 33.2%
inn 24458 28.7%4.2 MB 32.8%
innd 23176 27.2%3.6 MB 28.2%
innfeed 3656 4.3%753.6 KB 5.8%
overchan 8 0.0%1.1 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 85200 100.0%12.7 MB100.0%

History cache:

ReasonCount%Count
Cache misses 218842 54.0%
Do not exist 186314 46.0%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 405156 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.002 0.0%314 0.000 0.006 0.333
article cleanup00:00:00.534 0.0%48508 0.000 0.011 0.048
article logging00:00:00.794 0.0%48994 0.000 0.016 0.229
article parse00:00:02.040 0.0%122570 0.002 0.017 0.048
article write00:00:42.262 0.0%33850 0.449 1.249 26.569
artlog/artparse00:00:00.000 0.0%6 0.000 0.000 0.000
data move00:00:00.336 0.0%428654 0.000 0.001 0.004
hishave/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.074 0.0%406414 0.002 0.008 0.483
history sync00:00:00.290 0.0%764 0.000 0.380 3.000
history write00:00:04.754 0.0%48510 0.000 0.098 3.411
hiswrite/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
idle47:59:03.092 99.9%461822 170.199 374.047 1094.538
nntp read00:00:02.648 0.0%458982 0.001 0.006 0.015
overview write00:00:00.226 0.0%33850 0.000 0.007 0.034
perl filter00:01:01.966 0.0%48496 1.035 1.278 2.182
python filter00:00:01.176 0.0%48506 0.000 0.024 0.061
site send00:00:01.992 0.0%101488 0.004 0.020 0.040
TOTAL: 48:01:42.01448:01:05.186100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.160 0.0%39668 0.000 0.004 0.036
article prepare00:00:00.010 0.0%26632 0.000 0.000 0.013
article read00:00:00.604 0.0%5530 0.000 0.109 4.562
backlog stats00:00:00.210 0.0%395278 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.140 0.0%262494 0.000 0.008 0.020
data write00:00:03.480 0.0%229144 0.003 0.015 0.028
idle47:33:56.844100.0%395278 242.263 433.206 1705.162
status file00:00:05.848 0.0%338 0.000 17.302 46.000
TOTAL: 47:34:21.20847:34:09.296100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.574 0.0%42568 0.000 0.013 0.026
idle05:14:40.908 72.4%76964 0.000 245.321 25518.042
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:59.488 0.2%1375118 0.000 0.043 0.294
readart00:00:17.672 0.1%2230 0.000 7.925 38.000
TOTAL: 07:14:26.16805:15:58.642 72.7%----

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 42686 11364 26482 4840 26%94:37:22
2news.snarked.org 72 43694 10602 28892 4200 24%62:33:10
3newsfeed.neva.ru 564 66164 5898 58236 2030 8%283:33:42
4feeder3.feed-ams.uzoreto.com 574 18478 4354 13322 802 23%47:19:32
5newsfeed.fu-berlin.de 2 17290 856 16222 212 4%50:10:48
6news.lightlink.com 2 11530 294 9886 1350 2%43:34:34
7news-in.rahul.net 574 20648 256 20392 0 1%47:29:46
8ripco-out.feeder.erje.net 574 37448 82 36228 1138 0%47:18:04
9news.cmpublishers.com 46 9380 14 9346 20 0%42:21:32
10localhost 154 10 10 0 0 100%00:00:00
TOTAL: 10 3710 267328 33730 219006 14592 12%718:58:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org31.0 MB0.0 KB14.2 MB45.2 MB 68%3.1 KB
2ripco-out.feed.uzoreto.com30.7 MB0.0 KB9.8 MB40.5 MB 75%2.6 KB
3newsfeed.neva.ru18.8 MB10.7 KB8.0 MB26.8 MB 70%3.5 KB
4news-in.rahul.net12.2 MB0.0 KB0.0 KB12.2 MB100%48.7 KB
5feeder3.feed-ams.uzoreto.com10.8 MB0.0 KB1.8 MB12.7 MB 85%2.5 KB
6news.lightlink.com6.7 MB0.0 KB43.4 MB50.0 MB 13%31.2 KB
7newsfeed.fu-berlin.de3.2 MB0.0 KB1.5 MB4.7 MB 68%4.5 KB
8ripco-out.feeder.erje.net265.2 KB0.0 KB9.7 MB10.0 MB 2%8.4 KB
9news.cmpublishers.com30.5 KB0.0 KB36.9 KB67.4 KB 45%2.0 KB
10localhost18.4 KB0.0 KB0.0 KB18.4 KB100%1.8 KB
TOTAL: 10113.6 MB10.7 KB88.5 MB202.1 MB 56%4.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 12 00:01:03 - 00:59:59 404 2.4% 0.111.2 MB 2.2% 0.36
Nov 12 01:00:00 - 01:59:59 446 2.6% 0.121.7 MB 3.0% 0.48
Nov 12 02:00:00 - 02:59:59 597 3.5% 0.171.6 MB 2.8% 0.45
Nov 12 03:00:00 - 03:59:59 717 4.2% 0.202.2 MB 3.8% 0.62
Nov 12 04:00:00 - 04:59:59 723 4.3% 0.202.2 MB 3.9% 0.64
Nov 12 05:00:00 - 05:59:59 805 4.8% 0.222.3 MB 4.0% 0.64
Nov 12 06:00:00 - 06:59:59 720 4.3% 0.202.2 MB 3.9% 0.63
Nov 12 07:00:00 - 07:59:59 749 4.4% 0.212.1 MB 3.7% 0.59
Nov 12 08:00:00 - 08:59:59 868 5.1% 0.243.2 MB 5.6% 0.90
Nov 12 09:00:00 - 09:59:59 1011 6.0% 0.282.8 MB 4.9% 0.79
Nov 12 10:00:00 - 10:59:59 1135 6.7% 0.324.0 MB 7.0% 1.14
Nov 12 11:00:00 - 11:59:59 1028 6.1% 0.292.9 MB 5.2% 0.84
Nov 12 12:00:00 - 12:59:59 1027 6.1% 0.293.2 MB 5.7% 0.92
Nov 12 13:00:00 - 13:59:59 1018 6.0% 0.283.6 MB 6.3% 1.01
Nov 12 14:00:00 - 14:59:59 872 5.2% 0.242.8 MB 5.0% 0.80
Nov 12 15:00:00 - 15:59:59 954 5.6% 0.274.1 MB 7.1% 1.15
Nov 12 16:00:00 - 16:59:59 796 4.7% 0.223.3 MB 5.8% 0.94
Nov 12 17:00:00 - 17:59:59 666 3.9% 0.182.6 MB 4.5% 0.73
Nov 12 18:00:00 - 18:59:59 539 3.2% 0.152.5 MB 4.4% 0.71
Nov 12 19:00:00 - 19:59:59 417 2.5% 0.121.4 MB 2.5% 0.41
Nov 12 20:00:00 - 20:59:59 372 2.2% 0.101.2 MB 2.1% 0.34
Nov 12 21:00:00 - 21:59:59 411 2.4% 0.111.2 MB 2.1% 0.34
Nov 12 22:00:00 - 22:59:59 352 2.1% 0.101.5 MB 2.7% 0.43
Nov 12 23:00:00 - 23:57:49 273 1.6% 0.081.0 MB 1.8% 0.30
TOTAL: 23:56:46 16900 100.0% 0.2056.8 MB 100.0% 0.67
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2431 1873 0 0 3 0 0 0 555
2news.snarked.org 2102 63 0 0 4 0 0 0 2035
3newsfeed.neva.ru 1071 657 0 1 5 0 0 0 408
4news.lightlink.com 689 2 0 0 0 0 0 0 687
5ripco-out.feeder.erje.net 569 565 0 0 0 0 0 0 4
6feeder3.feed-ams.uzoreto.com 406 291 0 0 0 0 0 0 115
7newsfeed.fu-berlin.de 280 0 0 0 0 0 0 0 280
8news.cmpublishers.com 10 0 0 0 0 0 0 0 10
TOTAL: 8 7558 3451 0 1 12 0 0 0 4094

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2250
alt.binaries.ftd 279
alt.astrology.pro 59
fido7.su.pol.news 48
free.it.scienza.fisica 46
alt.bestjobsusa.computer.jobs 37
it.tlc.cellulari.android 32
alt.music.pink-floyd.publius 31
alt.bumbling.idiots.the.fbi 29
perl.cpan.uploads 27
it.comp.os.win.windows10 26
free.uk.astrology 24
at.jobs 22
free.willey 20
alt.binaries.nospam.female.bodyhair.pubes 20
fr.rec.loisirs-creatifs 20
alt.my.job.sucks 19
alt.binaries.pictures.scenic 18
alt.jobs.jobsearch 15
aus.jobs 15
TOTAL: 150 3451

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

GroupsCount
pl.praca.oferowana 6
christnet.bible 6
TOTAL: 2 12

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4060
Too many newsgroups 1720
Binary in non-binary group 1506
EMP (phl) 192
Scoring filter (16) 84
Scoring filter (8) 56
Attached HTML file 38
Scoring filter (9) 16
Scoring filter (10) 12
Scoring filter (11) 4
Scoring filter (18) 2
Scoring filter (12) 2
Scoring filter (17) 2
HTML post 2
TOTAL: 14 7696

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feed.uzoreto.com 3
feeder3.feed-ams.uzoreto.com 2
TOTAL: 2 5
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Huge articles
newsfeed.fu-berlin.de 2
TOTAL: 1 2
Including strange strings
newsfeed.fu-berlin.de 175
newsfeed.neva.ru 51
ripco-out.feed.uzoreto.com 9
feeder3.feed-ams.uzoreto.com 6
news.snarked.org 1
news.lightlink.com 1
TOTAL: 6 243
No colon-space in header
ripco-out.feed.uzoreto.com 1
TOTAL: 1 1
TOTAL: 5 269

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 10942 2902 5444 14 0 0 26%47:40:04
2rahul.net 27554 988 14734 28 0 10 3%47:47:40
3feeder.erje.net 31444 258 30054 318 0 0 0%47:49:36
4news.cmpublishers.com 42596 122 31574 1534 0 5850 0%47:38:06
5news.snarked.org 38204 108 22778 2 0 136 0%47:39:52
6fu-berlin.de 30652 30 30622 0 0 0 0%47:40:04
7newsfeed.neva.ru 28428 26 27532 192 0 0 0%47:38:08
8news.uzoreto.com 18244 22 17330 120 0 0 0%47:40:04
9remote6hme0.ripco.com 0 0 0 0 0 6 0%47:40:04
TOTAL: 9 228064 4456 180068 2208 0 6002 1%429:13:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com8.2 MB40.2 KB8.3 MB0.0 KB/s2.9 KB47:40:04
2rahul.net3.8 MB73.8 KB3.8 MB0.0 KB/s3.9 KB47:47:40
3feeder.erje.net810.2 KB1.2 MB2.0 MB0.0 KB/s3.5 KB47:49:36
4news.cmpublishers.com412.9 KB4.1 MB4.5 MB0.0 KB/s2.8 KB47:38:06
5news.snarked.org302.3 KB3.9 KB306.3 KB0.0 KB/s2.8 KB47:39:52
6fu-berlin.de98.8 KB0.0 KB98.8 KB0.0 KB/s3.3 KB47:40:04
7newsfeed.neva.ru87.6 KB518.1 KB605.7 KB0.0 KB/s2.8 KB47:38:08
8news.uzoreto.com72.7 KB453.7 KB526.4 KB0.0 KB/s3.7 KB47:40:04
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:04
TOTAL: 913.8 MB6.3 MB20.1 MB0.0 KB/s3.1 KB429:13:38
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 8822.2 MB 386 8 000:06:35
2shell3 26 3821.1 MB 46 2 001:55:23
3tor-gateway.afilias.info 10 70154.2 KB 12 0 001:51:29
475-151-74-61-independence.hfc.comcastbusiness.net 8 44.8 KB 4 0 000:11:54
5host138-57-dynamic.55-79-r.retail.telecomitalia.it 2 26.9 KB 2 0 000:03:50
TOTAL: 5 332 13403.5 MB 450 10 007:14:17

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 8822.2 MB 386 8 000:06:35
2? 26 3821.1 MB 46 2 001:55:23
3*.afilias.info 10 70154.2 KB 12 0 001:51:29
4*.hfc.comcastbusiness.net 8 44.8 KB 4 0 000:11:54
5*.55-79-r.retail.telecomitalia.it 2 26.9 KB 2 0 000:03:50
6*.dsl.bell.ca 20 00.0 KB 0 0 001:21:48
7*.hawaii.res.rr.com 12 00.0 KB 0 0 001:16:30
8*.7-3.cable.virginm.net 2 00.0 KB 0 0 000:12:49
9unresolved 88 00.0 KB 0 0 000:13:12
10*.dip0.t-ipconnect.de 4 00.0 KB 0 0 000:00:39
11*.14-131-66.mc.videotron.ca 8 00.0 KB 0 0 000:00:02
TOTAL: 11 466 13403.5 MB 450 10 007:14:17

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 5.902 2.502 0.00001:55:23
tor-gateway.afilias.info 2.296 0.680 0.00001:51:29
nrbaon0431w-lp140-01-70-30-111-64.dsl.bell.ca 2.366 1.002 0.00001:21:48
cpe-24-25-255-195.hawaii.res.rr.com 1.528 0.812 0.00001:16:30
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 0.278 0.162 0.00000:12:49
75-151-74-61-independence.hfc.comcastbusiness.net 1.674 1.242 0.00000:11:54
ftupet.com 44.566 7.492 0.00000:06:35
107.181.189.75 0.400 0.096 0.00000:05:24
185.232.20.154 1.204 0.304 0.00000:04:46
host138-57-dynamic.55-79-r.retail.telecomitalia.it 0.438 0.176 0.00000:03:50
176.67.84.202 0.806 0.178 0.00000:02:08
p4fcafc47.dip0.t-ipconnect.de 0.590 0.276 0.00000:00:39
197.185.99.15 4.374 0.954 0.00000:00:33
190.2.132.74 1.604 0.340 0.00000:00:13
62.112.10.131 0.540 0.094 0.00000:00:06
modemcable041.14-131-66.mc.videotron.ca 1.044 0.252 0.00000:00:02
TOTAL: 16 69.610 16.562 0.00007:14:17

Curious NNRP server explorers [Top 20]:

SystemConn
197.185.99.15 44
nrbaon0431w-lp140-01-70-30-111-64.dsl.bell.ca 20
190.2.132.74 16
185.232.20.154 12
cpe-24-25-255-195.hawaii.res.rr.com 12
modemcable041.14-131-66.mc.videotron.ca 8
176.67.84.202 8
107.181.189.75 4
62.112.10.131 4
p4fcafc47.dip0.t-ipconnect.de 4
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 2
TOTAL: 11 134

NNRP unrecognized commands (by host):

SystemConn
shell3 14
107.181.189.75 6
TOTAL: 2 20

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 14
À*À&À\x0fÀ\x05 1
u*UX \x7frò™önmê³\x1ehÂÖ 1
\300*\300&\300^O\300^E 1
u*UX\240\217^?r\362\231\366nm\352\263^^h\302\326 1
\x16\x03\x01\x02 1
^V^C^A^B 1
TOTAL: 7 20

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1004 74.9%
2comp 180 13.4%
3alt 122 9.1%
4misc 14 1.0%
5chi 6 0.4%
6ba 6 0.4%
7news 6 0.4%
8uk 2 0.1%
TOTAL: 8 1340100.0%

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

NewsgroupCount
1rec.arts.tv 574
2rec.food.cooking 374
3alt.comp.os.windows-10 84
4rec.bicycles.tech 46
5comp.sys.mac.system 40
6comp.lang.lisp 26
7comp.lang.scheme 24
8comp.mobile.android 22
9alt.stupidity 20
10comp.sys.mac.apps 16
11comp.dcom.telecom 12
12misc.consumers.frugal-living 12
13comp.unix.solaris 10
14comp.databases.postgresql 10
15comp.lang.mumps 6
16chi.weather 6
17ba.food 6
18alt.free.newsservers 6
19comp.unix.bsd.freebsd.misc 4
20comp.mobile.ipad 4
21rec.radio.swap 4
22alt.games.warcraft 4
23news.groups.questions 4
24alt.online-service.comcast 2
25uk.net.web.authoring 2
26alt.sex.erotica 2
27rec.bicycles.soc 2
28alt.fan.nikola-tesla 2
29comp.protocols.dns.bind 2
30alt.tasteless 2
31news.software.nntp 2
32comp.lang.prolog 2
33rec.radio.broadcasting 2
34rec.bicycles.misc 2
35comp.object.corba 2
36misc.phone.mobile.iphone 2
TOTAL: 36 1340