Daily Usenet report for news.ripco.com

May 30 00:01:01 -- May 30 23:57:34

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

May 30 17:16:41 remote5bge0 nnrpd[2012]: [ID 801593 news.crit] failed to strdup 14 bytes at tradspool/tradspool.c line 111: Resource temporarily unavailable
May 30 17:26:36 remote5bge0 nnrpd[2685]: [ID 801593 news.crit] failed to malloc 16 bytes at tradspool/tradspool.c line 160: Resource temporarily unavailable
May 30 17:26:37 remote5bge0 nnrpd[2686]: [ID 801593 news.crit] failed to strdup 16 bytes at tradspool/tradspool.c line 111: Resource temporarily unavailable
May 30 17:26:39 remote5bge0 nnrpd[2684]: [ID 801593 news.crit] failed to strdup 33 bytes at tradspool/tradspool.c line 111: Resource temporarily unavailable
May 30 18:41:19 remote5bge0 overchan[2133]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 54553 57.3%6.8 MB 49.8%
inn 19837 20.8%3.5 MB 25.5%
innd 17486 18.4%2.7 MB 19.7%
innfeed 3360 3.5%719.4 KB 5.1%
controlchan 2 0.0%0.2 KB 0.0%
overchan 1 0.0%0.3 KB 0.0%
TOTAL: 6 95239 100.0%13.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 165542 51.4%
Cache misses 156798 48.6%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 322340 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%174 0.000 0.000 0.000
article cleanup00:00:00.410 0.0%39162 0.000 0.010 0.049
article logging00:00:00.674 0.0%39586 0.000 0.017 0.055
article parse00:00:01.438 0.0%93400 0.000 0.015 0.043
article write00:01:07.638 0.0%29814 0.682 2.269 37.333
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.324 0.0%327880 0.000 0.001 0.005
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:01.842 0.0%324438 0.001 0.006 0.189
history sync00:00:00.204 0.0%796 0.000 0.256 2.333
history write00:00:03.136 0.0%39094 0.000 0.080 3.045
idle47:20:16.032 99.2%376842 170.919 452.221 1236.462
nntp read00:00:02.366 0.0%360408 0.000 0.007 0.018
overview write00:00:00.226 0.0%29814 0.000 0.008 0.043
perl filter00:00:55.042 0.0%39092 1.168 1.408 5.192
python filter00:00:01.410 0.0%39104 0.000 0.036 0.096
site send00:00:02.102 0.0%89378 0.000 0.024 0.049
TOTAL: 47:41:51.34447:22:32.844 99.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.160 0.0%36682 0.000 0.004 0.040
article prepare00:00:00.002 0.0%11912 0.000 0.000 0.012
article read00:00:00.388 0.0%2634 0.000 0.147 9.125
backlog stats00:00:00.278 0.0%324992 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.896 0.0%221408 0.000 0.009 0.018
data write00:00:02.910 0.0%192242 0.003 0.015 0.027
idle47:52:12.402100.0%324992 201.789 530.267 1604.455
status file00:00:02.124 0.0%368 0.000 5.772 31.000
TOTAL: 47:52:47.71647:52:20.160100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:26:27.172 66.0%18260 0.000 678.377 15610.576
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:07.076 0.0%655390 0.000 0.011 47.000
readart00:00:21.332 0.1%2250 0.000 9.481 69.000
TOTAL: 05:12:54.49203:26:55.580 66.1%----

Control commands to INND:

CommandNumber
L 2
flush 2
go 4
logmode 2
mode 299
name 2
pause 2
paused 2
reserve 4
TOTAL: 9 319

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.snarked.org 56 28226 8734 19156 336 30%51:49:24
2ripco-out.feed.uzoreto.com 1148 33442 7918 20480 5044 23%94:37:34
3newsfeed.neva.ru 572 42948 6206 34856 1886 14%282:48:44
4feeder3.feed-ams.uzoreto.com 574 17092 5502 10804 786 32%47:19:14
5newsfeed.fu-berlin.de 4 12038 624 11374 40 5%42:46:04
6news.cmpublishers.com 50 8892 416 8402 74 4%48:00:08
7news.lightlink.com 2 6500 170 6172 158 2%45:34:24
8news-in.rahul.net 296 14190 156 14026 8 1%26:17:12
9ripco-out.feeder.erje.net 576 32502 110 31340 1052 0%47:35:56
10localhost 158 12 12 0 0 100%00:00:00
TOTAL: 10 3436 195842 29848 156610 9384 15%686:48:40
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org25.6 MB16.7 KB1.3 MB26.8 MB 95%3.0 KB
2ripco-out.feed.uzoreto.com19.9 MB51.6 KB10.7 MB30.6 MB 64%2.4 KB
3newsfeed.neva.ru17.7 MB60.9 KB5.7 MB23.4 MB 75%3.0 KB
4feeder3.feed-ams.uzoreto.com13.5 MB13.7 KB2.1 MB15.6 MB 86%2.5 KB
5newsfeed.fu-berlin.de8.7 MB10.3 KB667.0 KB9.4 MB 92%14.5 KB
6news.lightlink.com6.1 MB7.8 KB8.2 MB14.3 MB 42%44.7 KB
7news-in.rahul.net2.4 MB0.0 KB97.5 KB2.5 MB 96%15.6 KB
8news.cmpublishers.com1.2 MB7.8 KB251.7 KB1.5 MB 83%3.1 KB
9ripco-out.feeder.erje.net748.6 KB23.9 KB10.9 MB11.6 MB 6%10.3 KB
10localhost22.0 KB0.0 KB0.0 KB22.0 KB100%1.8 KB
TOTAL: 1095.9 MB192.8 KB39.8 MB135.9 MB 70%3.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 30 00:01:01 - 00:59:59 391 2.6% 0.111.3 MB 2.7% 0.37
May 30 01:00:00 - 01:59:59 431 2.9% 0.121.2 MB 2.4% 0.33
May 30 02:00:00 - 02:59:59 551 3.7% 0.151.3 MB 2.8% 0.38
May 30 03:00:00 - 03:59:59 605 4.0% 0.171.6 MB 3.3% 0.44
May 30 04:00:00 - 04:59:59 641 4.3% 0.182.0 MB 4.2% 0.57
May 30 05:00:00 - 05:59:59 700 4.7% 0.192.0 MB 4.1% 0.56
May 30 06:00:00 - 06:59:59 709 4.7% 0.202.4 MB 4.9% 0.67
May 30 07:00:00 - 07:59:59 784 5.2% 0.222.3 MB 4.9% 0.66
May 30 08:00:00 - 08:59:59 917 6.1% 0.253.0 MB 6.2% 0.84
May 30 09:00:00 - 09:59:59 851 5.7% 0.242.2 MB 4.7% 0.64
May 30 10:00:00 - 10:59:59 952 6.4% 0.263.7 MB 7.7% 1.05
May 30 11:00:00 - 11:59:59 847 5.7% 0.242.9 MB 6.0% 0.82
May 30 12:00:00 - 12:59:59 837 5.6% 0.232.2 MB 4.6% 0.63
May 30 13:00:00 - 13:59:59 803 5.4% 0.222.6 MB 5.4% 0.74
May 30 14:00:00 - 14:59:59 752 5.0% 0.212.4 MB 5.0% 0.68
May 30 15:00:00 - 15:59:59 763 5.1% 0.212.5 MB 5.2% 0.71
May 30 16:00:00 - 16:59:59 605 4.0% 0.171.7 MB 3.6% 0.49
May 30 17:00:00 - 17:59:59 514 3.4% 0.141.6 MB 3.3% 0.45
May 30 18:00:00 - 18:59:59 449 3.0% 0.121.6 MB 3.3% 0.45
May 30 19:00:00 - 19:59:59 510 3.4% 0.141.7 MB 3.5% 0.48
May 30 20:00:00 - 20:59:59 379 2.5% 0.111.9 MB 4.0% 0.55
May 30 21:00:00 - 21:59:59 390 2.6% 0.111.7 MB 3.5% 0.47
May 30 22:00:00 - 22:59:59 297 2.0% 0.081.4 MB 2.9% 0.40
May 30 23:00:00 - 23:57:34 269 1.8% 0.08900.7 KB 1.8% 0.26
TOTAL: 23:56:33 14947 100.0% 0.1747.9 MB 100.0% 0.57
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2533 1977 0 10 3 0 0 0 543
2newsfeed.neva.ru 994 419 0 11 0 0 0 0 564
3ripco-out.feeder.erje.net 524 511 0 3 0 0 0 0 10
4feeder3.feed-ams.uzoreto.com 397 218 0 3 1 0 0 0 175
5news.snarked.org 164 68 0 3 1 0 0 0 92
6newsfeed.fu-berlin.de 164 0 0 2 0 0 0 0 162
7news.lightlink.com 81 0 0 1 0 0 0 0 80
8news.cmpublishers.com 29 3 0 1 0 0 0 0 25
9news-in.rahul.net 4 0 0 0 0 0 0 0 4
TOTAL: 9 4890 3196 0 34 5 0 0 0 1655

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2343
alt.binaries.ftd 283
it.tlc.cellulari.android 69
fido7.su.pol.news 48
alt.binaries.nl 42
perl.cpan.uploads 30
free.it.scienza.fisica 28
uk.net.news.moderation 24
alt.binaries.nospam.female.bodyhair.pubes 17
alt.binaries.e-books 16
fr.usenet.abus.nocem 13
scot.general 12
alt.binaries.pictures.scenic 12
it.comp.os.win.windows10 11
alt.binaries.pictures.earlmiller 11
de.comp.lang.php 10
free.willey 9
free.ucp 8
alt.binaries.pictures.wallpaper.duplicates 8
alt.binaries.3d.poser 7
TOTAL: 122 3196

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

