Daily Usenet report for news.ripco.com

Oct 18 00:01:03 -- Oct 18 23:57:31

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Oct 18 03:04:22 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 07:50:00 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 08:43:46 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 09:01:07 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 09:19:37 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 09:42:28 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 09:42:28 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 09:42:28 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 11:28:22 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 12:48:29 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 12:51:24 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 13:33:53 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 14:18:04 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping
Oct 18 19:03:43 remote5bge0 overchan[350]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 54970 56.2%7.2 MB 50.0%
inn 21275 21.8%3.7 MB 25.8%
innd 17884 18.3%2.8 MB 19.2%
innfeed 3598 3.7%732.8 KB 5.0%
overchan 14 0.0%1.8 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 97743 100.0%14.3 MB100.0%

History cache:

ReasonCount%Count
Cache misses 179582 52.3%
Do not exist 163668 47.7%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 343250 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%168 0.000 0.000 0.000
article cleanup00:00:00.436 0.0%41986 0.000 0.010 0.061
article logging00:00:00.882 0.0%42406 0.000 0.021 0.273
article parse00:00:01.622 0.0%97270 0.000 0.017 0.051
article write00:00:47.256 0.0%31138 0.470 1.518 50.328
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.234 0.0%354024 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:02.938 0.0%344994 0.001 0.009 0.139
history sync00:00:00.320 0.0%798 0.000 0.401 3.000
history write00:00:05.272 0.0%41976 0.009 0.126 4.289
hiswrite/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
idle47:39:24.510 99.9%390382 225.314 439.479 1184.051
nntp read00:00:01.924 0.0%386600 0.001 0.005 0.014
overview write00:00:00.198 0.0%31138 0.000 0.006 0.041
perl filter00:00:54.590 0.0%41970 1.013 1.301 2.118
python filter00:00:01.178 0.0%41978 0.000 0.028 0.081
site send00:00:01.804 0.0%93350 0.000 0.019 0.043
TOTAL: 47:42:01.00047:41:23.164100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.154 0.0%48982 0.000 0.003 0.040
article prepare00:00:00.004 0.0%19304 0.000 0.000 0.018
article read00:00:00.588 0.0%4092 0.000 0.144 6.000
backlog stats00:00:00.206 0.0%349898 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.970 0.0%231290 0.000 0.009 0.019
data write00:00:03.024 0.0%200160 0.004 0.015 0.056
idle47:51:52.006100.0%349898 113.415 492.464 1056.928
status file00:00:04.212 0.0%368 0.000 11.446 75.000
TOTAL: 47:52:15.62447:52:02.164100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:02:04.546 0.0%9761060 0.000 0.013 0.520
idle422:45:16.002 99.9%9813428 18.733 155.085300068.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:01:59.166 0.0%20406018 0.000 0.006 1.000
readart00:00:19.660 0.0%2228 0.750 8.824 37.000
TOTAL: 422:58:44.674422:49:39.374100.0%----

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 45798 13752 27106 4940 30%94:39:26
2news.snarked.org 64 39404 10186 26574 2644 25%62:10:06
3newsfeed.neva.ru 556 55462 6294 46872 2296 11%281:11:40
4newsfeed.fu-berlin.de 4 16028 668 15344 16 4%52:45:16
5news-in.rahul.net 576 16874 200 16666 8 1%47:29:34
6news.lightlink.com 2 6312 160 5986 166 2%45:01:30
7ripco-out.feeder.erje.net 574 34300 74 33404 822 0%47:17:00
8news.cmpublishers.com 52 8518 38 8468 12 0%16:27:42
9localhost 152 8 8 0 0 100%00:00:00
TOTAL: 9 3128 222704 31380 180420 10904 14%647:02:14
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com33.5 MB0.0 KB9.7 MB43.2 MB 77%2.4 KB
2news.snarked.org29.9 MB0.0 KB7.8 MB37.7 MB 79%3.0 KB
3newsfeed.neva.ru17.3 MB14.9 KB12.2 MB29.5 MB 58%3.5 KB
4news-in.rahul.net9.4 MB0.0 KB159.7 KB9.6 MB 98%47.0 KB
5newsfeed.fu-berlin.de4.3 MB0.0 KB622.7 KB4.9 MB 87%7.4 KB
6news.lightlink.com3.9 MB0.0 KB6.4 MB10.3 MB 37%32.4 KB
7ripco-out.feeder.erje.net157.9 KB0.0 KB8.4 MB8.6 MB 1%9.8 KB
8news.cmpublishers.com113.1 KB0.0 KB60.2 KB173.4 KB 65%3.5 KB
9localhost15.1 KB0.0 KB0.0 KB15.1 KB100%1.9 KB
TOTAL: 998.6 MB14.9 KB45.4 MB144.0 MB 68%3.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 18 00:01:03 - 00:59:59 471 3.0% 0.131.3 MB 2.6% 0.37
Oct 18 01:00:00 - 01:59:59 512 3.3% 0.141.3 MB 2.7% 0.37
Oct 18 02:00:00 - 02:59:59 606 3.9% 0.171.5 MB 3.0% 0.42
Oct 18 03:00:00 - 03:59:59 663 4.2% 0.181.7 MB 3.4% 0.48
Oct 18 04:00:00 - 04:59:59 769 4.9% 0.212.5 MB 5.0% 0.71
Oct 18 05:00:00 - 05:59:59 827 5.3% 0.232.3 MB 4.7% 0.67
Oct 18 06:00:00 - 06:59:59 728 4.7% 0.202.3 MB 4.6% 0.65
Oct 18 07:00:00 - 07:59:59 760 4.9% 0.211.8 MB 3.6% 0.51
Oct 18 08:00:00 - 08:59:59 911 5.8% 0.252.7 MB 5.5% 0.78
Oct 18 09:00:00 - 09:59:59 844 5.4% 0.232.2 MB 4.5% 0.64
Oct 18 10:00:00 - 10:59:59 766 4.9% 0.212.5 MB 5.0% 0.70
Oct 18 11:00:00 - 11:59:59 868 5.6% 0.242.3 MB 4.8% 0.67
Oct 18 12:00:00 - 12:59:59 856 5.5% 0.242.4 MB 4.8% 0.68
Oct 18 13:00:00 - 13:59:59 805 5.2% 0.223.6 MB 7.2% 1.01
Oct 18 14:00:00 - 14:59:59 876 5.6% 0.242.8 MB 5.7% 0.80
Oct 18 15:00:00 - 15:59:59 829 5.3% 0.233.2 MB 6.4% 0.90
Oct 18 16:00:00 - 16:59:59 576 3.7% 0.162.3 MB 4.6% 0.64
Oct 18 17:00:00 - 17:59:59 546 3.5% 0.152.0 MB 4.0% 0.57
Oct 18 18:00:00 - 18:59:59 555 3.6% 0.151.7 MB 3.4% 0.48
Oct 18 19:00:00 - 19:59:59 431 2.8% 0.121.4 MB 2.8% 0.40
Oct 18 20:00:00 - 20:59:59 330 2.1% 0.091.4 MB 2.8% 0.39
Oct 18 21:00:00 - 21:59:59 356 2.3% 0.101.2 MB 2.5% 0.35
Oct 18 22:00:00 - 22:59:59 341 2.2% 0.091.3 MB 2.7% 0.37
Oct 18 23:00:00 - 23:57:31 388 2.5% 0.111.8 MB 3.6% 0.53
TOTAL: 23:56:28 15614 100.0% 0.1849.4 MB 100.0% 0.59
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2492 1972 0 0 3 0 0 0 517
2news.snarked.org 1310 78 0 0 3 0 0 0 1229
3newsfeed.neva.ru 1246 580 0 5 2 0 0 0 659
4ripco-out.feeder.erje.net 412 411 0 0 0 0 0 0 1
5newsfeed.fu-berlin.de 139 0 0 0 0 0 0 0 139
6news.lightlink.com 52 0 0 0 0 0 0 0 52
7news.cmpublishers.com 6 0 0 0 0 0 0 0 6
8news-in.rahul.net 4 0 0 0 0 0 0 0 4
TOTAL: 8 5661 3041 0 5 8 0 0 0 2607

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2132
alt.binaries.ftd 224
it.tlc.cellulari.android 59
perl.cpan.uploads 59
alt.astrology.pro 54
fido7.su.pol.news 46
uk.radio.amateur.moderated 36
it.comp.os.win.windows10 33
alt.binaries.e-book 19
gmane.linux.alsa.devel 18
alt.binaries.pictures.scenic 18
alt.music.pink-floyd.publius 13
alt.binaries.nl 12
free.ucp 12
alt.binaries.pictures.rail 12
free.willey 9
alt.binaries.humor.skewed 9
nl.comp.os.linux.techniek 9
alt.binaries.test.yenc 9
free.uk.astrology 8
TOTAL: 137 3041

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

