Daily Usenet report for news.ripco.com

Apr 21 00:01:01 -- Apr 21 23:57:33

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Apr 21 10:36:31 s_local@remote5bge0 innfeed[14854]: [ID 702911 news.notice] tradspool: mapping file /zones/news/spool/tradspool.map not found
Apr 21 10:36:31 s_local@remote5bge0 innd: [ID 702911 news.notice] tradspool: mapping file /zones/news/spool/tradspool.map not found
Apr 21 10:38:30 s_local@remote5bge0 nnrpd[15087]: [ID 702911 news.warning] CNFS: cnfs_retrieve: token @030254574F00000000000007BCED0000001D@: bogus cycbuff name: TWO:0x7bced:29
Apr 21 10:42:31 s_local@remote5bge0 nnrpd[15791]: [ID 702911 news.warning] CNFS: cnfs_retrieve: token @030254574F00000000000007CD660000001D@: bogus cycbuff name: TWO:0x7cd66:29
Apr 21 13:03:49 s_local@remote5bge0 nnrpd[28963]: [ID 702911 news.warning] CNFS: cnfs_retrieve: token @030254574F00000000000010FC640000001D@: bogus cycbuff name: TWO:0x10fc64:29
Apr 21 16:04:33 s_local@remote5bge0 nnrpd[5382]: [ID 702911 news.warning] CNFS: cnfs_retrieve: token @030254574F0000000000000227BD0000001E@: bogus cycbuff name: TWO:0x227bd:30

Log entries by program:

Program nameLines%LinesSize%Size
inn 20932 47.3%3.6 MB 52.7%
innd 11230 25.4%1.8 MB 26.1%
nnrpd 10186 23.0%1.1 MB 16.1%
innfeed 1676 3.8%326.2 KB 4.7%
cnfsstat 207 0.5%29.0 KB 0.4%
rnews 3 0.0%0.6 KB 0.0%
controlchan 3 0.0%0.4 KB 0.0%
overchan 2 0.0%0.2 KB 0.0%
TOTAL: 8 44239 100.0%6.8 MB100.0%

History cache:

ReasonCount%Count
Do not exist 103994 63.5%
Cache misses 59815 36.5%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 163809 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%62 0.000 0.000 0.000
article cleanup00:00:00.252 0.0%20810 0.000 0.012 0.048
article logging00:00:00.393 0.0%20851 0.000 0.019 0.180
article parse00:00:00.753 0.0%45620 0.000 0.017 0.061
article write00:23:14.937 1.6%15214 0.053 91.688 13863.056
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
artlog/artwrite00:00:00.002 0.0%100 0.000 0.020 0.031
data move00:00:00.192 0.0%148373 0.000 0.001 0.007
hishave/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
hissync/artwrite00: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:01:11.470 0.1%164526 0.000 0.434 12.302
history sync00:00:00.556 0.0%391 0.000 1.422 14.000
history write00:01:30.634 0.1%20705 0.000 4.377 148.750
hiswrite/artparse00:00:00.007 0.0%1 7.000 7.000 7.000
idle23:14:09.111 98.0%167823 272.678 498.437 6667.644
nntp read00:00:01.279 0.0%168180 0.000 0.008 0.043
overview write00:00:00.121 0.0%15114 0.000 0.008 0.048
perl filter00:00:31.067 0.0%20798 1.171 1.494 9.809
python filter00:00:00.832 0.0%20801 0.000 0.040 0.200
site send00:00:01.168 0.0%45334 0.000 0.026 0.046
TOTAL: 23:42:17.99823:40:42.774 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.142 0.0%15120 0.000 0.009 0.047
article prepare00:00:00.001 0.0%1012 0.000 0.001 0.125
article read00:00:00.139 0.0%238 0.000 0.584 99.000
backlog stats00:00:00.087 0.0%122025 0.000 0.001 0.070
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.992 0.0%85793 0.000 0.012 0.167
data write00:00:01.089 0.0%73045 0.000 0.015 0.034
idle23:35:42.661 99.9%122025 106.718 696.109 21436.500
status file00:01:32.476 0.1%238 4.000 388.555 2884.000
TOTAL: 23:37:26.37123:37:17.587100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:39:25.332 97.9%10152 17.775 587.602120001.200
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:08.628 0.1%726123 0.000 0.012 0.046
readart00:00:07.545 0.1%2313 0.000 3.262 35.500
TOTAL: 01:41:31.85801:39:41.505 98.2%----

