Daily Usenet report for news.ripco.com

Jul 9 00:01:01 -- Jul 9 23:57:56

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jul 9 03:27:17 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping
Jul 9 04:25:06 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping
Jul 9 04:33:34 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping
Jul 9 05:45:38 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping
Jul 9 08:02:10 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping
Jul 9 09:27:10 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping
Jul 9 09:32:18 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping
Jul 9 09:33:30 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping
Jul 9 23:34:38 remote5bge0 overchan[6996]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 20086 33.6%3.9 MB 41.5%
innd 18797 31.5%2.9 MB 31.1%
nnrpd 17230 28.8%1.8 MB 19.1%
innfeed 3622 6.1%803.1 KB 8.4%
overchan 9 0.0%1.4 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 59746 100.0%9.4 MB100.0%

History cache:

ReasonCount%Count
Cache misses 187458 50.5%
Do not exist 184030 49.5%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 371488 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%156 0.000 0.000 0.000
article cleanup00:00:00.436 0.0%39850 0.000 0.011 0.049
article logging00:00:00.906 0.0%40226 0.000 0.023 0.831
article parse00:00:01.502 0.0%98908 0.000 0.015 0.033
article write00:01:21.606 0.0%29882 1.503 2.731 26.662
artlog/artparse00:00:00.000 0.0%12 0.000 0.000 0.000
data move00:00:00.408 0.0%375296 0.000 0.001 0.006
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.864 0.0%372590 0.001 0.008 0.021
history sync00:00:00.324 0.0%818 0.000 0.396 3.000
history write00:00:04.342 0.0%39858 0.012 0.109 8.191
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:56:52.706 99.8%414382 210.883 416.555 1173.461
nntp read00:00:02.926 0.0%414684 0.002 0.007 0.017
overview write00:00:00.290 0.0%29882 0.000 0.010 0.037
perl filter00:01:04.410 0.0%39846 1.330 1.616 2.457
python filter00:00:01.476 0.0%39850 0.000 0.037 0.130
site send00:00:02.992 0.0%89624 0.007 0.033 0.061
TOTAL: 48:02:19.59647:59:37.188 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.150 0.0%30404 0.000 0.005 0.066
article prepare00:00:00.004 0.0%13536 0.000 0.000 0.023
article read00:00:00.504 0.0%2658 0.000 0.190 18.000
backlog stats00:00:00.300 0.0%453324 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.134 0.0%288258 0.000 0.007 0.018
data write00:00:05.020 0.0%258656 0.009 0.019 0.043
idle47:51:34.742100.0%453324 232.987 380.070 1059.789
status file00:00:02.368 0.0%360 0.750 6.578 122.000
TOTAL: 47:52:04.23647:51:45.222100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:24:58.632 93.0%17834 0.333 84.032 14085.216
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.368 0.1%213728 0.000 0.011 1.000
readart00:00:18.388 1.1%2278 1.833 8.072 44.500
TOTAL: 00:26:51.20200:25:19.388 94.3%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1148 31372 8872 18760 3740 28%94:47:44
2feeder3.feed-ams.uzoreto.com 574 20008 7574 9784 2650 37%47:18:14
3news.snarked.org 52 27072 6348 20096 628 23%49:34:22
4newsfeed.neva.ru 570 38376 5270 31092 2014 13%279:48:38
5ripco-out.news.tnetconsulting.net 6 41048 1190 39746 112 2%59:20:04
6newsfeed.fu-berlin.de 2 25788 802 24956 30 3%79:48:26
7news.lightlink.com 2 6814 100 6680 34 1%43:52:52
8news-in.rahul.net 574 14890 56 14816 18 0%47:19:52
9ripco-out.feeder.erje.net 574 31416 30 30650 736 0%47:18:20
10news.cmpublishers.com 50 8604 16 8586 2 0%48:00:00
11localhost 150 4 4 0 0 100%00:00:02
TOTAL: 11 3702 245392 30262 205166 9964 12%797:08:34
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com23.1 MB0.0 KB8.2 MB31.3 MB 73%2.5 KB
2ripco-out.news.tnetconsulting.net20.2 MB0.0 KB3.2 MB23.4 MB 86%18.4 KB
3feeder3.feed-ams.uzoreto.com18.3 MB0.0 KB4.8 MB23.1 MB 79%2.3 KB
4news.snarked.org17.2 MB0.0 KB2.0 MB19.2 MB 89%2.8 KB
5newsfeed.neva.ru15.6 MB7.0 KB5.1 MB20.7 MB 75%2.9 KB
6newsfeed.fu-berlin.de8.3 MB0.0 KB680.2 KB9.0 MB 92%11.0 KB
7news.lightlink.com4.4 MB0.0 KB2.4 MB6.8 MB 64%52.1 KB
8news-in.rahul.net1.2 MB0.0 KB1.0 MB2.3 MB 54%31.5 KB
9ripco-out.feeder.erje.net162.3 KB0.0 KB4.2 MB4.3 MB 3%5.8 KB
10news.cmpublishers.com65.3 KB0.0 KB30.2 KB95.5 KB 68%5.3 KB
11localhost8.3 KB0.0 KB0.0 KB8.3 KB100%2.1 KB
TOTAL: 11108.6 MB7.0 KB31.6 MB140.2 MB 77%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 9 00:01:01 - 00:59:59 300 2.0% 0.081.1 MB 2.1% 0.32
Jul 9 01:00:00 - 01:59:59 403 2.7% 0.111.2 MB 2.3% 0.35
Jul 9 02:00:00 - 02:59:59 621 4.2% 0.172.1 MB 3.9% 0.59
Jul 9 03:00:00 - 03:59:59 643 4.3% 0.181.8 MB 3.4% 0.52
Jul 9 04:00:00 - 04:59:59 650 4.4% 0.181.8 MB 3.4% 0.52
Jul 9 05:00:00 - 05:59:59 640 4.3% 0.182.5 MB 4.8% 0.72
Jul 9 06:00:00 - 06:59:59 747 5.0% 0.213.7 MB 6.9% 1.05
Jul 9 07:00:00 - 07:59:59 734 4.9% 0.202.0 MB 3.8% 0.57
Jul 9 08:00:00 - 08:59:59 795 5.3% 0.222.9 MB 5.4% 0.82
Jul 9 09:00:00 - 09:59:59 911 6.1% 0.253.1 MB 5.9% 0.89
Jul 9 10:00:00 - 10:59:59 800 5.4% 0.222.7 MB 5.1% 0.77
Jul 9 11:00:00 - 11:59:59 708 4.7% 0.202.6 MB 4.9% 0.74
Jul 9 12:00:00 - 12:59:59 854 5.7% 0.242.7 MB 5.2% 0.78
Jul 9 13:00:00 - 13:59:59 792 5.3% 0.222.3 MB 4.3% 0.65
Jul 9 14:00:00 - 14:59:59 823 5.5% 0.232.2 MB 4.1% 0.62
Jul 9 15:00:00 - 15:59:59 743 5.0% 0.212.1 MB 4.0% 0.60
Jul 9 16:00:00 - 16:59:59 784 5.3% 0.223.7 MB 7.0% 1.06
Jul 9 17:00:00 - 17:59:59 581 3.9% 0.161.9 MB 3.5% 0.53
Jul 9 18:00:00 - 18:59:59 488 3.3% 0.141.7 MB 3.1% 0.47
Jul 9 19:00:00 - 19:59:59 446 3.0% 0.123.3 MB 6.1% 0.93
Jul 9 20:00:00 - 20:59:59 382 2.6% 0.111.3 MB 2.5% 0.37
Jul 9 21:00:00 - 21:59:59 401 2.7% 0.111.0 MB 1.9% 0.29
Jul 9 22:00:00 - 22:59:59 325 2.2% 0.091.8 MB 3.5% 0.52
Jul 9 23:00:00 - 23:57:56 342 2.3% 0.101.6 MB 3.0% 0.47
TOTAL: 23:56:55 14913 100.0% 0.1753.2 MB 100.0% 0.63
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 1873 1183 0 0 5 0 0 0 685
2feeder3.feed-ams.uzoreto.com 1330 1078 0 0 2 0 0 0 250
3newsfeed.neva.ru 1077 542 0 2 1 0 0 0 532
4ripco-out.feeder.erje.net 370 369 0 0 0 0 0 0 1
5news.snarked.org 315 198 0 0 1 0 0 0 116
6newsfeed.fu-berlin.de 119 0 0 0 0 0 0 0 119
7ripco-out.news.tnetconsulting.net 55 15 0 0 0 0 0 0 40
8news.lightlink.com 24 0 0 0 0 0 0 0 24
9news-in.rahul.net 9 0 0 0 0 0 0 0 9
10news.cmpublishers.com 1 0 0 0 0 0 0 0 1
TOTAL: 10 5173 3385 0 2 9 0 0 0 1777

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2643
alt.binaries.ftd 134
linux.debian.changes 51
fido7.su.pol.news 42
perl.cpan.uploads 37
scinet.stats 31
alt.binaries.e-book 26
scot.general 23
alt.binaries.pictures.scenic 16
free.it.scienza.fisica 15
mozilla.dev.security.policy 13
free.willey 11
free.linux 11
it.comp.os.win.windows10 11
alt.binaries.mac.osx.apps 10
alt.binaries.pictures.purebeauty 10
linux.debian.maint.python 10
alt.binaries.humor.skewed 9
alt.paranormal.spells 9
swnet.finans 8
TOTAL: 135 3385

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