GroupsCount
christnet.bible 3
fr.comp.lang.php 2
bit.listserv.catholic 2
pl.praca.oferowana 1
TOTAL: 4 8

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2388
Too many newsgroups 1822
Binary in non-binary group 214
EMP (phl) 164
Scoring filter (16) 62
Attached HTML file 40
HTML post 40
Scoring filter (8) 20
Scoring filter (10) 18
Scoring filter (9) 12
Scoring filter (18) 8
Scoring filter (13) 2
Scoring filter (11) 2
TOTAL: 13 4792

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feed.uzoreto.com 3
TOTAL: 1 3
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 132
newsfeed.neva.ru 56
ripco-out.feed.uzoreto.com 22
news.snarked.org 1
TOTAL: 4 211
TOTAL: 4 234

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 10052 2890 4588 38 0 6 28%47:40:00
2rahul.net 24162 744 13344 16 0 22 3%47:40:00
3feeder.erje.net 30056 78 28590 196 0 0 0%47:42:00
4news.uzoreto.com 18434 70 16688 148 0 0 0%47:39:06
5news.snarked.org 36096 42 20892 0 0 182 0%47:40:00
6fu-berlin.de 28220 38 28182 0 0 0 0%47:40:00
7news.cmpublishers.com 36398 22 32198 392 0 15736 0%47:47:28
8newsfeed.neva.ru 25482 22 24624 68 0 0 0%47:40:00
9remote6hme0.ripco.com 0 0 0 0 0 10 0%47:40:00
TOTAL: 9 208900 3906 169106 858 0 15956 1%429:08:34
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com8.9 MB90.6 KB9.0 MB0.1 KB/s3.2 KB47:40:00
2rahul.net2.5 MB39.8 KB2.5 MB0.0 KB/s3.4 KB47:40:00
3feeder.erje.net278.9 KB853.0 KB1.1 MB0.0 KB/s4.1 KB47:42:00
4news.uzoreto.com267.5 KB331.9 KB599.5 KB0.0 KB/s2.7 KB47:39:06
5news.snarked.org253.3 KB0.0 KB253.3 KB0.0 KB/s6.0 KB47:40:00
6newsfeed.neva.ru225.9 KB168.8 KB394.6 KB0.0 KB/s4.4 KB47:40:00
7fu-berlin.de172.8 KB0.0 KB172.8 KB0.0 KB/s4.5 KB47:40:00
8news.cmpublishers.com66.1 KB1020.8 KB1.1 MB0.0 KB/s2.6 KB47:47:28
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:00
TOTAL: 912.7 MB2.4 MB15.1 MB0.0 KB/s3.2 KB429:08:34
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 9042.4 MB 388 6 000:06:44
2shell3 10 4061.3 MB 40 2 000:52:41
375-151-74-61-independence.hfc.comcastbusiness.net 16 87.3 KB 8 0 000:25:52
TOTAL: 3 312 13183.7 MB 436 8 0422:58:09

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 9042.4 MB 388 6 000:06:44
2? 10 4061.3 MB 40 2 000:53:04
3*.hfc.comcastbusiness.net 16 87.3 KB 8 0 000:25:52
4*.7-3.cable.virginm.net 4 00.0 KB 0 0 002:52:16
5unresolved1568 00.0 KB 0 0 091:47:06
6*.cpe.metrocast.net 8 00.0 KB 0 0 000:04:02
7*.cm-12-2c.dynamic.ziggo.nl 58 00.0 KB 0 0 0326:49:01
8*.208-100-26.static.steadfastdns.net 2 00.0 KB 0 0 000:00:00
TOTAL: 81952 13183.7 MB 436 8 0422:58:09

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
5469a4b0.cm-12-2c.dynamic.ziggo.nl 179.318 279.296 0.000326:49:01
66.11.163.217 0.444 0.200 0.00091:32:32
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 1.646 2.260 0.00002:52:16
shell3 3.774 2.020 0.00000:52:41
75-151-74-61-independence.hfc.comcastbusiness.net 3.016 2.558 0.00000:25:52
1.251.94.143 156.282 31.172 0.00000:14:13
ftupet.com 46.356 7.494 0.00000:06:44
d-72-9-26-236.cpe.metrocast.net 0.818 0.208 0.00000:04:02
? 0.914 0.222 0.00000:00:22
107.170.209.195 0.202 0.042 0.00000:00:19
ip231.208-100-26.static.steadfastdns.net 0.240 0.062 0.00000:00:00
TOTAL: 11 393.010 325.534 0.000422:58:09

