Daily Usenet report for news.ripco.com

Nov 7 00:01:03 -- Nov 7 23:57:47

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Nov 7 02:03:43 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 02:22:15 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 02:42:49 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 02:43:55 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 02:50:16 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 04:30:41 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 04:33:53 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 12:39:53 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 13:00:57 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 14:10:08 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 15:06:50 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 15:43:21 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping
Nov 7 21:39:31 remote5bge0 overchan[3897]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 33904 40.1%4.2 MB 33.3%
inn 23900 28.3%4.1 MB 32.8%
innd 23055 27.3%3.5 MB 28.1%
innfeed 3576 4.2%758.8 KB 5.9%
overchan 13 0.0%1.8 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 84450 100.0%12.6 MB100.0%

History cache:

ReasonCount%Count
Cache misses 211796 52.9%
Do not exist 188602 47.1%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 400398 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%374 0.000 0.000 0.000
article cleanup00:00:00.466 0.0%47316 0.000 0.010 0.041
article logging00:00:00.908 0.0%47732 0.000 0.019 0.298
article parse00:00:01.836 0.0%113514 0.003 0.016 0.040
article write00:00:45.548 0.0%34748 0.345 1.311 17.705
artlog/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
data move00:00:00.302 0.0%413756 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:04.624 0.0%402830 0.000 0.011 0.672
history sync00:00:00.294 0.0%738 0.000 0.398 3.333
history write00:00:06.610 0.0%47306 0.000 0.140 4.430
hiswrite/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
idle47:38:54.930 99.9%448114 198.878 382.793 1199.818
nntp read00:00:02.634 0.0%446832 0.001 0.006 0.015
overview write00:00:00.224 0.0%34748 0.000 0.006 0.044
perl filter00:01:03.172 0.0%47282 0.965 1.336 2.845
python filter00:00:01.144 0.0%47290 0.000 0.024 0.078
site send00:00:01.984 0.0%104190 0.006 0.019 0.041
TOTAL: 47:41:43.79047:41:04.676100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.496 0.0%34986 0.000 0.014 0.040
article prepare00:00:00.014 0.0%26456 0.000 0.001 0.021
article read00:00:00.518 0.0%5188 0.000 0.100 7.300
backlog stats00:00:00.250 0.0%416758 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.924 0.0%276564 0.000 0.011 0.023
data write00:00:03.672 0.0%242736 0.003 0.015 0.030
idle47:51:30.626100.0%416758 247.758 413.407 1078.361
status file00:00:06.972 0.0%324 5.000 21.519 98.500
TOTAL: 47:52:00.46847:51:45.472100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:09.038 0.0%655148 0.000 0.014 1.000
idle58:04:04.342 99.6%692024 18.452 302.077397666.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:02:07.374 0.1%2644438 0.000 0.048 0.368
readart00:00:28.774 0.0%4838 0.000 5.947 41.000
TOTAL: 58:19:43.92658:06:49.528 99.6%----

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 64 43816 9816 29664 4336 22%59:59:38
2feeder3.feed-ams.uzoreto.com 574 29030 9298 16636 3096 32%47:28:20
3ripco-out.feed.uzoreto.com 1154 31780 7228 22940 1612 22%94:48:16
4newsfeed.neva.ru 578 61560 7186 52554 1820 11%281:10:28
5newsfeed.fu-berlin.de 2 13820 682 13094 44 4%40:41:20
6news.lightlink.com 2 12274 298 11162 814 2%47:55:04
7news-in.rahul.net 574 20838 210 20570 58 1%47:18:32
8ripco-out.feeder.erje.net 574 36960 66 36086 808 0%47:17:08
9news.cmpublishers.com 50 8734 18 8712 4 0%48:00:04
10localhost 152 8 8 0 0 100%00:00:00
TOTAL: 10 3724 258820 34810 211418 12592 13%714:38:50
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org29.0 MB0.0 KB16.7 MB45.7 MB 63%3.3 KB
2feeder3.feed-ams.uzoreto.com23.3 MB15.5 KB6.4 MB29.7 MB 78%2.5 KB
3newsfeed.neva.ru21.3 MB10.4 KB6.7 MB28.1 MB 75%3.2 KB
4ripco-out.feed.uzoreto.com18.0 MB0.0 KB4.7 MB22.6 MB 79%2.6 KB
5news-in.rahul.net8.9 MB0.0 KB2.3 MB11.2 MB 79%42.6 KB
6news.lightlink.com3.8 MB10.3 KB29.4 MB33.1 MB 11%30.5 KB
7newsfeed.fu-berlin.de2.5 MB0.0 KB266.3 KB2.7 MB 90%3.8 KB
8ripco-out.feeder.erje.net168.1 KB0.0 KB5.8 MB6.0 MB 2%7.0 KB
9news.cmpublishers.com43.6 KB0.0 KB7.2 KB50.8 KB 85%2.3 KB
10localhost17.1 KB0.0 KB0.0 KB17.1 KB100%2.1 KB
TOTAL: 10106.9 MB36.3 KB72.2 MB179.2 MB 59%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 7 00:01:03 - 00:59:59 439 2.5% 0.121.2 MB 2.2% 0.35
Nov 7 01:00:00 - 01:59:59 611 3.5% 0.171.7 MB 3.2% 0.48
Nov 7 02:00:00 - 02:59:59 558 3.2% 0.151.5 MB 2.9% 0.44
Nov 7 03:00:00 - 03:59:59 760 4.4% 0.212.1 MB 3.8% 0.58
Nov 7 04:00:00 - 04:59:59 695 4.0% 0.192.0 MB 3.8% 0.57
Nov 7 05:00:00 - 05:59:59 728 4.2% 0.201.9 MB 3.6% 0.55
Nov 7 06:00:00 - 06:59:59 780 4.5% 0.222.6 MB 4.8% 0.73
Nov 7 07:00:00 - 07:59:59 918 5.3% 0.262.4 MB 4.5% 0.68
Nov 7 08:00:00 - 08:59:59 949 5.5% 0.262.7 MB 5.1% 0.77
Nov 7 09:00:00 - 09:59:59 1091 6.3% 0.303.0 MB 5.6% 0.86
Nov 7 10:00:00 - 10:59:59 1092 6.3% 0.303.5 MB 6.5% 0.99
Nov 7 11:00:00 - 11:59:59 978 5.6% 0.273.5 MB 6.5% 0.98
Nov 7 12:00:00 - 12:59:59 980 5.6% 0.272.8 MB 5.2% 0.80
Nov 7 13:00:00 - 13:59:59 957 5.5% 0.272.9 MB 5.4% 0.82
Nov 7 14:00:00 - 14:59:59 982 5.6% 0.273.2 MB 5.9% 0.90
Nov 7 15:00:00 - 15:59:59 905 5.2% 0.253.0 MB 5.7% 0.86
Nov 7 16:00:00 - 16:59:59 852 4.9% 0.242.7 MB 5.1% 0.77
Nov 7 17:00:00 - 17:59:59 605 3.5% 0.171.8 MB 3.3% 0.51
Nov 7 18:00:00 - 18:59:59 531 3.1% 0.152.0 MB 3.8% 0.58
Nov 7 19:00:00 - 19:59:59 448 2.6% 0.121.6 MB 3.0% 0.45
Nov 7 20:00:00 - 20:59:59 377 2.2% 0.101.2 MB 2.2% 0.34
Nov 7 21:00:00 - 21:59:59 361 2.1% 0.101.0 MB 2.0% 0.30
Nov 7 22:00:00 - 22:59:59 386 2.2% 0.111.7 MB 3.3% 0.50
Nov 7 23:00:00 - 23:57:47 411 2.4% 0.121.4 MB 2.7% 0.43
TOTAL: 23:56:44 17394 100.0% 0.2053.5 MB 100.0% 0.64
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 2146 60 0 0 2 0 0 0 2084
2feeder3.feed-ams.uzoreto.com 1552 1162 0 3 2 0 0 0 385
3newsfeed.neva.ru 977 426 0 2 4 0 0 0 545
4ripco-out.feed.uzoreto.com 811 436 0 0 1 0 0 0 374
5news.lightlink.com 417 2 0 1 0 0 0 0 414
6ripco-out.feeder.erje.net 404 398 0 0 0 0 0 0 6
7newsfeed.fu-berlin.de 168 0 0 0 0 0 0 0 168
8news-in.rahul.net 29 0 0 0 0 0 0 0 29
9news.cmpublishers.com 2 1 0 0 0 0 0 0 1
TOTAL: 9 6506 2485 0 6 9 0 0 0 4006

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1617
alt.binaries.ftd 175
perl.cpan.uploads 51
fido7.su.pol.news 44
it.tlc.cellulari.android 42
alt.bestjobsusa.computer.jobs 38
alt.binaries.e-book 26
alt.my.job.sucks 23
alt.music.pink-floyd.publius 23
alt.jobs.jobsearch 22
at.jobs 20
alt.binaries.drwho 17
att.jobs 15
it.comp.os.win.windows10 13
free.willey 12
alt.binaries.nl 12
free.ucp 12
aus.jobs 12
gmane.linux.alsa.devel 11
alt.binaries.test.yenc 11
TOTAL: 128 2485

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