GroupsCount
fr.comp.securite 3
pl.praca.oferowana 2
fr.bienvenue 1
alt.christnet.religion 1
rec.travel.resorts.all-inclusive 1
bit.listserv.catholic 1
TOTAL: 6 9

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1638
Too many newsgroups 930
EMP (phl) 296
Binary in non-binary group 202
Scoring filter (8) 46
Scoring filter (9) 18
Scoring filter (10) 16
HTML post 12
Poison newsgroup 6
Scoring filter (11) 2
TOTAL: 10 3166

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 32
ripco-out.feed.uzoreto.com 12
feeder3.feed-ams.uzoreto.com 6
TOTAL: 3 50
Including strange strings
newsfeed.fu-berlin.de 108
newsfeed.neva.ru 72
feeder3.feed-ams.uzoreto.com 5
ripco-out.feed.uzoreto.com 3
TOTAL: 4 188
No colon-space in header
ripco-out.feed.uzoreto.com 6
TOTAL: 1 6
TOTAL: 3 244

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1feeder.erje.net 34752 872 27028 660 0 0 2%47:50:54
2rahul.net 23280 712 12796 64 0 0 3%47:40:04
3news.lightlink.com 10528 572 6338 26 0 0 5%47:40:04
4news.tnetconsulting.net 41786 90 28592 10 0 72 0%47:37:58
5news.snarked.org 38236 48 23062 14 0 256 0%47:39:32
6news.cmpublishers.com 43918 22 28954 220 0 288 0%47:34:48
7fu-berlin.de 26634 22 26612 0 0 0 0%47:40:04
8newsfeed.neva.ru 24942 14 24396 12 0 0 0%47:34:16
9news.uzoreto.com 13144 6 12864 12 0 0 0%47:40:04
10remote6hme0.ripco.com 8 2 0 6 0 0 25%01:32:06
TOTAL: 10 257228 2360 190642 1024 0 616 0%430:29:50
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net9.8 MB142.3 KB10.0 MB0.1 KB/s13.1 KB47:40:04
2news.lightlink.com2.7 MB122.7 KB2.8 MB0.0 KB/s4.9 KB47:40:04
3feeder.erje.net2.3 MB2.0 MB4.3 MB0.0 KB/s2.9 KB47:50:54
4news.tnetconsulting.net435.1 KB19.1 KB454.3 KB0.0 KB/s4.5 KB47:37:58
5news.snarked.org383.4 KB608.7 KB992.0 KB0.0 KB/s16.0 KB47:39:32
6fu-berlin.de305.2 KB0.0 KB305.2 KB0.0 KB/s13.9 KB47:40:04
7news.cmpublishers.com160.1 KB747.2 KB907.4 KB0.0 KB/s3.7 KB47:34:48
8newsfeed.neva.ru141.0 KB139.3 KB280.4 KB0.0 KB/s10.8 KB47:34:16
9news.uzoreto.com33.4 KB35.8 KB69.2 KB0.0 KB/s3.8 KB47:40:04
10remote6hme0.ripco.com4.3 KB40.9 KB45.2 KB0.0 KB/s5.7 KB01:32:06
TOTAL: 1016.3 MB3.8 MB20.1 MB0.0 KB/s6.1 KB430:29:50
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 11142.8 MB 452 2 000:07:07
2shell3 16 48101.6 KB 12 2 000:19:32
TOTAL: 2 302 11622.9 MB 464 4 000:26:41

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 11142.8 MB 452 2 000:07:07
2? 16 48101.6 KB 12 2 000:19:32
TOTAL: 2 302 11622.9 MB 464 4 000:26:39

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 2.306 0.798 0.00000:19:32
ftupet.com 47.784 21.808 0.00000:07:07
33.20.97.83.ro.ovo.sc 0.200 0.052 0.00000:00:00
192.241.231.147 0.200 0.054 0.00000:00:00
ec2-54-76-120-237.eu-west-1.compute.amazonaws.com 0.200 0.052 0.00000:00:00
worker-18.sfj.censys-scanner.com 0.206 0.052 0.00000:00:00
TOTAL: 6 50.896 22.816 0.00000:26:41

NNRP no permission clients:

SystemConn
33.20.97.83.ro.ovo.sc 2
ec2-54-76-120-237.eu-west-1.compute.amazonaws.com 2
192.241.231.147 2
worker-18.sfj.censys-scanner.com 2
TOTAL: 4 8

NNRP unrecognized commands (by host):

SystemConn
shell3 6
TOTAL: 1 6

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 6
TOTAL: 1 6

Newsgroup request counts (by category):

CategoryCountPct
1rec 1108 95.4%
2news 14 1.2%
3alt 12 1.0%
4sci 12 1.0%
5comp 8 0.7%
6chi 6 0.5%
7ba 2 0.2%
TOTAL: 7 1162100.0%

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

NewsgroupCount
1rec.arts.tv 496
2rec.food.cooking 474
3rec.bicycles.tech 124
4alt.stupidity 12
5rec.gardens.edible 12
6sci.electronics.repair 12
7news.software.nntp 10
8comp.sys.mac.system 8
9news.software.readers 4
10chi.weather 4
11ba.food 2
12chi.general 2
13rec.radio.swap 2
TOTAL: 13 1162