Daily Usenet report for news.ripco.com

Oct 13 00:01:02 -- Oct 13 23:57:58

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Oct 13 02:25:14 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 02:29:35 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 02:33:06 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 04:18:39 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 06:55:59 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 09:06:47 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 10:04:58 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 10:19:45 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 10:49:43 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping
Oct 13 13:19:12 remote5bge0 overchan[14857]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 36027 46.1%4.5 MB 39.0%
inn 20249 25.9%3.5 MB 30.3%
innd 18165 23.3%2.8 MB 24.1%
innfeed 3646 4.7%773.4 KB 6.5%
overchan 10 0.0%1.3 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 78099 100.0%11.6 MB100.0%

History cache:

ReasonCount%Count
Cache misses 186868 53.1%
Do not exist 164742 46.9%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 351610 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%246 0.000 0.000 0.000
article cleanup00:00:00.406 0.0%40020 0.000 0.010 0.042
article logging00:00:00.586 0.0%40576 0.000 0.014 0.043
article parse00:00:01.568 0.0%94850 0.000 0.017 0.035
article write00:00:42.962 0.0%29002 0.371 1.481 36.761
artlog/artparse00:00:00.000 0.0%6 0.000 0.000 0.000
data move00:00:00.240 0.0%354368 0.000 0.001 0.004
hishave/artparse00:00:00.000 0.0%6 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.890 0.0%353310 0.001 0.014 0.709
history sync00:00:00.356 0.0%794 0.000 0.448 4.000
history write00:00:06.892 0.0%40012 0.010 0.172 12.067
hiswrite/artparse00:00:00.000 0.0%6 0.000 0.000 0.000
idle47:59:28.768 99.9%390294 246.993 442.663 1134.234
nntp read00:00:02.218 0.0%386672 0.001 0.006 0.013
overview write00:00:00.242 0.0%29002 0.000 0.008 0.038
perl filter00:00:51.240 0.0%40012 1.000 1.281 1.911
python filter00:00:01.016 0.0%40020 0.000 0.025 0.093
site send00:00:01.772 0.0%86980 0.000 0.020 0.043
TOTAL: 48:02:04.84848:01:23.156100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.352 0.0%29116 0.000 0.012 0.045
article prepare00:00:00.000 0.0%14016 0.000 0.000 0.000
article read00:00:00.336 0.0%2670 0.000 0.126 12.000
backlog stats00:00:00.232 0.0%340538 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.478 0.0%228552 0.002 0.011 0.022
data write00:00:03.104 0.0%200100 0.002 0.016 0.043
idle47:53:10.552100.0%340538 286.429 506.230 1082.995
status file00:00:04.846 0.0%358 1.000 13.536 85.000
TOTAL: 47:53:33.52447:53:21.900100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:09.666 0.0%628074 0.000 0.015 1.000
idle324:46:21.434 95.7%663028 0.000 1763.397268830.551
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:03:52.748 0.0%3587222 0.000 0.065 80.176
readart00:00:19.330 0.0%3108 1.609 6.219 75.000
TOTAL: 339:25:55.294324:50:43.178 95.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 50966 12296 33922 4748 24%94:40:26
2news.snarked.org 60 37288 9294 25024 2970 24%59:44:18
3newsfeed.neva.ru 578 53854 5878 46026 1950 10%279:27:16
4newsfeed.fu-berlin.de 2 21108 1162 19866 80 5%59:11:12
5news-in.rahul.net 574 18298 208 18030 60 1%47:21:02
6news.lightlink.com 2 8268 164 7994 110 1%44:22:30
7news.cmpublishers.com 52 9866 46 9810 10 0%47:59:26
8ripco-out.feeder.erje.net 574 32466 38 31370 1058 0%47:18:32
9localhost 152 8 8 0 0 100%00:00:00
TOTAL: 9 3142 232122 29094 192042 10986 12%680:04:42
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com33.4 MB0.0 KB9.6 MB43.1 MB 77%2.6 KB
2news.snarked.org26.7 MB5.1 KB8.5 MB35.2 MB 75%2.9 KB
3newsfeed.neva.ru18.0 MB12.5 KB7.1 MB25.1 MB 71%3.3 KB
4news-in.rahul.net7.1 MB0.0 KB4.5 MB11.6 MB 60%44.4 KB
5newsfeed.fu-berlin.de4.8 MB0.0 KB3.8 MB8.5 MB 55%7.0 KB
6news.lightlink.com2.6 MB0.0 KB4.5 MB7.2 MB 36%26.7 KB
7news.cmpublishers.com122.0 KB0.0 KB19.4 KB141.4 KB 86%2.5 KB
8ripco-out.feeder.erje.net89.5 KB0.0 KB9.8 MB9.9 MB 0%9.2 KB
9localhost22.7 KB0.0 KB0.0 KB22.7 KB100%2.8 KB
TOTAL: 992.8 MB17.7 KB47.9 MB140.7 MB 65%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 13 00:01:02 - 00:59:59 414 2.9% 0.121.7 MB 3.6% 0.48
Oct 13 01:00:00 - 01:59:59 439 3.0% 0.121.7 MB 3.6% 0.47
Oct 13 02:00:00 - 02:59:59 635 4.4% 0.181.9 MB 4.2% 0.54
Oct 13 03:00:00 - 03:59:59 560 3.9% 0.161.8 MB 4.0% 0.52
Oct 13 04:00:00 - 04:59:59 532 3.7% 0.152.0 MB 4.3% 0.56
Oct 13 05:00:00 - 05:59:59 673 4.7% 0.191.7 MB 3.7% 0.49
Oct 13 06:00:00 - 06:59:59 587 4.1% 0.161.8 MB 3.9% 0.51
Oct 13 07:00:00 - 07:59:59 605 4.2% 0.171.8 MB 4.0% 0.52
Oct 13 08:00:00 - 08:59:59 736 5.1% 0.202.6 MB 5.6% 0.73
Oct 13 09:00:00 - 09:59:59 626 4.3% 0.171.9 MB 4.1% 0.53
Oct 13 10:00:00 - 10:59:59 824 5.7% 0.232.5 MB 5.5% 0.71
Oct 13 11:00:00 - 11:59:59 891 6.2% 0.252.7 MB 5.9% 0.77
Oct 13 12:00:00 - 12:59:59 794 5.5% 0.222.2 MB 4.7% 0.62
Oct 13 13:00:00 - 13:59:59 900 6.2% 0.252.6 MB 5.7% 0.75
Oct 13 14:00:00 - 14:59:59 830 5.7% 0.232.7 MB 5.9% 0.77
Oct 13 15:00:00 - 15:59:59 769 5.3% 0.213.0 MB 6.5% 0.85
Oct 13 16:00:00 - 16:59:59 692 4.8% 0.192.1 MB 4.7% 0.61
Oct 13 17:00:00 - 17:59:59 553 3.8% 0.151.5 MB 3.3% 0.43
Oct 13 18:00:00 - 18:59:59 522 3.6% 0.141.5 MB 3.3% 0.43
Oct 13 19:00:00 - 19:59:59 472 3.3% 0.131.4 MB 3.2% 0.41
Oct 13 20:00:00 - 20:59:59 381 2.6% 0.111.3 MB 2.8% 0.36
Oct 13 21:00:00 - 21:59:59 360 2.5% 0.101.1 MB 2.3% 0.30
Oct 13 22:00:00 - 22:59:59 352 2.4% 0.101.3 MB 2.8% 0.36
Oct 13 23:00:00 - 23:57:58 319 2.2% 0.091.0 MB 2.2% 0.30
TOTAL: 23:56:56 14466 100.0% 0.1745.8 MB 100.0% 0.54
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2386 1827 0 0 0 0 0 0 559
2news.snarked.org 1484 87 0 1 0 0 0 0 1396
3newsfeed.neva.ru 1023 547 0 3 3 0 0 0 470
4ripco-out.feeder.erje.net 529 523 0 0 0 0 0 0 6
5newsfeed.fu-berlin.de 252 0 0 0 0 0 0 0 252
6news.lightlink.com 73 0 0 0 0 0 0 0 73
7news-in.rahul.net 30 0 0 0 0 0 0 0 30
8news.cmpublishers.com 6 0 0 0 0 0 0 0 6
TOTAL: 8 5783 2984 0 4 3 0 0 0 2792

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2092
alt.binaries.ftd 279
alt.astrology.pro 71
alt.binaries.pictures.rail 29
fido7.su.pol.news 24
de.soc.arbeitslos 22
it.tlc.cellulari.android 19
alt.binaries.pictures.wallpaper 19
free.it.scienza.fisica 18
perl.cpan.uploads 17
it.comp.os.win.windows10 16
free.uk.astrology 13
alt.binaries.nl 12
alt.binaries.drwho 12
free.ucp 12
alt.binaries.sounds.karaoke 12
alt.binaries.pictures.scenic 11
grisbi.bugsreports 11
gmane.linux.alsa.devel 10
alt.binaries.e-book 10
TOTAL: 148 2984

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2954
Too many newsgroups 1640
EMP (phl) 158
Binary in non-binary group 134
Scoring filter (8) 56
Scoring filter (16) 38
Attached HTML file 20
Scoring filter (10) 14
Scoring filter (12) 4
Scoring filter (18) 2
Scoring filter (17) 2
Scoring filter (9) 2
TOTAL: 12 5024

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Huge articles
newsfeed.fu-berlin.de 6
TOTAL: 1 6
Including strange strings
newsfeed.fu-berlin.de 217
newsfeed.neva.ru 49
ripco-out.feed.uzoreto.com 11
news.snarked.org 1
TOTAL: 4 278
TOTAL: 3 302

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 9604 1682 5558 122 0 0 17%47:50:12
2rahul.net 24776 956 14340 44 0 6 3%47:40:02
3news.snarked.org 31018 64 19382 2 0 188 0%47:40:42
4news.uzoreto.com 17470 56 16176 134 0 0 0%47:52:54
5feeder.erje.net 27534 32 26840 34 0 0 0%47:53:00
6fu-berlin.de 26104 30 25966 108 0 0 0%47:47:48
7newsfeed.neva.ru 23442 16 22856 64 0 0 0%47:46:22
8news.cmpublishers.com 37090 6 28428 148 0 14 0%47:40:02
9remote6hme0.ripco.com 0 0 0 0 0 10 0%47:40:02
TOTAL: 9 197038 2842 159546 656 0 218 1%429:51:04
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com5.1 MB205.5 KB5.3 MB0.0 KB/s3.0 KB47:50:12
2rahul.net2.9 MB101.7 KB3.0 MB0.0 KB/s3.1 KB47:40:02
3news.snarked.org258.4 KB37.3 KB295.6 KB0.0 KB/s4.5 KB47:40:42
4news.uzoreto.com239.1 KB268.4 KB507.5 KB0.0 KB/s2.7 KB47:52:54
5feeder.erje.net187.7 KB119.0 KB306.7 KB0.0 KB/s4.6 KB47:53:00
6fu-berlin.de162.5 KB162.9 KB325.5 KB0.0 KB/s2.4 KB47:47:48
7newsfeed.neva.ru82.5 KB153.0 KB235.5 KB0.0 KB/s2.9 KB47:46:22
8news.cmpublishers.com9.8 KB502.0 KB511.8 KB0.0 KB/s3.3 KB47:40:02
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
TOTAL: 98.9 MB1.5 MB10.4 MB0.0 KB/s3.0 KB429:51:04
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 15244.1 MB 474 8 000:06:47
297.101.131.74 34 2432.8 KB 10 0 419:10:30
3205-178-112-159.s5018.c3-0.stk-ubr1.chi-stk.il.cable.rcncustomer.com 6 1433.1 KB 6 0 000:52:45
4d118-75-236-131.try.wideopenwest.com 8 1011.6 KB 4 0 000:15:05
575-151-74-61-independence.hfc.comcastbusiness.net 8 45.0 KB 4 0 000:13:48
TOTAL: 5 342 15764.2 MB 498 8 4344:01:27

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 15244.1 MB 474 8 000:06:47
2unresolved 416 2432.8 KB 10 0 4119:25:06
3*.s5018.c3-0.stk-ubr1.chi-stk.il.cable.rcncustomer.com 6 1433.1 KB 6 0 000:52:45
4*.try.wideopenwest.com 8 1011.6 KB 4 0 000:15:05
5*.hfc.comcastbusiness.net 8 45.0 KB 4 0 000:13:48
6*.fbx.proxad.net 32 00.0 KB 0 0 003:43:13
7*.dedicatedpanel.com 32 00.0 KB 0 0 006:25:30
8*.7-3.cable.virginm.net 2 00.0 KB 0 0 001:27:39
9*.chello.nl 196 00.0 KB 0 0 0209:29:25
10*.cpe.metrocast.net 24 00.0 KB 0 0 001:55:40
11? 2 00.0 KB 0 0 000:00:01
12*.access.telenet.be 2 00.0 KB 0 0 000:01:54
13*.static.bellmts.net 18 00.0 KB 0 0 000:04:29
TOTAL: 131032 15764.2 MB 498 8 4344:01:27

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
dhcp-089-098-157-215.chello.nl 31.438 22.786 0.000209:29:25
66.11.163.217 0.256 0.174 0.000100:12:51
97.101.131.74 16.550 4.361 0.00019:10:30
usloft5361.dedicatedpanel.com 3.258 0.778 0.00006:25:30
vvs78-1-88-173-43-113.fbx.proxad.net 3.218 0.724 0.00003:43:13
d-72-9-26-236.cpe.metrocast.net 2.528 0.724 0.00001:55:40
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 0.766 0.926 0.00001:27:39
205-178-112-159.s5018.c3-0.stk-ubr1.chi-stk.il.cable.rcncustomer.com 1.056 0.218 0.00000:52:45
d118-75-236-131.try.wideopenwest.com 1.444 0.652 0.00000:15:05
75-151-74-61-independence.hfc.comcastbusiness.net 1.402 1.296 0.00000:13:48
ftupet.com 47.162 7.312 0.00000:06:47
wnpgmb1154w-ds01-59-55.static.bellmts.net 1.840 0.452 0.00000:04:29
d5153980a.access.telenet.be 0.294 0.166 0.00000:01:54
92.116.44.27 38.528 7.824 0.00000:01:43
? 0.478 0.112 0.00000:00:00
shell3 0.196 0.036 0.00000:00:00
TOTAL: 16 150.414 48.541 0.000344:01:27