Control commands to INND:

CommandNumber
L 1
flush 1
go 3
logmode 1
mode 150
name 1
pause 1
paused 1
reserve 2
shutdown 6
throttle 2
TOTAL: 11 169

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 867 37285 10091 23705 3489 27%67:53:10
2newsfeed.neva.ru 294 12605 3349 7468 1788 26%133:25:00
3news.cmpublishers.com 24 4430 667 3663 100 15%21:56:30
4news-in.rahul.net 290 10360 594 9692 74 5%22:41:58
5newsfeed.fu-berlin.de 3 6433 238 6177 18 3%20:57:01
6ripco-out.feeder.erje.net 278 8949 124 8593 232 1%22:31:47
7localhost 72 3 3 0 0 100%00:00:00
TOTAL: 7 1828 80065 15066 59298 5701 18%289:25:26
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com27.5 MB3.5 KB9.0 MB36.5 MB 75%2.8 KB
2newsfeed.neva.ru10.4 MB12.7 KB5.3 MB15.7 MB 66%3.1 KB
3news-in.rahul.net2.9 MB0.0 KB830.3 KB3.7 MB 77%5.6 KB
4news.cmpublishers.com1.9 MB1.3 KB328.8 KB2.2 MB 85%2.9 KB
5newsfeed.fu-berlin.de1.6 MB0.0 KB271.2 KB1.9 MB 85%7.5 KB
6ripco-out.feeder.erje.net656.8 KB3.7 KB2.9 MB3.6 MB 17%10.3 KB
7localhost3.4 KB0.0 KB0.0 KB3.4 KB100%1.1 KB
TOTAL: 744.9 MB21.2 KB18.6 MB63.5 MB 70%3.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 21 00:01:01 - 00:59:59 393 2.6% 0.111.2 MB 2.6% 0.34
Apr 21 01:00:00 - 01:59:59 526 3.5% 0.151.7 MB 3.8% 0.49
Apr 21 02:00:00 - 02:59:59 643 4.3% 0.182.1 MB 4.8% 0.61
Apr 21 03:00:00 - 03:59:59 643 4.3% 0.181.8 MB 4.0% 0.51
Apr 21 04:00:00 - 04:59:59 648 4.3% 0.181.7 MB 3.8% 0.48
Apr 21 05:00:00 - 05:59:59 651 4.3% 0.181.7 MB 3.8% 0.48
Apr 21 06:00:00 - 06:59:59 606 4.0% 0.171.7 MB 3.7% 0.48
Apr 21 07:00:00 - 07:59:59 661 4.4% 0.181.7 MB 3.7% 0.48
Apr 21 08:00:00 - 08:59:59 675 4.5% 0.192.1 MB 4.6% 0.59
Apr 21 09:00:00 - 09:59:59 222 1.5% 0.06642.0 KB 1.4% 0.18
Apr 21 10:00:00 - 10:59:59 1400 9.3% 0.394.1 MB 9.0% 1.15
Apr 21 11:00:00 - 11:59:59 838 5.6% 0.232.7 MB 5.9% 0.76
Apr 21 12:00:00 - 12:59:59 801 5.3% 0.222.3 MB 5.1% 0.65
Apr 21 13:00:00 - 13:59:59 820 5.4% 0.232.3 MB 5.2% 0.67
Apr 21 14:00:00 - 14:59:59 789 5.2% 0.222.2 MB 5.0% 0.64
Apr 21 15:00:00 - 15:59:59 874 5.8% 0.242.9 MB 6.4% 0.82
Apr 21 16:00:00 - 16:59:59 730 4.8% 0.202.1 MB 4.7% 0.61
Apr 21 17:00:00 - 17:59:59 597 4.0% 0.172.0 MB 4.4% 0.56
Apr 21 18:00:00 - 18:59:59 485 3.2% 0.131.6 MB 3.5% 0.45
Apr 21 19:00:00 - 19:59:59 559 3.7% 0.161.8 MB 4.0% 0.51
Apr 21 20:00:00 - 20:59:59 339 2.2% 0.091.0 MB 2.3% 0.29
Apr 21 21:00:00 - 21:59:59 560 3.7% 0.161.6 MB 3.5% 0.45
Apr 21 22:00:00 - 22:59:59 314 2.1% 0.091.0 MB 2.3% 0.30
Apr 21 23:00:00 - 23:57:33 319 2.1% 0.091.1 MB 2.5% 0.33
TOTAL: 23:56:32 15093 100.0% 0.1845.0 MB 100.0% 0.53
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
ONECHIAREA979.4 MB685.0 MB 69.9% 0 0.02 680.15
TWOBIGAREA22.1 GB264.0 MB 1.2% 29 0.54 501.86
TOTAL: 2-23.1 GB949.0 MB 4.0% 29 0.52-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 3498 1749 0 1 3 0 0 0 1745
2newsfeed.neva.ru 1825 650 0 3 0 0 0 0 1172
3ripco-out.feeder.erje.net 232 217 0 1 0 0 0 0 14
4news.cmpublishers.com 106 8 0 1 0 0 0 0 97
5newsfeed.fu-berlin.de 103 0 0 0 0 0 0 0 103
6news-in.rahul.net 75 31 0 0 0 0 0 0 44
TOTAL: 6 5839 2655 0 6 3 0 0 0 3175

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1835
alt.binaries.ftd 182
alt.astrology.pro 177
perl.cpan.uploads 83
free.uk.astrology 36
free.it.scienza.fisica 34
mpc.lists.freebsd.ports.bugs 29
it.tlc.cellulari.android 24
fido7.su.pol.news 23
free.willey 22
it.comp.os.win.windows10 20
gmane.linux.alsa.devel 12
grisbi.bugsreports 10
alt.bestjobsusa.computer.jobs 9
mpc.lists.nanog 9
uk.rec.cycling.moderated 7
mozilla.test 7
alt.clearing.o5 7
linux.debian.maint.python 6
alt.my.job.sucks 5
TOTAL: 93 2655

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

