Daily Usenet report for news.ripco.com

Dec 14 00:01:01 -- Dec 14 23:57:14

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Dec 14 06:56:48 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 10:32:17 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 11:13:58 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 11:24:57 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 12:29:58 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 12:50:36 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 12:54:54 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 18:04:24 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 19:48:48 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping
Dec 14 23:24:25 remote5bge0 overchan[5171]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 19123 32.9%3.6 MB 40.2%
innd 18121 31.2%2.8 MB 31.2%
nnrpd 17206 29.6%1.8 MB 19.8%
innfeed 3602 6.2%809.9 KB 8.8%
overchan 10 0.0%1.4 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 58064 100.0%9.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 181048 52.2%
Cache misses 165952 47.8%
Positive hits 0 0.0%
Negative hits 0 0.0%
TOTAL: 4 347000 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%52 0.000 0.000 0.000
article cleanup00:00:00.504 0.0%37960 0.000 0.013 0.049
article logging00:00:00.780 0.0%38326 0.000 0.020 0.069
article parse00:00:01.450 0.0%96136 0.000 0.015 0.059
article write00:01:59.712 0.1%27538 1.625 4.347 343.691
artlog/artparse00:00:00.000 0.0%14 0.000 0.000 0.000
data move00:00:00.460 0.0%352982 0.000 0.001 0.007
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:02.944 0.0%349144 0.003 0.008 0.018
history sync00:00:00.226 0.0%804 0.000 0.281 1.667
history write00:00:04.168 0.0%37970 0.020 0.110 6.190
hiswrite/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
idle47:56:10.090 99.8%393156 235.386 438.935 1157.255
nntp read00:00:03.340 0.0%393028 0.003 0.008 0.017
overview write00:00:00.268 0.0%27538 0.000 0.010 0.053
perl filter00:00:56.924 0.0%37960 1.215 1.500 2.653
python filter00:00:01.370 0.0%37964 0.000 0.036 0.105
site send00:00:01.856 0.0%82590 0.000 0.022 0.054
TOTAL: 48:01:58.90047:59:24.092 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.368 0.0%27632 0.000 0.013 0.068
article prepare00:00:00.004 0.0%8832 0.000 0.000 0.028
article read00:00:00.330 0.0%1730 0.000 0.191 74.000
backlog stats00:00:00.284 0.0%417984 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.584 0.0%263778 0.004 0.010 0.023
data write00:00:04.142 0.0%236380 0.008 0.018 0.033
idle47:35:18.202100.0%417984 215.791 409.868 1056.528
status file00:00:02.378 0.0%356 0.750 6.680 160.000
TOTAL: 47:35:48.62847:35:28.292100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle02:24:37.988 97.7%17718 18.274 489.784 37315.118
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:08.554 0.1%834974 0.000 0.010 1.000
readart00:00:17.120 0.2%1834 0.000 9.335 37.500
TOTAL: 02:28:00.39802:25:03.662 98.0%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1148 32252 9914 17972 4366 30%94:38:04
2feeder3.feed-ams.uzoreto.com 574 17752 6288 9034 2430 35%47:28:22
3newsfeed.neva.ru 588 27214 5548 19742 1924 20%276:08:16
4news.snarked.org 46 28564 4422 23778 364 15%49:33:06
5newsfeed.fu-berlin.de 2 10868 424 10424 20 3%44:04:18
6ripco-out.news.tnetconsulting.net 2 29164 404 28654 106 1%43:27:50
7news.lightlink.com 2 6918 288 6610 20 4%44:03:24
8news-in.rahul.net 574 12596 78 12500 18 0%47:19:26
9ripco-out.feeder.erje.net 574 29178 32 28032 1114 0%47:18:48
10news.cmpublishers.com 52 7184 10 7170 4 0%47:59:02
11localhost 150 4 4 0 0 100%00:00:02
TOTAL: 11 3712 201694 27412 163916 10366 13%742:00:38
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com24.5 MB0.0 KB8.0 MB32.6 MB 75%2.3 KB
2newsfeed.neva.ru17.0 MB0.0 KB4.4 MB21.4 MB 79%2.9 KB
3feeder3.feed-ams.uzoreto.com16.0 MB0.0 KB4.5 MB20.4 MB 78%2.4 KB
4news.snarked.org12.7 MB0.0 KB1.2 MB14.0 MB 91%3.0 KB
5ripco-out.news.tnetconsulting.net9.5 MB0.0 KB2.2 MB11.8 MB 80%23.7 KB
6newsfeed.fu-berlin.de2.7 MB0.0 KB230.5 KB3.0 MB 92%6.9 KB
7news-in.rahul.net2.1 MB0.0 KB1.4 MB3.5 MB 60%37.7 KB
8news.lightlink.com1.9 MB0.0 KB414.1 KB2.3 MB 82%7.7 KB
9ripco-out.feeder.erje.net54.5 KB0.0 KB9.0 MB9.0 MB 0%8.1 KB
10news.cmpublishers.com29.5 KB0.0 KB8.0 KB37.5 KB 78%2.7 KB
11localhost8.2 KB0.0 KB0.0 KB8.2 KB100%2.1 KB
TOTAL: 1186.6 MB0.0 KB31.4 MB118.0 MB 73%3.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 14 00:01:01 - 00:59:59 250 1.8% 0.07800.1 KB 1.7% 0.23
Dec 14 01:00:00 - 01:59:59 383 2.8% 0.111.3 MB 2.9% 0.36
Dec 14 02:00:00 - 02:59:59 565 4.1% 0.161.6 MB 3.6% 0.45
Dec 14 03:00:00 - 03:59:59 714 5.2% 0.202.5 MB 5.5% 0.70
Dec 14 04:00:00 - 04:59:59 653 4.8% 0.181.7 MB 3.8% 0.49
Dec 14 05:00:00 - 05:59:59 667 4.9% 0.191.9 MB 4.2% 0.53
Dec 14 06:00:00 - 06:59:59 586 4.3% 0.161.4 MB 3.2% 0.41
Dec 14 07:00:00 - 07:59:59 724 5.3% 0.202.3 MB 5.1% 0.65
Dec 14 08:00:00 - 08:59:59 886 6.4% 0.252.5 MB 5.5% 0.70
Dec 14 09:00:00 - 09:59:59 818 6.0% 0.233.5 MB 7.8% 0.99
Dec 14 10:00:00 - 10:59:59 833 6.1% 0.232.7 MB 6.0% 0.76
Dec 14 11:00:00 - 11:59:59 728 5.3% 0.202.2 MB 4.8% 0.62
Dec 14 12:00:00 - 12:59:59 740 5.4% 0.212.2 MB 5.0% 0.63
Dec 14 13:00:00 - 13:59:59 734 5.3% 0.202.3 MB 5.1% 0.65
Dec 14 14:00:00 - 14:59:59 704 5.1% 0.202.2 MB 4.8% 0.61
Dec 14 15:00:00 - 15:59:59 603 4.4% 0.172.0 MB 4.4% 0.56
Dec 14 16:00:00 - 16:59:59 644 4.7% 0.182.5 MB 5.5% 0.70
Dec 14 17:00:00 - 17:59:59 548 4.0% 0.152.0 MB 4.5% 0.57
Dec 14 18:00:00 - 18:59:59 424 3.1% 0.121.7 MB 3.8% 0.49
Dec 14 19:00:00 - 19:59:59 365 2.7% 0.101.0 MB 2.3% 0.29
Dec 14 20:00:00 - 20:59:59 295 2.1% 0.081.2 MB 2.6% 0.34
Dec 14 21:00:00 - 21:59:59 358 2.6% 0.10990.9 KB 2.2% 0.28
Dec 14 22:00:00 - 22:59:59 251 1.8% 0.07757.6 KB 1.7% 0.21
Dec 14 23:00:00 - 23:57:14 271 2.0% 0.081.8 MB 4.0% 0.53
TOTAL: 23:56:13 13744 100.0% 0.1644.7 MB 100.0% 0.53
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 2194 1706 0 0 3 0 0 0 485
2feeder3.feed-ams.uzoreto.com 1267 906 0 0 3 0 0 0 358
3newsfeed.neva.ru 1020 504 0 0 5 0 0 0 511
4ripco-out.feeder.erje.net 556 552 0 0 0 0 0 0 4
5news.snarked.org 186 78 0 0 0 0 0 0 108
6newsfeed.fu-berlin.de 69 0 0 0 0 0 0 0 69
7ripco-out.news.tnetconsulting.net 51 36 0 0 0 0 0 0 15
8news.lightlink.com 25 1 0 0 2 0 0 0 22
9news-in.rahul.net 9 0 0 0 0 0 0 0 9
10news.cmpublishers.com 2 1 0 0 0 0 0 0 1
TOTAL: 10 5379 3784 0 0 13 0 0 0 1582

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2867
alt.binaries.ftd 359
alt.bestjobsusa.computer.jobs 39
fido7.su.pol.news 36
scinet.stats 29
free.it.scienza.fisica 27
it.comp.os.win.windows10 26
de.soc.arbeitslos 17
scot.general 16
alt.jobs.jobsearch 15
alt.binaries.pictures.purebeauty 15
alt.binaries.e-book 14
aus.jobs 13
att.jobs 12
alt.binaries.pictures.scenic 12
alt.my.job.sucks 12
it.tlc.cellulari.android 12
at.jobs 12
alt.binaries.pictures.earlmiller 11
free.willey 10
TOTAL: 120 3784

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