Curious NNRP server explorers [Top 20]:

SystemConn
1.251.94.143 1562
5469a4b0.cm-12-2c.dynamic.ziggo.nl 58
d-72-9-26-236.cpe.metrocast.net 8
66.11.163.217 4
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 4
ip231.208-100-26.static.steadfastdns.net 2
107.170.209.195 2
TOTAL: 7 1640

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
5469a4b0.cm-12-2c.dynamic.ziggo.nl 26 0
66.11.163.217 4 0
cpc124512-swan5-2-0-cust46.7-3.cable.virginm.net 2 0
TOTAL: 3 32 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1100 83.5%
2comp 76 5.8%
3alt 50 3.8%
4misc 42 3.2%
5sci 18 1.4%
6chi 14 1.1%
7ba 8 0.6%
8news 4 0.3%
9chile 2 0.2%
10nl 2 0.2%
11de 2 0.2%
TOTAL: 11 1318100.0%

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

NewsgroupCount
1rec.arts.tv 758
2rec.food.cooking 302
3misc.phone.mobile.iphone 42
4alt.comp.os.windows-10 24
5alt.stupidity 24
6comp.mobile.ipad 22
7comp.mobile.android 22
8rec.bicycles.tech 20
9sci.electronics.repair 18
10comp.sys.mac.system 16
11chi.weather 12
12comp.protocols.dns.bind 10
13ba.food 8
14rec.radio.broadcasting 6
15rec.radio.swap 6
16news.software.readers 4
17rec.video.cable-tv 2
18nl.test 2
19chile.grupos 2
20comp.sys.mac.apps 2
21alt.hvac 2
22comp.mail.sendmail 2
23rec.music.marketplace.vinyl 2
24comp.sys.ibm.pc.games.rpg 2
25rec.gardens.edible 2
26chi.eats 2
27rec.puzzles 2
28de.etc.finanz.boerse 2
TOTAL: 28 1318