GroupsCount
pl.praca.oferowana 1
alt.penthouse.sex.wanted.escorts.ads 1
bit.listserv.catholic 1
TOTAL: 3 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1902
Too many newsgroups 729
EMP (phl) 171
Binary in non-binary group 56
Scoring filter (8) 21
Scoring filter (9) 16
Scoring filter (10) 14
Scoring filter (16) 9
Attached HTML file 5
Scoring filter (18) 2
Scoring filter (11) 1
TOTAL: 11 2926

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feed.uzoreto.com 6
TOTAL: 1 6
Huge articles
newsfeed.fu-berlin.de 1
TOTAL: 1 1
Including strange strings
newsfeed.fu-berlin.de 71
newsfeed.neva.ru 40
ripco-out.feed.uzoreto.com 8
news-in.rahul.net 1
TOTAL: 4 120
TOTAL: 3 127

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1rahul.net 11648 129 7225 2 0 1 1%23:00:54
2news.cmpublishers.com 17394 10 14146 95 0 62 0%23:12:14
3feeder.erje.net 13745 4 13613 0 0 0 0%23:00:54
4news.uzoreto.com 4914 3 4911 0 0 0 0%23:00:54
5newsfeed.neva.ru 11487 2 11450 1 0 0 0%23:00:18
6fu-berlin.de 13815 2 13809 0 0 0 0%23:00:54
7remote6hme0.ripco.com 5 2 0 3 0 0 40%01:16:02
8news.snarked.org 0 0 0 0 0 14972 0%23:35:50
TOTAL: 8 73008 152 65154 101 0 15035 0%163:08:00
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net1.3 MB160.8 KB1.4 MB0.0 KB/s11.1 KB23:00:54
2news.cmpublishers.com30.7 KB289.8 KB320.5 KB0.0 KB/s3.1 KB23:12:14
3feeder.erje.net6.9 KB0.0 KB6.9 KB0.0 KB/s1.7 KB23:00:54
4news.uzoreto.com3.4 KB0.0 KB3.4 KB0.0 KB/s1.1 KB23:00:54
5newsfeed.neva.ru2.6 KB0.8 KB3.4 KB0.0 KB/s1.1 KB23:00:18
6fu-berlin.de2.6 KB0.0 KB2.6 KB0.0 KB/s1.3 KB23:00:54
7remote6hme0.ripco.com1.4 KB10.7 KB12.2 KB0.0 KB/s2.4 KB01:16:02
8news.snarked.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:35:50
TOTAL: 81.3 MB462.2 KB1.8 MB0.0 KB/s7.1 KB163:08:00
Outgoing feeds (innfeed) by Volume

