Daily Usenet report for news.ripco.com

Nov 8 00:01:03 -- Nov 8 23:57:52

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Nov 8 02:07:11 remote5bge0 overchan[7133]: [ID 702911 news.error] input line too long, skipping
Nov 8 04:34:07 remote5bge0 overchan[7133]: [ID 702911 news.error] input line too long, skipping
Nov 8 05:20:24 remote5bge0 overchan[7133]: [ID 702911 news.error] input line too long, skipping
Nov 8 07:15:04 remote5bge0 overchan[7133]: [ID 702911 news.error] input line too long, skipping
Nov 8 07:25:51 remote5bge0 overchan[7133]: [ID 702911 news.error] input line too long, skipping
Nov 8 08:23:18 remote5bge0 overchan[7133]: [ID 702911 news.error] input line too long, skipping
Nov 8 12:59:21 remote5bge0 overchan[8940]: [ID 702911 news.error] input line too long, skipping
Nov 8 15:46:31 remote5bge0 overchan[8940]: [ID 702911 news.error] input line too long, skipping
Nov 8 21:04:16 remote5bge0 overchan[8940]: [ID 702911 news.error] input line too long, skipping
Nov 8 22:04:32 remote5bge0 overchan[8940]: [ID 702911 news.error] input line too long, skipping
Nov 8 22:04:48 remote5bge0 overchan[8940]: [ID 702911 news.error] input line too long, skipping
Nov 8 23:56:2

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 33792 40.4%4.2 MB 33.4%
inn 23962 28.6%4.1 MB 33.1%
innd 22314 26.6%3.4 MB 27.5%
innfeed 3656 4.4%766.2 KB 6.0%
overchan 11 0.0%1.5 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 83737 100.0%12.5 MB100.0%

History cache:

