Daily Usenet report for news.ripco.com

May 31 00:01:01 -- May 31 23:58:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

May 31 11:40:37 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 12:13:24 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 12:25:11 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 13:04:58 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 13:54:09 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 14:45:08 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 17:11:23 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 17:14:47 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 17:58:38 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping
May 31 23:50:53 remote5bge0 overchan[21806]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 36164 46.6%4.5 MB 37.6%
inn 19343 24.9%3.8 MB 31.8%
innd 18391 23.7%2.9 MB 24.0%
innfeed 3720 4.8%806.6 KB 6.6%
overchan 10 0.0%1.5 KB 0.0%
controlchan 10 0.0%2.0 KB 0.0%
TOTAL: 6 77638 100.0%12.0 MB100.0%

History cache:

ReasonCount%Count
Cache misses 187276 52.1%
Do not exist 172100 47.9%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 359376 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%324 0.000 0.000 0.000
article cleanup00:00:00.476 0.0%38492 0.000 0.012 0.074
article logging00:00:00.780 0.0%38754 0.000 0.020 0.457
article parse00:00:01.508 0.0%97670 0.000 0.015 0.032
article write00:01:33.876 0.1%29954 1.597 3.134 68.493
artlog/artparse00:00:00.000 0.0%6 0.000 0.000 0.000
data move00:00:00.416 0.0%373308 0.000 0.001 0.005
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.818 0.0%360614 0.001 0.008 0.017
history sync00:00:00.340 0.0%794 0.000 0.428 3.500
history write00:00:03.388 0.0%38496 0.029 0.088 0.774
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:52:06.742 99.7%418162 230.666 412.105 1037.619
nntp read00:00:03.248 0.0%409688 0.002 0.008 0.020
overview write00:00:00.236 0.0%29954 0.000 0.008 0.040
perl filter00:01:04.532 0.0%38490 1.231 1.677 3.533
python filter00:00:01.462 0.0%38492 0.000 0.038 0.120
site send00:00:02.896 0.0%89824 0.008 0.032 0.075
TOTAL: 48:02:05.50247:55:02.718 99.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.120 0.0%30274 0.000 0.004 0.024
article prepare00:00:00.000 0.0%15072 0.000 0.000 0.000
article read00:00:00.430 0.0%2962 0.000 0.145 6.083
backlog stats00:00:00.276 0.0%450794 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.656 0.0%289526 0.001 0.006 0.013
data write00:00:04.368 0.0%259616 0.005 0.017 0.035
idle47:52:56.216100.0%450794 211.767 382.384 1007.072
status file00:00:02.454 0.0%384 1.000 6.391 37.000
TOTAL: 47:53:23.73647:53:05.520100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:27:27.958 43.2%17444 5.333 94.471 35690.611
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.356 0.0%122260 0.000 0.011 31.000
readart00:00:17.474 0.5%1924 2.536 9.082 36.000
TOTAL: 01:03:31.10600:27:46.788 43.7%----

Control commands to INND:

