Daily Usenet report for news.ripco.com

Aug 28 00:01:02 -- Aug 28 23:57:34

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Aug 28 00:25:00 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 04:28:01 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 07:11:25 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 09:00:56 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 10:15:24 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 10:15:48 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 12:29:12 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 13:21:44 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 22:38:42 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping
Aug 28 23:48:18 remote5bge0 overchan[14313]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 17595 32.0%3.5 MB 40.7%
nnrpd 17198 31.3%1.8 MB 20.6%
innd 16465 29.9%2.6 MB 29.9%
innfeed 3714 6.8%770.7 KB 8.7%
overchan 10 0.0%1.4 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 54984 100.0%8.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 174602 54.3%
Cache misses 146792 45.7%
Positive hits 0 0.0%
Negative hits 0 0.0%
TOTAL: 4 321394 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%162 0.000 0.000 0.000
article cleanup00:00:00.394 0.0%34936 0.000 0.011 0.045
article logging00:00:01.004 0.0%35278 0.000 0.028 2.000
article parse00:00:01.386 0.0%93162 0.000 0.015 0.041
article write00:01:53.916 0.1%27794 1.378 4.099 413.026
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.364 0.0%332542 0.000 0.001 0.004
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.576 0.0%322570 0.001 0.008 0.016
history sync00:00:00.176 0.0%804 0.000 0.219 1.333
history write00:00:03.134 0.0%34936 0.018 0.090 0.705
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:57:00.752 99.8%369678 228.695 466.949 1399.035
nntp read00:00:02.980 0.0%369560 0.003 0.008 0.018
overview write00:00:00.218 0.0%27794 0.000 0.008 0.043
perl filter00:00:50.150 0.0%34936 1.082 1.435 2.875
python filter00:00:01.256 0.0%34942 0.000 0.036 0.127
site send00:00:01.820 0.0%83354 0.000 0.022 0.065
TOTAL: 48:02:04.66448:00:00.126 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.424 0.0%38910 0.000 0.011 0.057
article prepare00:00:00.006 0.0%31040 0.000 0.000 0.036
article read00:00:23.188 0.0%7664 0.000 3.026 17.750
backlog stats00:00:00.282 0.0%416084 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.638 0.0%261496 0.000 0.010 0.024
data write00:00:04.360 0.0%234456 0.007 0.019 0.036
idle47:51:07.212100.0%416084 50.671 414.020 1213.164
status file00:00:03.696 0.0%392 0.800 9.429 44.750
TOTAL: 47:52:04.18447:51:41.806100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle02:11:48.802 97.6%18044 9.000 438.306 60344.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:07.666 0.1%742942 0.000 0.010 0.053
readart00:00:19.830 0.2%2342 3.318 8.467 43.000
TOTAL: 02:15:01.10802:12:16.298 98.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 33180 15214 14720 3246 45%94:43:30
2newsfeed.neva.ru 590 17818 5662 10900 1256 31%274:56:22
3feeder3.feed-ams.uzoreto.com 574 11730 3526 6440 1764 30%47:33:10
4news.snarked.org 44 26412 1630 24664 118 6%54:17:42
5ripco-out.news.tnetconsulting.net 4 31614 970 30580 64 3%50:00:42
6newsfeed.fu-berlin.de 6 13422 538 12852 32 4%44:18:34
7news-in.rahul.net 330 11706 122 11582 2 1%27:32:12
8ripco-out.feeder.erje.net 574 29118 34 28428 656 0%47:28:12
9localhost 152 6 6 0 0 100%00:00:02
10news.cmpublishers.com 52 7412 2 7404 6 0%47:58:10
TOTAL: 10 3474 182418 27704 147570 7144 15%688:48:36
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com41.1 MB0.0 KB6.6 MB47.7 MB 86%2.6 KB
2ripco-out.news.tnetconsulting.net23.8 MB0.0 KB1.4 MB25.3 MB 94%25.0 KB
3newsfeed.neva.ru17.3 MB0.0 KB3.6 MB20.8 MB 82%3.1 KB
4feeder3.feed-ams.uzoreto.com9.0 MB0.0 KB2.8 MB11.8 MB 75%2.3 KB
5news-in.rahul.net8.0 MB0.0 KB4.9 KB8.0 MB 99%65.9 KB
6newsfeed.fu-berlin.de5.9 MB0.0 KB3.1 MB9.0 MB 65%16.1 KB
7news.snarked.org5.2 MB0.0 KB527.3 KB5.7 MB 91%3.4 KB
8ripco-out.feeder.erje.net121.1 KB0.0 KB4.0 MB4.1 MB 2%6.1 KB
9localhost13.9 KB0.0 KB0.0 KB13.9 KB100%2.3 KB
10news.cmpublishers.com7.1 KB0.0 KB8.9 KB16.0 KB 44%2.0 KB
TOTAL: 10110.4 MB0.0 KB22.1 MB132.5 MB 83%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 28 00:01:02 - 00:59:59 376 2.7% 0.112.0 MB 3.6% 0.58
Aug 28 01:00:00 - 01:59:59 494 3.6% 0.142.2 MB 4.0% 0.63
Aug 28 02:00:00 - 02:59:59 485 3.5% 0.131.7 MB 3.1% 0.49
Aug 28 03:00:00 - 03:59:59 563 4.1% 0.162.1 MB 3.8% 0.58
Aug 28 04:00:00 - 04:59:59 565 4.1% 0.161.6 MB 3.0% 0.47
Aug 28 05:00:00 - 05:59:59 616 4.4% 0.171.9 MB 3.5% 0.54
Aug 28 06:00:00 - 06:59:59 634 4.6% 0.182.3 MB 4.2% 0.65
Aug 28 07:00:00 - 07:59:59 689 5.0% 0.192.5 MB 4.5% 0.70
Aug 28 08:00:00 - 08:59:59 818 5.9% 0.234.0 MB 7.3% 1.14
Aug 28 09:00:00 - 09:59:59 929 6.7% 0.263.6 MB 6.6% 1.03
Aug 28 10:00:00 - 10:59:59 848 6.1% 0.242.8 MB 5.1% 0.80
Aug 28 11:00:00 - 11:59:59 801 5.8% 0.222.9 MB 5.3% 0.82
Aug 28 12:00:00 - 12:59:59 752 5.4% 0.212.7 MB 5.0% 0.77
Aug 28 13:00:00 - 13:59:59 632 4.6% 0.182.2 MB 4.0% 0.62
Aug 28 14:00:00 - 14:59:59 629 4.5% 0.171.7 MB 3.1% 0.48
Aug 28 15:00:00 - 15:59:59 679 4.9% 0.192.4 MB 4.3% 0.67
Aug 28 16:00:00 - 16:59:59 546 3.9% 0.151.8 MB 3.3% 0.51
Aug 28 17:00:00 - 17:59:59 574 4.1% 0.162.1 MB 3.9% 0.60
Aug 28 18:00:00 - 18:59:59 403 2.9% 0.111.3 MB 2.4% 0.37
Aug 28 19:00:00 - 19:59:59 367 2.6% 0.101.5 MB 2.8% 0.43
Aug 28 20:00:00 - 20:59:59 466 3.4% 0.134.4 MB 8.0% 1.24
Aug 28 21:00:00 - 21:59:59 425 3.1% 0.122.1 MB 3.8% 0.59
Aug 28 22:00:00 - 22:59:59 352 2.5% 0.101.7 MB 3.0% 0.47
Aug 28 23:00:00 - 23:57:34 216 1.6% 0.061.3 MB 2.4% 0.39
TOTAL: 23:56:32 13859 100.0% 0.1654.7 MB 100.0% 0.65
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 1630 1132 0 0 4 0 0 0 494
2feeder3.feed-ams.uzoreto.com 883 687 0 0 2 0 0 0 194
3newsfeed.neva.ru 672 362 0 0 3 0 0 0 307
4ripco-out.feeder.erje.net 328 328 0 0 0 0 0 0 0
5newsfeed.fu-berlin.de 126 0 0 0 0 0 0 0 126
6news.snarked.org 62 25 0 0 0 0 0 0 37
7ripco-out.news.tnetconsulting.net 32 12 0 0 0 0 0 0 20
8news.cmpublishers.com 2 2 0 0 0 0 0 0 0
9news-in.rahul.net 1 0 0 0 0 0 0 0 1
TOTAL: 9 3736 2548 0 0 9 0 0 0 1179

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1770
alt.binaries.ftd 135
it.tlc.cellulari.android 44
fido7.su.pol.news 43
it.comp.os.win.windows10 35
alt.binaries.pictures.erotica.vintage 33
scinet.stats 30
alt.bestjobsusa.computer.jobs 26
perl.cpan.uploads 25
ba.forsale 21
alt.binaries.e-book 18
alt.binaries.pictures.purebeauty 17
free.willey 17
free.it.scienza.fisica 17
nl.comp.os.linux.techniek 16
de.soc.arbeitslos 15
scot.general 15
alt.binaries.pictures.earlmiller 12
alt.my.job.sucks 12
alt.binaries.pictures.scenic 11
TOTAL: 132 2548

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