GroupsCount
pl.praca.oferowana 8
alt.penthouse.sex.stories 2
christnet.bible 2
bit.listserv.catholic 1
TOTAL: 4 13

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1188
Too many newsgroups 1030
EMP (phl) 362
Binary in non-binary group 132
Scoring filter (8) 20
Scoring filter (10) 16
Scoring filter (11) 12
HTML post 12
Poison newsgroup 4
Scoring filter (13) 2
Scoring filter (17) 2
Scoring filter (15) 2
Scoring filter (9) 2
Too many test groups in crosspost 2
Scoring filter (14) 2
TOTAL: 15 2788

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 18
TOTAL: 1 18
Huge articles
newsfeed.fu-berlin.de 4
TOTAL: 1 4
Including strange strings
newsfeed.neva.ru 60
newsfeed.fu-berlin.de 55
feeder3.feed-ams.uzoreto.com 52
ripco-out.feed.uzoreto.com 11
news.snarked.org 5
TOTAL: 5 183
No colon-space in header
ripco-out.feeder.erje.net 3
ripco-out.feed.uzoreto.com 1
feeder3.feed-ams.uzoreto.com 1
TOTAL: 3 5
TOTAL: 4 210

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 8414 906 5158 14 0 0 10%47:50:18
2rahul.net 19760 554 10598 6 0 2 2%47:37:22
3feeder.erje.net 29984 248 25964 132 0 0 0%47:40:00
4news.tnetconsulting.net 41642 60 26946 0 0 12 0%47:45:22
5news.snarked.org 38558 40 22562 6 0 58 0%47:40:00
6newsfeed.neva.ru 22276 36 21766 40 0 0 0%47:40:00
7fu-berlin.de 24576 14 24562 0 0 0 0%47:40:00
8news.uzoreto.com 11120 6 10810 14 0 0 0%47:32:36
9news.cmpublishers.com 40224 4 26952 124 0 172 0%47:40:40
10remote6hme0.ripco.com 6 0 0 6 0 0 0%01:12:08
TOTAL: 10 236560 1868 175318 342 0 244 0%430:18:26
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.lightlink.com4.0 MB57.0 KB4.1 MB0.0 KB/s4.6 KB47:50:18
2rahul.net3.4 MB13.9 KB3.4 MB0.0 KB/s6.2 KB47:37:22
3feeder.erje.net611.0 KB357.0 KB968.0 KB0.0 KB/s2.5 KB47:40:00
4news.tnetconsulting.net263.7 KB0.0 KB263.7 KB0.0 KB/s4.4 KB47:45:22
5news.snarked.org252.3 KB7.7 KB260.0 KB0.0 KB/s5.7 KB47:40:00
6newsfeed.neva.ru185.4 KB92.3 KB277.7 KB0.0 KB/s3.7 KB47:40:00
7fu-berlin.de52.9 KB0.0 KB52.9 KB0.0 KB/s3.8 KB47:40:00
8news.uzoreto.com20.4 KB47.2 KB67.5 KB0.0 KB/s3.4 KB47:32:36
9news.cmpublishers.com13.9 KB346.3 KB360.2 KB0.0 KB/s2.8 KB47:40:40
10remote6hme0.ripco.com0.0 KB15.1 KB15.1 KB0.0 KB/s2.5 KB01:12:08
TOTAL: 108.8 MB936.5 KB9.7 MB0.0 KB/s4.5 KB430:18:26
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 7602.3 MB 360 2 000:08:39
2shell3.ripco.com 16 3121.1 MB 40 2 002:19:04
TOTAL: 2 302 10723.4 MB 400 4 002:27:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 7602.3 MB 360 2 000:08:39
2*.ripco.com 16 3121.1 MB 40 2 002:19:04
TOTAL: 2 302 10723.4 MB 400 4 002:27:44

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3.ripco.com 4.716 2.812 0.00002:19:04
ftupet.com 44.150 21.804 0.00000:08:39
212.90.61.180 0.814 0.208 0.00000:00:06
134.122.26.214 0.200 0.056 0.00000:00:00
? 0.466 0.120 0.00000:00:00
TOTAL: 5 50.346 25.000 0.00002:27:50

NNRP no permission clients:

SystemConn
212.90.61.180 8
134.122.26.214 2
TOTAL: 2 10

NNRP unrecognized commands (by host):

SystemConn
shell3.ripco.com 2
TOTAL: 1 2

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 944 88.1%
2misc 46 4.3%
3news 30 2.8%
4alt 24 2.2%
5comp 10 0.9%
6seattle 8 0.7%
7sci 6 0.6%
8chi 4 0.4%
TOTAL: 8 1072100.0%

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

NewsgroupCount
1rec.arts.tv 432
2rec.food.cooking 386
3rec.bicycles.tech 110
4misc.phone.mobile.iphone 38
5news.admin.peering 18
6alt.stupidity 14
7news.admin.hierarchies 8
8misc.consumers.frugal-living 8
9seattle.general 8
10comp.mobile.android 8
11alt.comp.os.windows-10 6
12rec.radio.broadcasting 6
13sci.electronics.repair 6
14rec.radio.shortwave 6
15news.admin.net-abuse.usenet 4
16rec.radio.swap 4
17chi.weather 4
18comp.sys.mac.system 2
19alt.dss.hack 2
20alt.internet.wireless 2
TOTAL: 20 1072