CommandNumber
L 2
flush 2
go 4
logmode 2
mode 288
name 5
pause 2
paused 2
reserve 4
TOTAL: 9 311

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org002002
us-control@lists.killfile.org002002
TOTAL004004

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1148 39102 14820 20138 4144 37%94:39:16
2news.snarked.org 72 29134 7062 21342 730 24%62:33:28
3newsfeed.neva.ru 570 36868 4550 31008 1310 12%275:52:48
4feeder3.feed-ams.uzoreto.com 574 9104 1622 6546 936 17%47:20:12
5ripco-out.news.tnetconsulting.net 2 41170 1164 39848 158 2%61:51:22
6newsfeed.fu-berlin.de 2 14904 460 14436 8 3%45:38:12
7news.lightlink.com 2 8404 368 7806 230 4%47:38:30
8news-in.rahul.net 574 15524 254 15164 106 1%47:21:34
9ripco-out.feeder.erje.net 574 32344 50 31382 912 0%47:16:50
10news.cmpublishers.com 52 7756 10 7736 10 0%48:00:04
11localhost 148 2 2 0 0 100%00:00:02
TOTAL: 11 3718 234312 30362 195406 8544 12%778:12:18
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com38.2 MB0.0 KB8.5 MB46.7 MB 81%2.5 KB
2news.snarked.org20.2 MB0.0 KB2.1 MB22.3 MB 90%2.9 KB
3ripco-out.news.tnetconsulting.net18.1 MB0.0 KB2.3 MB20.4 MB 88%15.8 KB
4newsfeed.neva.ru15.0 MB0.0 KB4.1 MB19.2 MB 78%3.3 KB
5news-in.rahul.net7.1 MB0.0 KB3.4 MB10.5 MB 68%29.9 KB
6feeder3.feed-ams.uzoreto.com4.5 MB0.0 KB2.5 MB7.0 MB 64%2.8 KB
7news.lightlink.com4.0 MB136.0 KB9.0 MB13.1 MB 30%22.5 KB
8newsfeed.fu-berlin.de3.4 MB0.0 KB15.5 KB3.4 MB 99%7.4 KB
9ripco-out.feeder.erje.net87.2 KB0.0 KB5.2 MB5.3 MB 1%5.6 KB
10news.cmpublishers.com32.5 KB0.0 KB15.3 KB47.8 KB 67%2.4 KB
11localhost2.6 KB0.0 KB0.0 KB2.6 KB100%1.3 KB
TOTAL: 11110.7 MB136.0 KB37.0 MB147.9 MB 74%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 31 00:01:01 - 00:59:59 339 2.3% 0.101.2 MB 2.4% 0.36
May 31 01:00:00 - 01:59:59 401 2.7% 0.112.2 MB 4.1% 0.61
May 31 02:00:00 - 02:59:59 503 3.4% 0.141.3 MB 2.5% 0.37
May 31 03:00:00 - 03:59:59 531 3.6% 0.151.2 MB 2.4% 0.35
May 31 04:00:00 - 04:59:59 572 3.8% 0.161.5 MB 2.8% 0.42
May 31 05:00:00 - 05:59:59 657 4.4% 0.182.2 MB 4.2% 0.63
May 31 06:00:00 - 06:59:59 713 4.8% 0.202.6 MB 4.9% 0.73
May 31 07:00:00 - 07:59:59 803 5.4% 0.222.1 MB 4.0% 0.59
May 31 08:00:00 - 08:59:59 822 5.5% 0.232.1 MB 4.0% 0.59
May 31 09:00:00 - 09:59:59 805 5.4% 0.222.3 MB 4.4% 0.65
May 31 10:00:00 - 10:59:59 849 5.7% 0.243.7 MB 7.1% 1.05
May 31 11:00:00 - 11:59:59 785 5.3% 0.222.9 MB 5.5% 0.82
May 31 12:00:00 - 12:59:59 774 5.2% 0.213.8 MB 7.3% 1.08
May 31 13:00:00 - 13:59:59 906 6.1% 0.253.5 MB 6.8% 1.00
May 31 14:00:00 - 14:59:59 815 5.5% 0.232.3 MB 4.4% 0.66
May 31 15:00:00 - 15:59:59 816 5.5% 0.232.5 MB 4.7% 0.70
May 31 16:00:00 - 16:59:59 761 5.1% 0.213.2 MB 6.2% 0.92
May 31 17:00:00 - 17:59:59 546 3.7% 0.151.8 MB 3.4% 0.50
May 31 18:00:00 - 18:59:59 577 3.9% 0.161.7 MB 3.3% 0.49
May 31 19:00:00 - 19:59:59 448 3.0% 0.121.7 MB 3.3% 0.49
May 31 20:00:00 - 20:59:59 472 3.2% 0.132.2 MB 4.3% 0.63
May 31 21:00:00 - 21:59:59 379 2.5% 0.111.4 MB 2.8% 0.41
May 31 22:00:00 - 22:59:59 384 2.6% 0.111.7 MB 3.2% 0.48
May 31 23:00:00 - 23:58:02 283 1.9% 0.081.0 MB 1.9% 0.29
TOTAL: 23:57:01 14941 100.0% 0.1752.0 MB 100.0% 0.62
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2074 1585 0 0 2 0 0 0 487
2newsfeed.neva.ru 703 395 0 0 2 0 0 0 306
3feeder3.feed-ams.uzoreto.com 468 257 0 0 1 0 0 0 210
4ripco-out.feeder.erje.net 457 455 0 0 0 0 0 0 2
5news.snarked.org 369 214 0 0 1 0 0 0 154
6news.lightlink.com 115 0 0 1 0 0 0 0 114
7newsfeed.fu-berlin.de 89 0 0 0 0 0 0 0 89
8ripco-out.news.tnetconsulting.net 69 30 0 0 0 0 0 0 39
9news-in.rahul.net 53 0 0 0 0 0 0 0 53
10news.cmpublishers.com 5 0 0 0 0 0 0 0 5
TOTAL: 10 4402 2936 0 1 6 0 0 0 1459

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2111
alt.binaries.ftd 153
perl.cpan.uploads 44
alt.binaries.3d.poser 32
scinet.stats 31
it.comp.os.win.windows10 28
scot.general 28
it.tlc.cellulari.android 27
wales.test 26
alt.binaries.pictures.scenic 21
fido7.su.pol.news 20
free.it.scienza.fisica 19
alt.binaries.sounds.lossless.jazz 18
alt.binaries.pictures.wallpaper 18
aioe.news.assistenza 14
alt.binaries.e-book 13
fr.rec.loisirs-creatifs 13
alt.binaries.mac.osx.apps 12
de.soc.arbeitslos 12
alt.binaries.photos.original 11
TOTAL: 143 2936

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