Rnews connections rejected:

ReasonConn
400 No such file or directory writing SMstore file -- throttling 3
TOTAL: 1 3

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 143 6881.8 MB 260 2 000:04:25
2shell3 10 326955.2 KB 48 1 001:36:37
3108-237-13-94.lightspeed.cicril.sbcglobal.net 1 125352.6 KB 3 0 000:00:25
TOTAL: 3 154 11393.1 MB 311 3 001:41:28

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 143 6881.8 MB 260 2 000:04:25
2? 10 326955.2 KB 48 1 001:36:37
3*.lightspeed.cicril.sbcglobal.net 1 125352.6 KB 3 0 000:00:25
TOTAL: 3 154 11393.1 MB 311 3 001:41:28

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 4.354 3.226 0.00001:36:37
ftupet.com 14.574 9.317 0.00000:04:25
108-237-13-94.lightspeed.cicril.sbcglobal.net 0.181 0.061 0.00000:00:25
TOTAL: 3 19.109 12.604 0.00001:41:28

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3 4 0
TOTAL: 1 4 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 792 69.5%
2soc 217 19.1%
3comp 53 4.7%
4alt 29 2.5%
5news 14 1.2%
6misc 13 1.1%
7sci 12 1.1%
8chi 7 0.6%
9ba 2 0.2%
TOTAL: 9 1139100.0%

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

NewsgroupCount
1rec.arts.tv 578
2soc.history.what-if 217
3rec.food.cooking 173
4rec.bicycles.tech 25
5comp.mobile.android 19
6comp.sys.mac.system 16
7alt.appalachian 10
8comp.mail.pine 8
9misc.phone.mobile.iphone 7
10sci.crypt 7
11alt.video.digital-tv 6
12comp.mobile.ipad 6
13news.groups.questions 6
14misc.consumers.frugal-living 6
15sci.electronics.repair 5
16chi.test 4
17rec.radio.broadcasting 4
18news.software.nntp 3
19rec.puzzles 3
20news.admin.net-abuse.email 3
21chi.weather 3
22comp.protocols.dns.bind 2
23alt.tasteless 2
24ba.internet 2
25rec.heraldry 2
26alt.comp.os.windows-10 2
27rec.radio.swap 2
28alt.vacation.las-vegas 2
29alt.home.repair 2
30rec.gardens.edible 2
31alt.cellular.verizon 1
32rec.woodworking 1
33comp.security.ssh 1
34news.lists.misc 1
35rec.audio.tubes 1
36alt.cellular.t-mobile 1
37rec.bicycles.misc 1
38comp.unix.solaris 1
39news.software.readers 1
40alt.security.alarms 1
41alt.online-services.roadrunner 1
42alt.dbs.directv 1
TOTAL: 42 1139