ReasonCount%Count
Cache misses 217298 54.3%
Do not exist 183232 45.7%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 400530 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%484 0.000 0.000 0.000
article cleanup00:00:00.476 0.0%47264 0.000 0.010 0.034
article logging00:00:00.872 0.0%47834 0.000 0.018 0.432
article parse00:00:01.890 0.0%113876 0.000 0.017 0.042
article write00:00:48.292 0.0%34838 0.315 1.386 35.363
artlog/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
data move00:00:00.282 0.0%417074 0.000 0.001 0.003
hishave/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.710 0.0%401602 0.001 0.012 0.645
history sync00:00:00.296 0.0%794 0.000 0.373 3.000
history write00:00:07.510 0.0%47260 0.000 0.159 5.323
hiswrite/artparse00:00:00.002 0.0%4 0.000 0.500 1.000
idle47:38:59.870 99.9%449878 181.127 381.303 832.089
nntp read00:00:02.618 0.0%448090 0.001 0.006 0.017
overview write00:00:00.208 0.0%34838 0.000 0.006 0.029
perl filter00:01:00.620 0.0%47250 1.022 1.283 2.157
python filter00:00:01.234 0.0%47262 0.000 0.026 0.071
site send00:00:01.946 0.0%104480 0.000 0.019 0.039
TOTAL: 47:41:54.21047:41:10.826100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.180 0.0%49018 0.000 0.004 0.020
article prepare00:00:00.006 0.0%24368 0.000 0.000 0.025
article read00:00:00.624 0.0%4928 0.000 0.127 4.933
backlog stats00:00:00.226 0.0%391450 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.416 0.0%260218 0.002 0.009 0.018
data write00:00:03.578 0.0%225796 0.003 0.016 0.029
idle47:53:07.654100.0%391450 162.421 440.382 1035.730
status file00:00:05.238 0.0%356 0.000 14.713 52.000
TOTAL: 47:53:32.50047:53:19.922100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:06.960 0.0%465488 0.000 0.015 0.033
idle43:11:36.562 98.8%500938 0.000 310.411300419.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:52.070 0.0%3468590 0.000 0.015 0.333
readart00:00:20.554 0.0%2842 0.000 7.232 36.000
TOTAL: 43:42:52.43043:12:56.146 98.9%----

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 42314 11262 28254 2798 26%94:37:24
2news.snarked.org 46 43732 10406 28772 4554 23%54:14:36
3newsfeed.neva.ru 558 64874 6904 56316 1654 10%280:07:14
4feeder3.feed-ams.uzoreto.com 536 18304 4630 12204 1470 25%46:07:56
5newsfeed.fu-berlin.de 2 18168 1058 16990 120 5%50:10:48
6news.lightlink.com 2 12022 288 10602 1132 2%49:06:16
7news-in.rahul.net 574 21280 160 21090 30 0%47:20:18
8ripco-out.feeder.erje.net 574 37134 50 36336 748 0%47:18:24
9news.cmpublishers.com 34 8870 32 8832 6 0%32:53:34
10localhost 156 12 12 0 0 100%00:00:00
TOTAL: 10 3630 266710 34802 219396 12512 13%701:56:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org30.5 MB0.0 KB15.3 MB45.8 MB 66%3.1 KB
2ripco-out.feed.uzoreto.com28.1 MB0.0 KB6.8 MB34.9 MB 80%2.5 KB
3newsfeed.neva.ru19.6 MB5.3 KB6.5 MB26.1 MB 75%3.1 KB
4feeder3.feed-ams.uzoreto.com11.5 MB0.0 KB3.3 MB14.8 MB 77%2.5 KB
5news-in.rahul.net7.4 MB0.0 KB1.2 MB8.6 MB 85%46.4 KB
6news.lightlink.com5.7 MB0.0 KB37.0 MB42.7 MB 13%30.8 KB
7newsfeed.fu-berlin.de3.7 MB0.0 KB2.2 MB5.9 MB 62%5.2 KB
8ripco-out.feeder.erje.net97.7 KB0.0 KB5.8 MB5.9 MB 1%7.5 KB
9news.cmpublishers.com62.9 KB0.0 KB7.0 KB69.9 KB 90%1.8 KB
10localhost22.6 KB0.0 KB0.0 KB22.6 KB100%1.9 KB
TOTAL: 10106.6 MB5.3 KB78.1 MB184.7 MB 57%4.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 8 00:01:03 - 00:59:59 412 2.4% 0.121.2 MB 2.3% 0.36
Nov 8 01:00:00 - 01:59:59 492 2.8% 0.141.4 MB 2.6% 0.39
Nov 8 02:00:00 - 02:59:59 712 4.1% 0.201.8 MB 3.3% 0.50
Nov 8 03:00:00 - 03:59:59 842 4.8% 0.232.5 MB 4.8% 0.72
Nov 8 04:00:00 - 04:59:59 813 4.7% 0.232.6 MB 4.8% 0.73
Nov 8 05:00:00 - 05:59:59 735 4.2% 0.202.1 MB 4.0% 0.60
Nov 8 06:00:00 - 06:59:59 833 4.8% 0.232.5 MB 4.7% 0.71
Nov 8 07:00:00 - 07:59:59 805 4.6% 0.222.3 MB 4.3% 0.64
Nov 8 08:00:00 - 08:59:59 1049 6.0% 0.293.1 MB 5.8% 0.88
Nov 8 09:00:00 - 09:59:59 1030 5.9% 0.292.7 MB 5.0% 0.76
Nov 8 10:00:00 - 10:59:59 925 5.3% 0.262.5 MB 4.7% 0.70
Nov 8 11:00:00 - 11:59:59 1104 6.3% 0.313.5 MB 6.5% 0.99
Nov 8 12:00:00 - 12:59:59 979 5.6% 0.273.2 MB 6.0% 0.91
Nov 8 13:00:00 - 13:59:59 984 5.6% 0.273.2 MB 6.0% 0.90
Nov 8 14:00:00 - 14:59:59 953 5.5% 0.262.8 MB 5.2% 0.79
Nov 8 15:00:00 - 15:59:59 902 5.2% 0.252.9 MB 5.5% 0.83
Nov 8 16:00:00 - 16:59:59 841 4.8% 0.233.0 MB 5.7% 0.86
Nov 8 17:00:00 - 17:59:59 619 3.5% 0.171.9 MB 3.6% 0.55
Nov 8 18:00:00 - 18:59:59 448 2.6% 0.121.5 MB 2.7% 0.41
Nov 8 19:00:00 - 19:59:59 419 2.4% 0.121.2 MB 2.3% 0.35
Nov 8 20:00:00 - 20:59:59 442 2.5% 0.121.6 MB 3.0% 0.45
Nov 8 21:00:00 - 21:59:59 359 2.1% 0.101.5 MB 2.8% 0.42
Nov 8 22:00:00 - 22:59:59 386 2.2% 0.111.2 MB 2.2% 0.33
Nov 8 23:00:00 - 23:57:52 369 2.1% 0.111.1 MB 2.0% 0.31
TOTAL: 23:56:49 17453 100.0% 0.2053.1 MB 100.0% 0.63
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 2279 101 0 0 3 0 0 0 2175
2ripco-out.feed.uzoreto.com 1406 1020 0 0 1 0 0 0 385
3newsfeed.neva.ru 903 571 0 2 6 0 0 0 324
4feeder3.feed-ams.uzoreto.com 738 578 0 0 0 0 0 0 160
5news.lightlink.com 545 1 0 0 0 0 0 0 544
6ripco-out.feeder.erje.net 374 369 0 0 0 0 0 0 5
7newsfeed.fu-berlin.de 247 0 0 0 0 0 0 0 247
8news-in.rahul.net 15 0 0 0 0 0 0 0 15
9news.cmpublishers.com 2 0 0 0 0 0 0 0 2
TOTAL: 9 6509 2640 0 2 10 0 0 0 3857

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1613
alt.binaries.ftd 169
alt.astrology.pro 75
it.tlc.cellulari.android 60
fido7.su.pol.news 48
perl.cpan.uploads 46
free.uk.astrology 44
alt.bestjobsusa.computer.jobs 37
alt.jobs.jobsearch 25
gmane.linux.alsa.devel 23
at.jobs 22
alt.music.pink-floyd.publius 21
free.willey 20
alt.binaries.drwho 20
fr.rec.loisirs-creatifs 17
alt.binaries.nospam.female.bodyhair.pubes 16
alt.my.job.sucks 14
linux.debian.maint.python 14
att.jobs 14
nemo.abuse 14
TOTAL: 146 2640

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