Curious NNRP server explorers [Top 20]:

SystemConn
92.116.44.27 380
dhcp-089-098-157-215.chello.nl 196
usloft5361.dedicatedpanel.com 32
vvs78-1-88-173-43-113.fbx.proxad.net 32
d-72-9-26-236.cpe.metrocast.net 24
wnpgmb1154w-ds01-59-55.static.bellmts.net 18
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 2
66.11.163.217 2
d5153980a.access.telenet.be 2
shell3 2
TOTAL: 10 690

NNRP unrecognized commands (by host):

SystemConn
97.101.131.74 74
TOTAL: 1 74

NNRP unrecognized commands (by command):

CommandCount
XZVER 2174168-2184168 2
XZVER 2144168-2154168 2
XZVER 2164168-2174168 2
XZVER 2124168-2134168 2
XZVER 2184168-2194168 2
XZVER 2064168-2074168 2
XZVER 2244168-2254168 2
XZVER 2364168-2374168 2
XZVER 2274168-2284168 2
XZVER 2044168-2054168 2
XZVER 2354168-2364168 2
XZVER 2374168-2384168 2
XZVER 2114168-2124168 2
XZVER 2314168-2324168 2
XZVER 2194168-2204168 2
XZVER 2304168-2314168 2
XZVER 2344168-2354168 2
XZVER 2394168-2404168 2
XZVER 2204168-2214168 2
XZVER 2054168-2064168 2
XZVER 2134168-2144168 2
XZVER 2094168-2104168 2
XZVER 2384168-2394168 2
XZVER 2284168-2294168 2
XZVER 2234168-2244168 2
XZVER 2074168-2084168 2
XZVER 2224168-2234168 2
XZVER 2104168-2114168 2
XZVER 2084168-2094168 2
XZVER 2254168-2264168 2
XZVER 2334168-2344168 2
XZVER 2264168-2274168 2
XZVER 2404168-2414168 2
XZVER 2324168-2334168 2
XZVER 2294168-2304168 2
XZVER 2154168-2164168 2
XZVER 2214168-2224168 2
TOTAL: 37 74