GroupsCount
pl.praca.oferowana 6
christnet.bible 2
bit.listserv.catholic 1
TOTAL: 3 9

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 858
EMP (md5) 718
EMP (phl) 204
Binary in non-binary group 118
Scoring filter (8) 36
Scoring filter (11) 18
Scoring filter (10) 18
Scoring filter (9) 14
HTML post 10
Scoring filter (13) 8
Scoring filter (12) 4
Scoring filter (18) 4
Scoring filter (14) 4
Scoring filter (15) 2
TOTAL: 14 2016

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 46
ripco-out.feed.uzoreto.com 26
feeder3.feed-ams.uzoreto.com 6
TOTAL: 3 78
Including strange strings
newsfeed.fu-berlin.de 112
newsfeed.neva.ru 48
ripco-out.feed.uzoreto.com 7
news.snarked.org 3
feeder3.feed-ams.uzoreto.com 1
TOTAL: 5 171
TOTAL: 2 249

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1rahul.net 25592 7194 10030 78 0 7150 28%47:35:18
2news.tnetconsulting.net 44444 64 26658 2 0 122 0%47:40:02
3news.cmpublishers.com 39850 36 26898 212 0 166 0%47:40:04
4fu-berlin.de 24976 36 24940 0 0 0 0%47:44:26
5news.snarked.org 41004 30 25844 24 0 246 0%47:39:28
6feeder.erje.net 27524 16 26356 18 0 34 0%47:40:44
7news.uzoreto.com 8564 14 8316 16 0 0 0%47:40:04
8newsfeed.neva.ru 22444 8 21952 0 0 0 0%47:40:04
9news.lightlink.com 0 0 0 0 0 7230 0%47:40:02
10remote6hme0.ripco.com 6 0 0 6 0 0 0%01:12:04
TOTAL: 10 234404 7398 170994 356 0 14948 3%430:12:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net47.0 MB524.4 KB47.6 MB0.3 KB/s6.7 KB47:35:18
2news.tnetconsulting.net171.9 KB1.3 KB173.2 KB0.0 KB/s2.6 KB47:40:02
3fu-berlin.de153.3 KB0.0 KB153.3 KB0.0 KB/s4.3 KB47:44:26
4news.cmpublishers.com133.8 KB551.3 KB685.2 KB0.0 KB/s2.8 KB47:40:04
5news.snarked.org89.0 KB129.4 KB218.4 KB0.0 KB/s4.0 KB47:39:28
6feeder.erje.net46.1 KB49.4 KB95.5 KB0.0 KB/s2.8 KB47:40:44
7news.uzoreto.com37.9 KB32.9 KB70.8 KB0.0 KB/s2.4 KB47:40:04
8newsfeed.neva.ru18.0 KB0.0 KB18.0 KB0.0 KB/s2.3 KB47:40:04
9news.lightlink.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
10remote6hme0.ripco.com0.0 KB33.2 KB33.2 KB0.0 KB/s5.5 KB01:12:04
TOTAL: 1047.7 MB1.3 MB49.0 MB0.0 KB/s6.5 KB430:12:16
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 9662.6 MB 402 2 000:08:10
2shell3.ripco.com 10 408882.1 KB 40 4 002:06:40
TOTAL: 2 296 13743.4 MB 442 6 002:14:51

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 9662.6 MB 402 2 000:08:10
2*.ripco.com 10 408882.1 KB 40 4 002:06:40
TOTAL: 2 296 13743.4 MB 442 6 002:14:51

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3.ripco.com 3.382 4.766 0.00002:06:40
ftupet.com 46.388 21.254 0.00000:08:10
60.217.72.12 0.200 0.054 0.00000:00:00
? 0.224 0.054 0.00000:00:00
TOTAL: 4 50.194 26.128 0.00002:14:51

NNRP no permission clients:

SystemConn
60.217.72.12 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3.ripco.com 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1074 78.2%
2comp 204 14.8%
3misc 58 4.2%
4alt 30 2.2%
5chi 4 0.3%
6news 2 0.1%
7seattle 2 0.1%
TOTAL: 7 1374100.0%

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

NewsgroupCount
1rec.food.cooking 570
2rec.arts.tv 392
3comp.mobile.android 178
4rec.bicycles.tech 100
5misc.phone.mobile.iphone 34
6misc.consumers.frugal-living 24
7comp.mail.pine 10
8alt.online-service.comcast 10
9alt.www.webmaster 6
10alt.security.alarms 6
11rec.heraldry 4
12rec.gardens.edible 4
13comp.security.ssh 4
14chi.weather 4
15comp.mail.sendmail 4
16comp.mobile.ipad 4
17seattle.general 2
18rec.bicycles.misc 2
19rec.radio.swap 2
20alt.video.digital-tv 2
21alt.autos.ford 2
22comp.sys.mac.apps 2
23alt.solaris.x86 2
24alt.troll.adam-h-kerman 2
25news.admin.net-abuse.email 2
26comp.internet.services.video.youtube 2
TOTAL: 26 1374