GroupsCount
pl.praca.oferowana 6
christnet.theology 1
alt.christnet.religion 1
alt.christnet.christnews 1
bit.listserv.catholic 1
TOTAL: 5 10

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4520
Too many newsgroups 1310
Binary in non-binary group 916
EMP (phl) 210
Scoring filter (16) 66
Attached HTML file 54
Scoring filter (9) 20
Scoring filter (8) 12
HTML post 10
Scoring filter (18) 8
Scoring filter (10) 6
Scoring filter (12) 4
Scoring filter (13) 2
Scoring filter (11) 2
TOTAL: 14 7140

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
ripco-out.feed.uzoreto.com 2
feeder3.feed-ams.uzoreto.com 2
ripco-out.feeder.erje.net 1
TOTAL: 3 5
Bad Message-ID's offered
ripco-out.feeder.erje.net 22
ripco-out.feed.uzoreto.com 4
TOTAL: 2 26
Huge articles
newsfeed.fu-berlin.de 4
TOTAL: 1 4
Including strange strings
newsfeed.fu-berlin.de 201
newsfeed.neva.ru 74
ripco-out.feed.uzoreto.com 7
news.snarked.org 2
feeder3.feed-ams.uzoreto.com 1
TOTAL: 5 285
TOTAL: 4 320

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 10760 2834 5314 10 0 0 26%47:40:02
2rahul.net 28112 936 15286 50 0 6 3%47:35:32
3news.cmpublishers.com 40852 128 33346 1036 0 13972 0%47:43:34
4feeder.erje.net 31448 116 30162 446 0 0 0%47:46:24
5news.snarked.org 40880 88 24214 12 0 160 0%47:45:36
6fu-berlin.de 31026 58 30968 0 0 0 0%47:40:02
7newsfeed.neva.ru 28596 36 27718 118 0 0 0%47:52:10
8news.uzoreto.com 19222 36 18372 172 0 0 0%47:40:02
9remote6hme0.ripco.com 0 0 0 0 0 8 0%47:40:00
TOTAL: 9 230896 4232 185380 1844 0 14146 1%429:23:22
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com8.1 MB20.9 KB8.1 MB0.0 KB/s2.9 KB47:40:02
2rahul.net2.8 MB103.4 KB2.9 MB0.0 KB/s3.0 KB47:35:32
3news.cmpublishers.com603.1 KB3.0 MB3.6 MB0.0 KB/s3.1 KB47:43:34
4feeder.erje.net452.1 KB1.5 MB2.0 MB0.0 KB/s3.6 KB47:46:24
5news.snarked.org391.7 KB34.3 KB426.0 KB0.0 KB/s4.3 KB47:45:36
6newsfeed.neva.ru300.1 KB287.3 KB587.5 KB0.0 KB/s3.8 KB47:52:10
7fu-berlin.de212.5 KB0.0 KB212.5 KB0.0 KB/s3.7 KB47:40:02
8news.uzoreto.com137.9 KB661.3 KB799.2 KB0.0 KB/s3.8 KB47:40:02
9remote6hme0.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s047:40:00
TOTAL: 912.9 MB5.6 MB18.5 MB0.0 KB/s3.1 KB429:23:22
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 10982.9 MB 422 10 000:06:48
2shell3 28 5921.5 MB 56 2 002:23:41
375-151-74-61-independence.hfc.comcastbusiness.net 8 43.5 KB 4 0 000:12:54
4108-219-229-47.lightspeed.irvnca.sbcglobal.net 2 00.0 KB 0 0 400:04:59
TOTAL: 4 324 16944.4 MB 482 12 443:42:43

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 10982.9 MB 422 10 000:06:48
2? 28 5921.5 MB 56 2 002:23:41
3*.hfc.comcastbusiness.net 8 43.5 KB 4 0 000:12:54
4*.security.ipip.net 2 00.0 KB 0 0 000:00:02
5*.cm-5-1d.dynamic.ziggo.nl 2 00.0 KB 0 0 000:00:03
6unresolved 110 00.0 KB 0 0 012:11:46
7*.shodan.io 2 00.0 KB 0 0 000:00:00
8*.cm-12-2c.dynamic.ziggo.nl 66 00.0 KB 0 0 028:42:26
9*.lightspeed.irvnca.sbcglobal.net 2 00.0 KB 0 0 400:04:59
TOTAL: 9 506 16944.4 MB 482 12 443:42:43

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
5469a4b0.cm-12-2c.dynamic.ziggo.nl 15.260 14.208 0.00028:42:26
83.136.84.217 3.680 1.634 0.00005:39:53
185.242.4.116 1.830 0.704 0.00003:47:31
shell3 14.168 5.224 0.00002:23:41
81.171.85.155 2.852 1.012 0.00001:11:47
193.234.48.199 1.422 1.210 0.00000:43:15
81.171.58.205 0.686 0.258 0.00000:20:46
185.217.171.38 1.692 0.380 0.00000:19:35
75-151-74-61-independence.hfc.comcastbusiness.net 1.538 1.302 0.00000:12:54
185.217.171.39 0.900 0.168 0.00000:08:24
ftupet.com 46.238 7.490 0.00000:06:48
108-219-229-47.lightspeed.irvnca.sbcglobal.net 0.342 0.048 0.00000:04:59
107.170.219.65 0.198 0.042 0.00000:00:19
185.163.109.66 0.198 0.040 0.00000:00:07
185.181.102.18 0.198 0.040 0.00000:00:04
5418de60.cm-5-1d.dynamic.ziggo.nl 0.200 0.042 0.00000:00:03
scan-141.security.ipip.net 0.204 0.044 0.00000:00:02
census10.shodan.io 0.202 0.042 0.00000:00:00
TOTAL: 18 91.808 33.888 0.00043:42:43

