Daily Usenet report for news.ripco.com

Oct 16 00:01:02 -- Oct 16 23:58:01

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Oct 16 00:51:30 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 01:14:26 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 06:41:09 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 06:42:14 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 08:46:42 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 10:20:34 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 11:12:34 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 11:22:34 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 11:41:38 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 12:17:30 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 14:26:41 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping
Oct 16 14:30:58 remote5bge0 overchan[3037]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 352085 85.5%46.4 MB 84.5%
innd 37441 9.1%5.8 MB 10.6%
nnrpd 18210 4.4%1.9 MB 3.5%
innfeed 4238 1.0%839.6 KB 1.5%
overchan 12 0.0%1.7 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 411988 100.0%55.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2422794 84.3%
Cache misses 450886 15.7%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 2873680 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%186 0.000 0.000 0.000
article cleanup00:00:04.220 0.0%704018 0.000 0.006 0.024
article logging00:00:07.154 0.0%704394 0.000 0.010 0.150
article parse00:00:11.500 0.0%1713650 0.002 0.007 0.032
article write00:00:46.394 0.0%32604 0.310 1.423 70.711
artlog/artparse00:00:00.000 0.0%8 0.000 0.000 0.000
data move00:00:00.768 0.0%1828212 0.000 0.000 0.003
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:21.744 0.0%2876132 0.003 0.008 0.027
history sync00:00:00.488 0.0%696 0.000 0.701 4.500
history write00:02:27.684 0.1%704010 0.024 0.210 1.514
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:41:42.866 99.3%2200624 20.634 78.025 709.804
nntp read00:00:10.010 0.0%2235726 0.001 0.004 0.013
overview write00:00:00.170 0.0%32604 0.000 0.005 0.048
perl filter00:12:15.140 0.4%704002 0.967 1.044 2.398
python filter00:00:12.256 0.0%704008 0.000 0.017 0.047
site send00:00:01.898 0.0%97766 0.000 0.019 0.070
TOTAL: 48:01:17.33647:58:22.292 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.142 0.0%33406 0.000 0.004 0.027
article prepare00:00:00.016 0.0%45184 0.000 0.000 0.015
article read00:00:00.816 0.0%8706 0.000 0.094 8.875
backlog stats00:00:00.318 0.0%429586 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.472 0.0%272022 0.002 0.009 0.017
data write00:00:03.682 0.0%240778 0.003 0.015 0.027
idle47:50:55.752100.0%429586 202.097 400.981 1296.841
status file00:00:02.478 0.0%588 0.000 4.214 42.000
TOTAL: 47:51:19.10047:51:05.676100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:22:16.514 98.9%18698 19.295 649.081 20699.393
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:09.682 0.1%997122 0.000 0.010 1.000
readart00:00:25.216 0.2%2938 0.000 8.583 36.000
TOTAL: 03:24:30.94203:22:51.412 99.2%----

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 65994 14458 38026 13510 21%94:38:04
2news.snarked.org 54 30278 7658 22078 542 25%56:02:16
3newsfeed.neva.ru 580 718220 5862 293694 418664 0%284:54:04
4feeder3.feed-ams.uzoreto.com 574 21906 3068 16674 2164 14%47:28:14
5news.cmpublishers.com 52 9224 566 8610 48 6%47:56:56
6news-in.rahul.net 574 14688 368 14276 44 2%47:20:26
7newsfeed.fu-berlin.de 6 5468 260 5202 6 4%24:08:10
8news.lightlink.com 2 8592 158 8250 184 1%49:48:38
9ripco-out.feeder.erje.net 540 846866 80 34606 812180 0%48:59:10
10localhost 152 6 6 0 0 100%00:00:00
TOTAL: 10 3682 1721242 32484 4414161247342 1%701:15:58
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com34.9 MB4.0 KB20.9 MB55.7 MB 62%2.0 KB
2news.snarked.org20.7 MB7.3 KB1.7 MB22.5 MB 92%2.8 KB
3newsfeed.neva.ru16.1 MB65.4 KB452.6 MB468.8 MB 3%1.1 KB
4feeder3.feed-ams.uzoreto.com7.5 MB0.0 KB3.2 MB10.7 MB 69%2.1 KB
5news-in.rahul.net4.9 MB0.0 KB972.7 KB5.9 MB 83%14.7 KB
6newsfeed.fu-berlin.de1.7 MB0.0 KB26.8 KB1.7 MB 98%6.5 KB
7news.lightlink.com1.3 MB0.0 KB9.1 MB10.4 MB 12%31.1 KB
8news.cmpublishers.com1.1 MB0.0 KB80.7 KB1.1 MB 93%1.9 KB
9ripco-out.feeder.erje.net242.7 KB0.0 KB232.7 MB232.9 MB 0%0.3 KB
10localhost12.9 KB0.0 KB0.0 KB12.9 KB100%2.2 KB
TOTAL: 1088.4 MB76.7 KB721.3 MB809.8 MB 10%0.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 16 00:01:02 - 00:59:59 459 2.8% 0.131.3 MB 2.9% 0.37
Oct 16 01:00:00 - 01:59:59 566 3.5% 0.161.8 MB 4.0% 0.50
Oct 16 02:00:00 - 02:59:59 597 3.7% 0.171.6 MB 3.5% 0.44
Oct 16 03:00:00 - 03:59:59 604 3.7% 0.171.6 MB 3.6% 0.46
Oct 16 04:00:00 - 04:59:59 664 4.1% 0.182.0 MB 4.6% 0.58
Oct 16 05:00:00 - 05:59:59 650 4.0% 0.182.1 MB 4.7% 0.60
Oct 16 06:00:00 - 06:59:59 846 5.2% 0.232.8 MB 6.2% 0.79
Oct 16 07:00:00 - 07:59:59 789 4.8% 0.221.9 MB 4.4% 0.55
Oct 16 08:00:00 - 08:59:59 777 4.8% 0.222.2 MB 5.0% 0.63
Oct 16 09:00:00 - 09:59:59 916 5.6% 0.252.1 MB 4.8% 0.61
Oct 16 10:00:00 - 10:59:59 904 5.6% 0.252.2 MB 4.9% 0.62
Oct 16 11:00:00 - 11:59:59 897 5.5% 0.252.3 MB 5.2% 0.66
Oct 16 12:00:00 - 12:59:59 932 5.7% 0.262.3 MB 5.2% 0.66
Oct 16 13:00:00 - 13:59:59 945 5.8% 0.262.8 MB 6.2% 0.78
Oct 16 14:00:00 - 14:59:59 933 5.7% 0.262.8 MB 6.3% 0.80
Oct 16 15:00:00 - 15:59:59 905 5.6% 0.252.3 MB 5.1% 0.65
Oct 16 16:00:00 - 16:59:59 662 4.1% 0.181.8 MB 4.0% 0.51
Oct 16 17:00:00 - 17:59:59 784 4.8% 0.221.8 MB 4.0% 0.51
Oct 16 18:00:00 - 18:59:59 553 3.4% 0.151.4 MB 3.2% 0.41
Oct 16 19:00:00 - 19:59:59 530 3.3% 0.151.2 MB 2.7% 0.34
Oct 16 20:00:00 - 20:59:59 441 2.7% 0.121.3 MB 2.9% 0.37
Oct 16 21:00:00 - 21:59:59 336 2.1% 0.09941.0 KB 2.1% 0.26
Oct 16 22:00:00 - 22:59:59 304 1.9% 0.08939.0 KB 2.1% 0.26
Oct 16 23:00:00 - 23:58:01 276 1.7% 0.081.1 MB 2.4% 0.32
TOTAL: 23:56:59 16270 100.0% 0.1944.5 MB 100.0% 0.53
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru209292205812 0 6 4 0 0 0 3470
2ripco-out.feeder.erje.net118186113959 0 0 0 0 0 0 4227
3ripco-out.feed.uzoreto.com 6759 5475 0 1 0 0 0 0 1283
4feeder3.feed-ams.uzoreto.com 1083 813 0 0 1 0 0 0 269
5news.snarked.org 278 160 0 0 2 0 0 0 116
6newsfeed.fu-berlin.de 154 0 0 0 0 0 0 0 154
7news.cmpublishers.com 26 13 0 0 0 0 0 0 13
8news-in.rahul.net 22 4 0 0 0 0 0 0 18
9news.lightlink.com 15 1 0 1 0 0 0 0 13
TOTAL: 9335815326237 0 8 7 0 0 0 9563

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.boneless 217006
alt.binaries.mom 10301
alt.binaries.test.yenc 8310
alt.binaries.ath 5071
alt.binaries.frogs 4738
alt.binaries.multimedia 4518
alt.b 4458
alt.binaries.erotica 4381
alt.binaries.kenpsx 3352
alt.binaries.misc 2909
alt.binaries.multimedia.anime.highspeed 2714
free.usenet 2713
alt.binaries.usenet2day 2437
alt.binaries.turkey 2404
alt.binaries.bloaf 1413
alt.binaries.encrypted 1298
a2000.erotica.binaries 1225
alt.binaries.highspeed 1155
alt.binaries.lossless 1049
alt.binaries.wtfnzb.alpha 963
TOTAL: 294 326237

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