GroupsCount
christnet.bible 3
christnet.theology 1
rec.travel.resorts.all-inclusive 1
TOTAL: 3 5

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1572
Too many newsgroups 910
Binary in non-binary group 176
EMP (phl) 112
Scoring filter (10) 34
Scoring filter (8) 28
HTML post 16
Scoring filter (12) 12
Scoring filter (11) 8
Scoring filter (16) 6
Scoring filter (9) 6
Scoring filter (18) 2
Scoring filter (14) 2
TOTAL: 13 2884

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Including strange strings
newsfeed.fu-berlin.de 147
newsfeed.neva.ru 49
ripco-out.feed.uzoreto.com 11
feeder3.feed-ams.uzoreto.com 4
news.snarked.org 1
TOTAL: 5 212
TOTAL: 2 230

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 10716 1908 4808 2 0 42 17%47:40:00
2rahul.net 17316 412 13130 6 0 6654 2%47:51:12
3news.snarked.org 33304 44 20832 16 0 56 0%47:36:02
4feeder.erje.net 28230 36 27754 26 0 0 0%47:40:02
5news.cmpublishers.com 37126 32 28608 406 0 34 0%47:37:14
6fu-berlin.de 27370 20 27348 0 0 0 0%47:40:02
7news.uzoreto.com 16440 18 16022 6 0 68 0%47:40:02
8newsfeed.neva.ru 23780 16 23550 10 0 0 0%47:51:22
9remote6hme0.ripco.com 8 2 0 6 0 0 25%01:32:16
TOTAL: 9 194290 2488 162052 478 0 6854 1%383:08:12
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com5.6 MB4.1 KB5.6 MB0.0 KB/s3.0 KB47:40:00
2rahul.net3.5 MB12.5 KB3.5 MB0.0 KB/s8.6 KB47:51:12
3news.cmpublishers.com144.6 KB1.1 MB1.2 MB0.0 KB/s2.9 KB47:37:14
4news.snarked.org92.3 KB39.8 KB132.1 KB0.0 KB/s2.2 KB47:36:02
5feeder.erje.net81.0 KB151.6 KB232.6 KB0.0 KB/s3.8 KB47:40:02
6newsfeed.neva.ru61.5 KB19.5 KB81.0 KB0.0 KB/s3.1 KB47:51:22
7fu-berlin.de45.4 KB0.0 KB45.4 KB0.0 KB/s2.3 KB47:40:02
8news.uzoreto.com37.0 KB21.0 KB58.0 KB0.0 KB/s2.4 KB47:40:02
9remote6hme0.ripco.com2.0 KB32.0 KB34.0 KB0.0 KB/s4.3 KB01:32:16
TOTAL: 99.6 MB1.4 MB10.9 MB0.0 KB/s3.8 KB383:08:12
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1shell3 20 7581.9 MB 65 4 003:20:48
2ftupet.com 286 7421.9 MB 358 8 000:07:39
TOTAL: 2 306 15003.9 MB 423 12 005:03:03

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 20 7581.9 MB 65 4 003:20:48
2*.com 286 7421.9 MB 358 8 000:07:39
TOTAL: 2 306 15003.9 MB 423 12 003:28:27

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 4.642 2.160 0.00003:20:48
84-105-134-55.cable.dynamic.v4.ziggo.nl 922.862 257.060 0.00001:33:53
ftupet.com 45.738 22.716 0.00000:07:39
197.185.111.146 4.842 1.384 0.00000:00:19
104.251.79.24 9.626 2.560 0.00000:00:13
58.109-247-134.customer.lyse.net 0.410 0.128 0.00000:00:02
5.253.204.14 1.208 0.356 0.00000:00:01
37-219-202-82.nat.bb.dnainternet.fi 0.620 0.164 0.00000:00:01
scan-141.security.ipip.net 0.198 0.052 0.00000:00:01
37-219-204-85.nat.bb.dnainternet.fi 0.200 0.054 0.00000:00:01
92.116.13.156 0.418 0.114 0.00000:00:00
66.11.163.170 0.404 0.110 0.00000:00:00
TOTAL: 12 991.168 286.858 0.00005:03:03