GroupsCount
pl.praca.oferowana 7
alt.christnet.religion 1
alt.penthouse.sex.masterbation 1
TOTAL: 3 9

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5342
Too many newsgroups 1298
Binary in non-binary group 640
EMP (phl) 120
Attached HTML file 76
Scoring filter (16) 34
Scoring filter (8) 22
Scoring filter (9) 16
Scoring filter (10) 10
HTML post 6
EMP (fsl) 4
Scoring filter (11) 2
TOTAL: 12 7570

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feed.uzoreto.com 5
ripco-out.feeder.erje.net 3
feeder3.feed-ams.uzoreto.com 3
TOTAL: 3 11
Bad Message-ID's offered
ripco-out.feeder.erje.net 20
feeder3.feed-ams.uzoreto.com 2
TOTAL: 2 22
Huge articles
newsfeed.fu-berlin.de 2
TOTAL: 1 2
Including strange strings
newsfeed.fu-berlin.de 127
newsfeed.neva.ru 72
ripco-out.feed.uzoreto.com 5
feeder3.feed-ams.uzoreto.com 5
TOTAL: 4 209
No colon-space in header
ripco-out.feed.uzoreto.com 1
TOTAL: 1 1
TOTAL: 5 245

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 11056 2524 5658 16 0 0 22%47:40:02
2rahul.net 25854 848 14594 54 0 0 3%47:40:06
3news.uzoreto.com 18632 114 17406 306 0 0 0%47:40:06
4news.cmpublishers.com 47116 108 32552 1708 0 22 0%47:40:02
5news.snarked.org 41524 100 24640 22 0 164 0%47:40:38
6feeder.erje.net 33574 98 32268 496 0 0 0%47:40:34
7newsfeed.neva.ru 28138 44 27266 118 0 0 0%47:40:06
8fu-berlin.de 31144 44 31100 0 0 74 0%47:40:06
9remote6hme0.ripco.com 0 0 0 0 0 14 0%47:40:02
TOTAL: 9 237038 3880 185484 2720 0 274 1%429:01:42
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com7.6 MB39.7 KB7.6 MB0.0 KB/s3.1 KB47:40:02
2rahul.net3.3 MB125.0 KB3.5 MB0.0 KB/s3.9 KB47:40:06
3news.uzoreto.com476.5 KB1.1 MB1.6 MB0.0 KB/s3.8 KB47:40:06
4news.cmpublishers.com382.0 KB4.5 MB4.9 MB0.0 KB/s2.7 KB47:40:02
5feeder.erje.net365.3 KB1.8 MB2.2 MB0.0 KB/s3.7 KB47:40:34
6news.snarked.org351.2 KB53.6 KB404.8 KB0.0 KB/s3.3 KB47:40:38
7newsfeed.neva.ru205.9 KB325.0 KB531.0 KB0.0 KB/s3.3 KB47:40:06
8fu-berlin.de147.9 KB0.0 KB147.9 KB0.0 KB/s3.4 KB47:40:06
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
TOTAL: 912.8 MB7.9 MB20.8 MB0.0 KB/s3.2 KB429:01:42
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1108-237-13-94.lightspeed.cicril.sbcglobal.net 4 28028.1 MB 26 0 000:06:52
2ftupet.com 286 8382.3 MB 416 4 000:06:43
3shell3 18 352939.4 KB 34 4 002:45:51
475-151-74-61-independence.hfc.comcastbusiness.net 8 43.9 KB 4 0 000:14:25
TOTAL: 4 316 399611.3 MB 480 8 058:19:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.lightspeed.cicril.sbcglobal.net 4 28028.1 MB 26 0 000:06:52
2*.com 286 8382.3 MB 416 4 000:06:43
3? 18 352939.4 KB 34 4 002:45:52
4*.hfc.comcastbusiness.net 8 43.9 KB 4 0 000:14:25
5*.dsl.bell.ca 20 00.0 KB 0 0 001:20:47
6*.direct-adsl.nl 4 00.0 KB 0 0 001:13:10
7*.tpgi.com.au 64 00.0 KB 0 0 000:48:02
8*.cpe.net.cable.rogers.com 56 00.0 KB 0 0 039:42:59
9*.us-west-1.compute.amazonaws.com 2 00.0 KB 0 0 000:00:01
10unresolved 76 00.0 KB 0 0 012:00:38
11*.compute-1.amazonaws.com 2 00.0 KB 0 0 000:00:01
TOTAL: 11 540 399611.3 MB 480 8 058:19:33

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
cpe10c37b534438-cma84e3fd13080.cpe.net.cable.rogers.com 18.190 20.848 0.00039:42:59
185.242.4.116 1.816 0.672 0.00005:45:15
173.44.36.250 2.790 0.588 0.00004:49:41
shell3 5.484 2.588 0.00002:45:51
nrbaon0431w-lp140-01-70-30-111-64.dsl.bell.ca 2.366 1.022 0.00001:20:47
ip4da2a296.direct-adsl.nl 0.528 0.312 0.00001:13:10
59-102-44-201.tpgi.com.au 6.608 1.706 0.00000:48:02
104.37.31.170 1.190 0.592 0.00000:45:11
66.11.163.217 0.398 0.082 0.00000:40:04
75-151-74-61-independence.hfc.comcastbusiness.net 1.682 1.266 0.00000:14:25
108-237-13-94.lightspeed.cicril.sbcglobal.net 0.834 0.352 0.00000:06:52
ftupet.com 45.986 7.502 0.00000:06:43
95.174.67.196 2.540 0.422 0.00000:00:25
ec2-54-161-225-35.compute-1.amazonaws.com 0.208 0.042 0.00000:00:01
ec2-54-241-150-200.us-west-1.compute.amazonaws.com 0.242 0.062 0.00000:00:01
? 0.234 0.056 0.00000:00:00
TOTAL: 16 91.096 38.112 0.00058:19:33