GroupsCount
pl.praca.oferowana 5
alt.christnet.religion 1
alt.christnet.christnews 1
TOTAL: 3 7

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 7528
Binary in non-binary group 5924
EMP (md5) 2848
EMP (fsl) 2040
EMP (phl) 216
Scoring filter (9) 92
Scoring filter (8) 80
Scoring filter (10) 18
Scoring filter (11) 6
Scoring filter (12) 2
HTML post 2
TOTAL: 11 18756

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 294
TOTAL: 1 294
Including strange strings
newsfeed.fu-berlin.de 142
newsfeed.neva.ru 37
news.snarked.org 5
ripco-out.feed.uzoreto.com 4
feeder3.feed-ams.uzoreto.com 1
TOTAL: 5 189
No colon-space in header
ripco-out.feeder.erje.net 1
ripco-out.feed.uzoreto.com 1
feeder3.feed-ams.uzoreto.com 1
TOTAL: 3 3
TOTAL: 3 486

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1rahul.net 23396 3792 11002 74 0 0 16%47:40:04
2news.lightlink.com 13980 1706 6394 70 0 2 12%47:50:08
3fu-berlin.de 29890 790 29058 2 0 202 2%47:41:12
4feeder.erje.net 32240 626 28556 312 0 570 1%47:39:58
5news.uzoreto.com 14430 566 13236 404 0 0 3%47:40:04
6newsfeed.neva.ru 35286 320 26162 38 0 4 0%47:35:10
7news.cmpublishers.com 42832 218 28932 2072 0 6 0%47:33:30
8news.snarked.org 38554 68 23872 10 0 120 0%47:39:28
9remote6hme0.ripco.com 0 0 0 0 0 12 0%47:40:02
TOTAL: 9 230608 8086 167212 2982 0 916 3%428:59:36
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net9.6 MB246.9 KB9.8 MB0.1 KB/s2.6 KB47:40:04
2news.lightlink.com6.3 MB508.2 KB6.8 MB0.0 KB/s3.9 KB47:50:08
3feeder.erje.net1.9 MB1.0 MB2.9 MB0.0 KB/s3.2 KB47:39:58
4news.uzoreto.com1.8 MB1.4 MB3.2 MB0.0 KB/s3.4 KB47:40:04
5fu-berlin.de1.3 MB40.0 KB1.3 MB0.0 KB/s1.7 KB47:41:12
6newsfeed.neva.ru1.0 MB113.4 KB1.2 MB0.0 KB/s3.3 KB47:35:10
7news.cmpublishers.com704.5 KB5.4 MB6.1 MB0.0 KB/s2.7 KB47:33:30
8news.snarked.org393.6 KB23.3 KB416.9 KB0.0 KB/s5.3 KB47:39:28
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:02
TOTAL: 922.9 MB8.8 MB31.7 MB0.0 KB/s2.9 KB428:59:36
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 10403.1 MB 428 4 000:07:30
2shell3 20 8542.1 MB 58 2 003:16:05
TOTAL: 2 306 18945.2 MB 486 6 003:24:20

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 10403.1 MB 428 4 000:07:30
2? 20 8542.1 MB 58 2 003:16:05
TOTAL: 2 306 18945.2 MB 486 6 003:23:35

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 5.946 2.856 0.00003:16:05
ftupet.com 47.102 7.600 0.00000:07:30
d54c176a7.access.telenet.be 11.230 2.428 0.00000:00:22
80.82.65.74 8.456 1.736 0.00000:00:19
census7.shodan.io 0.200 0.038 0.00000:00:02
195.239.107.62 0.210 0.042 0.00000:00:00
pool-96-224-80-166.nycmny.fios.verizon.net 0.200 0.038 0.00000:00:00
TOTAL: 7 73.344 14.738 0.00003:24:20