NNRP client timeouts [Top 20]:

SystemConnPeer
usloft5361.dedicatedpanel.com 32 0
97.101.131.74 8 8
205-178-112-159.s5018.c3-0.stk-ubr1.chi-stk.il.cable.rcncustomer.com 4 0
TOTAL: 3 44 8

Newsgroup request counts (by category):

CategoryCountPct
1rec 1498 95.1%
2alt 48 3.0%
3ba 6 0.4%
4misc 6 0.4%
5control 6 0.4%
6chi 4 0.3%
7comp 4 0.3%
8ont 2 0.1%
9soc 2 0.1%
TOTAL: 9 1576100.0%

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

NewsgroupCount
1rec.food.cooking 826
2rec.arts.tv 628
3rec.bicycles.tech 36
4alt.test 22
5alt.assassination.jfk 14
6control.cancel 6
7ba.food 6
8rec.gardens.edible 6
9misc.consumers.frugal-living 6
10alt.dbs.echostar 4
11alt.movies 4
12chi.weather 4
13rec.bicycles.misc 2
14comp.sys.ibm.pc.games.strategic 2
15soc.culture.laos 2
16alt.checkmate 2
17alt.games.warcraft 2
18comp.sys.ibm.pc.games.rpg 2
19ont.jobs 2
TOTAL: 19 1576