Curious NNRP server explorers [Top 20]:

SystemConn
59-102-44-201.tpgi.com.au 64
cpe10c37b534438-cma84e3fd13080.cpe.net.cable.rogers.com 56
173.44.36.250 28
nrbaon0431w-lp140-01-70-30-111-64.dsl.bell.ca 20
95.174.67.196 20
185.242.4.116 16
104.37.31.170 8
ip4da2a296.direct-adsl.nl 4
66.11.163.217 4
ec2-54-241-150-200.us-west-1.compute.amazonaws.com 2
ec2-54-161-225-35.compute-1.amazonaws.com 2
TOTAL: 11 224

NNRP unrecognized commands (by host):

SystemConn
shell3 4
TOTAL: 1 4

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 4
TOTAL: 1 4

NNRP client timeouts [Top 20]:

SystemConnPeer
173.44.36.250 28 0
185.242.4.116 16 0
shell3 6 0
ip4da2a296.direct-adsl.nl 4 0
66.11.163.217 4 0
TOTAL: 5 58 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1958 49.0%
2comp 934 23.4%
3misc 556 13.9%
4sci 322 8.1%
5alt 88 2.2%
6chi 72 1.8%
7news 34 0.9%
8ba 24 0.6%
9seattle 6 0.2%
10it 2 0.1%
TOTAL: 10 3996100.0%

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

NewsgroupCount
1rec.arts.tv 1610
2comp.sys.mac.system 730
3misc.phone.mobile.iphone 520
4sci.electronics.repair 322
5rec.food.cooking 320
6comp.mobile.ipad 150
7comp.mail.sendmail 40
8chi.general 38
9misc.consumers.frugal-living 36
10chi.eats 30
11alt.online-service.comcast 24
12ba.food 24
13alt.sex.pictures.female 20
14rec.bicycles.tech 18
15news.software.nntp 18
16alt.games.warcraft 16
17news.groups 12
18alt.config 8
19alt.stupidity 8
20comp.sys.mac.apps 6
21rec.radio.broadcasting 6
22comp.unix.bsd.freebsd.misc 6
23seattle.general 6
24alt.tasteless 6
25rec.radio.swap 4
26news.admin.net-abuse.email 4
27chi.weather 4
28alt.autos.ford 2
29it.comp.os.os2 2
30alt.christnet 2
31alt.comp.google 2
32comp.protocols.dns.bind 2
TOTAL: 32 3996