GroupsCount
fr.comp.lang.php 3
fr.comp.securite 2
bit.listserv.catholic 1
TOTAL: 3 6

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1250
Too many newsgroups 956
Binary in non-binary group 126
EMP (phl) 126
Scoring filter (8) 52
Scoring filter (10) 44
Scoring filter (9) 34
Scoring filter (11) 24
U2 violation - invalid distribution 20
Scoring filter (14) 6
Scoring filter (13) 4
Scoring filter (12) 4
HTML post 4
Scoring filter (16) 2
TOTAL: 14 2652

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 28
ripco-out.feed.uzoreto.com 10
feeder3.feed-ams.uzoreto.com 4
TOTAL: 3 42
Including strange strings
newsfeed.fu-berlin.de 85
newsfeed.neva.ru 44
ripco-out.feeder.erje.net 1
ripco-out.feed.uzoreto.com 1
TOTAL: 4 131
No colon-space in header
ripco-out.feed.uzoreto.com 2
feeder3.feed-ams.uzoreto.com 1
TOTAL: 2 3
TOTAL: 3 176

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 11358 1348 6588 16 0 8 11%47:40:00
2rahul.net 23574 964 13386 32 0 4 4%47:40:04
3news.cmpublishers.com 44034 110 28520 784 0 60 0%47:40:00
4feeder.erje.net 29146 54 28556 110 0 0 0%47:40:44
5news.uzoreto.com 13342 38 12848 162 0 0 0%47:57:02
6news.snarked.org 38232 36 22734 6 0 336 0%48:09:12
7news.tnetconsulting.net 44274 32 28870 4 0 20 0%47:36:02
8fu-berlin.de 27076 30 27046 0 0 0 0%47:35:16
9newsfeed.neva.ru 26036 16 25258 16 0 0 0%47:51:54
10remote6hme0.ripco.com 6 2 0 4 0 0 33%01:20:00
TOTAL: 10 257078 2630 193806 1134 0 428 1%431:10:14
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com5.6 MB87.5 KB5.6 MB0.0 KB/s4.2 KB47:40:00
2rahul.net4.9 MB76.1 KB5.0 MB0.0 KB/s5.1 KB47:40:04
3news.snarked.org526.2 KB11.2 KB537.4 KB0.0 KB/s12.8 KB48:09:12
4news.cmpublishers.com323.5 KB2.4 MB2.7 MB0.0 KB/s3.1 KB47:40:00
5feeder.erje.net291.2 KB427.5 KB718.8 KB0.0 KB/s4.4 KB47:40:44
6news.tnetconsulting.net289.9 KB4.0 KB293.9 KB0.0 KB/s8.2 KB47:36:02
7newsfeed.neva.ru266.6 KB221.1 KB487.7 KB0.0 KB/s15.2 KB47:51:54
8news.uzoreto.com132.2 KB835.9 KB968.0 KB0.0 KB/s4.8 KB47:57:02
9fu-berlin.de116.9 KB0.0 KB116.9 KB0.0 KB/s3.9 KB47:35:16
10remote6hme0.ripco.com2.6 KB18.3 KB20.9 KB0.0 KB/s3.5 KB01:20:00
TOTAL: 1012.4 MB4.0 MB16.4 MB0.0 KB/s4.5 KB431:10:14
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 9562.7 MB 416 0 000:07:18
2shell3 6 1221.8 KB 2 2 000:21:44
TOTAL: 2 292 9682.7 MB 418 2 001:00:24

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 9562.7 MB 416 0 000:07:18
2? 6 1221.8 KB 2 2 000:21:44
TOTAL: 2 292 9682.7 MB 418 2 000:29:03

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
host81-129-173-246.range81-129.btcentralplus.com 462.646 128.638 0.00000:28:54
shell3 0.868 0.336 0.00000:21:44
ftupet.com 46.898 21.868 0.00000:07:18
84-105-134-55.cable.dynamic.v4.ziggo.nl 16.976 4.678 0.00000:02:26
162.243.136.91 0.200 0.052 0.00000:00:00
TOTAL: 5 527.588 155.572 0.00001:00:24

NNRP no permission clients:

SystemConn
host81-129-173-246.range81-129.btcentralplus.com 4576
84-105-134-55.cable.dynamic.v4.ziggo.nl 168
162.243.136.91 2
TOTAL: 3 4746

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 936 96.7%
2chi 18 1.9%
3seattle 12 1.2%
4misc 2 0.2%
TOTAL: 4 968100.0%

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

NewsgroupCount
1rec.arts.tv 476
2rec.food.cooking 360
3rec.bicycles.tech 96
4chi.general 14
5seattle.general 12
6chi.weather 4
7rec.gardens.edible 4
8misc.consumers.frugal-living 2
TOTAL: 8 968