Curious NNRP server explorers [Top 20]:

SystemConn
84-105-134-55.cable.dynamic.v4.ziggo.nl 2
TOTAL: 1 2

NNRP no permission clients:

SystemConn
84-105-134-55.cable.dynamic.v4.ziggo.nl 9108
104.251.79.24 96
197.185.111.146 48
5.253.204.14 12
37-219-202-82.nat.bb.dnainternet.fi 6
58.109-247-134.customer.lyse.net 4
92.116.13.156 4
66.11.163.170 4
scan-141.security.ipip.net 2
37-219-204-85.nat.bb.dnainternet.fi 2
TOTAL: 10 9286

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
shell3 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 922 61.3%
2alt 231 15.4%
3misc 164 10.9%
4comp 130 8.6%
5rocksolid 22 1.5%
6sci 20 1.3%
7news 8 0.5%
8chi 6 0.4%
TOTAL: 8 1503100.0%

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

NewsgroupCount
1rec.arts.tv 502
2rec.food.cooking 336
3misc.phone.mobile.iphone 164
4alt.comp.os.windows-10 162
5rec.bicycles.tech 70
6comp.mobile.android 60
7comp.sys.mac.system 52
8alt.stupidity 29
9sci.electronics.repair 20
10alt.online-service.comcast 16
11comp.protocols.dns.bind 10
12alt.free.newsservers 10
13rocksolid.shared.general 8
14rec.radio.broadcasting 6
15rocksolid.shared.news 6
16alt.smokers 6
17alt.security.alarms 6
18comp.mobile.ipad 4
19rec.radio.swap 4
20comp.unix.solaris 4
21rocksolid.shared.tor 4
22news.software.readers 4
23chi.weather 4
24news.groups.questions 4
25rocksolid.shared.test 2
26rec.antiques.radio+phono 2
27rocksolid.shared.i2p 2
28alt.video.tape-trading 2
29chi.general 2
30rec.gardens.edible 2
TOTAL: 30 1503