NNRP no permission clients:

SystemConn
d54c176a7.access.telenet.be 112
80.82.65.74 84
census7.shodan.io 2
195.239.107.62 2
pool-96-224-80-166.nycmny.fios.verizon.net 2
TOTAL: 5 202

NNRP unrecognized commands (by host):

SystemConn
shell3 6
TOTAL: 1 6

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 6
TOTAL: 1 6

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3 4 0
TOTAL: 1 4 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1306 69.0%
2alt 450 23.8%
3misc 44 2.3%
4comp 40 2.1%
5sci 28 1.5%
6news 12 0.6%
7chi 10 0.5%
8ba 4 0.2%
TOTAL: 8 1894100.0%

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

NewsgroupCount
1rec.arts.tv 842
2alt.home.repair 398
3rec.food.cooking 360
4rec.bicycles.tech 86
5misc.phone.mobile.iphone 38
6sci.electronics.repair 28
7alt.stupidity 20
8comp.sys.mac.system 20
9alt.online-service.comcast 18
10comp.mobile.android 12
11alt.free.newsservers 12
12news.software.readers 10
13chi.forsale 6
14rec.outdoors.rv-travel 6
15misc.consumers.frugal-living 6
16rec.audio.high-end 4
17rec.radio.broadcasting 4
18comp.mobile.ipad 4
19rec.radio.swap 4
20chi.weather 4
21ba.food 4
22comp.sys.mac.apps 2
23alt.video.tape-trading 2
24comp.protocols.dns.bind 2
25news.groups.questions 2
TOTAL: 25 1894