Curious NNRP server explorers [Top 20]:

SystemConn
5469a4b0.cm-12-2c.dynamic.ziggo.nl 66
83.136.84.217 32
81.171.85.155 26
185.242.4.116 16
185.217.171.38 14
81.171.58.205 6
185.217.171.39 6
193.234.48.199 4
185.181.102.18 2
census10.shodan.io 2
scan-141.security.ipip.net 2
185.163.109.66 2
5418de60.cm-5-1d.dynamic.ziggo.nl 2
107.170.219.65 2
TOTAL: 14 182

NNRP unrecognized commands (by host):

SystemConn
shell3 12
scan-141.security.ipip.net 8
TOTAL: 2 20

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 12
Connection: close 2
GET / HTTP/1.1 2
User-Agent: HTTP Banner Detection (https... 2
Host: 66.251.253.167:119 2
TOTAL: 5 20

NNRP client timeouts [Top 20]:

SystemConnPeer
5469a4b0.cm-12-2c.dynamic.ziggo.nl 60 0
83.136.84.217 26 4
185.242.4.116 16 0
81.171.85.155 4 0
81.171.58.205 2 2
TOTAL: 5 108 6

Newsgroup request counts (by category):

CategoryCountPct
1rec 1232 72.7%
2comp 276 16.3%
3alt 48 2.8%
4misc 46 2.7%
5chi 38 2.2%
6news 24 1.4%
7sci 20 1.2%
8ba 6 0.4%
9hr 2 0.1%
10gnu 2 0.1%
TOTAL: 10 1694100.0%

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

NewsgroupCount
1rec.arts.tv 608
2rec.food.cooking 584
3comp.mobile.android 194
4comp.sys.mac.system 48
5misc.phone.mobile.iphone 30
6chi.general 26
7rec.bicycles.tech 22
8alt.stupidity 22
9sci.electronics.repair 20
10alt.games.warcraft 16
11misc.consumers.frugal-living 16
12comp.dcom.telecom 12
13news.software.readers 10
14chi.weather 10
15news.admin.net-abuse.email 8
16rec.antiques.radio+phono 6
17comp.sys.raspberry-pi 6
18comp.mail.pine 6
19comp.sys.mac.apps 6
20ba.food 6
21alt.tasteless 4
22rec.radio.broadcasting 4
23rec.radio.swap 4
24rec.gardens.edible 4
25alt.satellite.gps.garmin 4
26news.groups.questions 4
27comp.security.ssh 2
28news.software.nntp 2
29hr.test 2
30alt.job 2
31gnu.utils.bug 2
32comp.unix.solaris 2
33chi.eats 2
TOTAL: 33 1694