Daily Usenet report for news.ripco.com

Jun 18 00:01:02 -- Jun 18 23:56:29

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jun 18 04:10:10 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping
Jun 18 08:06:40 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping
Jun 18 08:52:58 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping
Jun 18 10:00:52 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping
Jun 18 10:31:09 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping
Jun 18 14:38:12 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping
Jun 18 14:49:53 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping
Jun 18 15:52:57 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping
Jun 18 23:38:52 remote5bge0 overchan[17521]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 19755 33.8%3.9 MB 42.4%
innd 17434 29.9%2.7 MB 29.2%
nnrpd 17429 29.9%1.8 MB 19.5%
innfeed 3736 6.4%835.9 KB 8.8%
overchan 9 0.0%1.5 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 58365 100.0%9.3 MB100.0%

History cache:

ReasonCount%Count
Cache misses 183634 53.0%
Do not exist 163000 47.0%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 346634 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%252 0.000 0.000 0.000
article cleanup00:00:00.438 0.0%39232 0.000 0.011 0.056
article logging00:00:00.866 0.0%39556 0.000 0.022 1.281
article parse00:00:01.374 0.0%98030 0.000 0.014 0.044
article write00:01:37.650 0.1%31824 1.803 3.068 26.938
artlog/artparse00:00:00.000 0.0%8 0.000 0.000 0.000
data move00:00:00.404 0.0%360514 0.000 0.001 0.006
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:03.296 0.0%348884 0.003 0.009 0.022
history sync00:00:00.234 0.0%770 0.000 0.304 3.000
history write00:00:03.916 0.0%39234 0.000 0.100 3.471
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:56:20.990 99.8%398196 234.603 433.407 1203.396
nntp read00:00:02.964 0.0%397818 0.002 0.007 0.019
overview write00:00:00.268 0.0%31824 0.000 0.008 0.052
perl filter00:01:02.314 0.0%39226 1.294 1.589 3.082
python filter00:00:01.370 0.0%39234 0.000 0.035 0.121
site send00:00:03.170 0.0%95406 0.014 0.033 0.088
TOTAL: 48:01:47.83647:59:19.254 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.128 0.0%32012 0.000 0.004 0.032
article prepare00:00:00.008 0.0%17552 0.000 0.000 0.062
article read00:00:00.468 0.0%3626 0.000 0.129 14.400
backlog stats00:00:00.290 0.0%490176 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.562 0.0%309114 0.001 0.005 0.021
data write00:00:04.620 0.0%277448 0.009 0.017 0.028
idle47:33:46.986100.0%490176 210.009 349.317 879.366
status file00:00:02.716 0.0%398 0.750 6.824 30.000
TOTAL: 47:34:17.96247:33:56.778100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:47:36.208 98.9%18514 0.000 737.615 75056.062
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:11.020 0.1%1171382 0.000 0.009 1.000
readart00:00:22.570 0.2%2668 0.000 8.460 36.000
TOTAL: 03:50:06.33203:48:09.798 99.2%----

Control commands to INND:

CommandNumber
L 2
flush 2
go 4
logmode 2
mode 290
name 5
pause 2
paused 2
reserve 4
TOTAL: 9 313

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1ripco-out.feed.uzoreto.com 1148 37788 14930 19440 3418 39%94:38:26
2news.snarked.org 58 30298 7028 22694 576 23%49:24:44
3newsfeed.neva.ru 504 32880 5382 26354 1144 16%242:41:32
4feeder3.feed-ams.uzoreto.com 574 11718 2614 7946 1158 22%47:28:46
5ripco-out.news.tnetconsulting.net 4 30844 954 29812 78 3%44:53:30
6newsfeed.fu-berlin.de 4 19030 606 18402 22 3%65:03:52
7news.lightlink.com 2 6922 112 6452 358 1%47:38:32
8ripco-out.feeder.erje.net 574 32578 74 31804 700 0%47:17:10
9news-in.rahul.net 576 14260 56 14200 4 0%47:19:30
10news.cmpublishers.com 52 7990 28 7930 32 0%47:59:34
11localhost 154 8 8 0 0 100%00:00:02
TOTAL: 11 3650 224316 31792 185034 7490 14%734:25:38
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com37.5 MB0.0 KB7.0 MB44.6 MB 84%2.5 KB
2news.snarked.org20.3 MB0.0 KB1.7 MB22.0 MB 92%3.0 KB
3ripco-out.news.tnetconsulting.net16.7 MB0.0 KB884.5 KB17.6 MB 95%17.4 KB
4newsfeed.neva.ru16.1 MB0.0 KB2.7 MB18.8 MB 85%3.0 KB
5feeder3.feed-ams.uzoreto.com6.6 MB0.0 KB2.3 MB8.9 MB 74%2.4 KB
6news.lightlink.com3.8 MB536.0 KB14.6 MB18.9 MB 20%41.2 KB
7newsfeed.fu-berlin.de3.2 MB0.0 KB354.7 KB3.5 MB 90%5.7 KB
8news-in.rahul.net1.6 MB0.0 KB146.3 KB1.8 MB 91%29.9 KB
9ripco-out.feeder.erje.net257.7 KB0.0 KB4.5 MB4.8 MB 5%6.3 KB
10news.cmpublishers.com83.0 KB0.0 KB66.0 KB149.0 KB 55%2.5 KB
11localhost31.6 KB0.0 KB0.0 KB31.6 KB100%3.9 KB
TOTAL: 11106.2 MB536.0 KB34.3 MB141.0 MB 75%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 18 00:01:02 - 00:59:59 404 2.5% 0.11972.8 KB 1.8% 0.27
Jun 18 01:00:00 - 01:59:59 459 2.9% 0.131.4 MB 2.7% 0.41
Jun 18 02:00:00 - 02:59:59 712 4.5% 0.202.2 MB 4.1% 0.61
Jun 18 03:00:00 - 03:59:59 684 4.3% 0.191.9 MB 3.6% 0.55
Jun 18 04:00:00 - 04:59:59 666 4.2% 0.182.9 MB 5.5% 0.83
Jun 18 05:00:00 - 05:59:59 764 4.8% 0.212.7 MB 5.1% 0.77
Jun 18 06:00:00 - 06:59:59 793 5.0% 0.222.2 MB 4.2% 0.63
Jun 18 07:00:00 - 07:59:59 854 5.4% 0.242.3 MB 4.3% 0.65
Jun 18 08:00:00 - 08:59:59 932 5.9% 0.264.6 MB 8.7% 1.32
Jun 18 09:00:00 - 09:59:59 831 5.2% 0.232.8 MB 5.2% 0.78
Jun 18 10:00:00 - 10:59:59 901 5.7% 0.253.1 MB 5.8% 0.87
Jun 18 11:00:00 - 11:59:59 917 5.8% 0.253.0 MB 5.6% 0.85
Jun 18 12:00:00 - 12:59:59 964 6.1% 0.273.1 MB 5.8% 0.88
Jun 18 13:00:00 - 13:59:59 855 5.4% 0.243.1 MB 5.8% 0.88
Jun 18 14:00:00 - 14:59:59 823 5.2% 0.232.4 MB 4.5% 0.67
Jun 18 15:00:00 - 15:59:59 752 4.7% 0.212.3 MB 4.3% 0.66
Jun 18 16:00:00 - 16:59:59 702 4.4% 0.202.0 MB 3.7% 0.57
Jun 18 17:00:00 - 17:59:59 601 3.8% 0.172.0 MB 3.8% 0.57
Jun 18 18:00:00 - 18:59:59 424 2.7% 0.122.2 MB 4.1% 0.61
Jun 18 19:00:00 - 19:59:59 410 2.6% 0.111.4 MB 2.7% 0.41
Jun 18 20:00:00 - 20:59:59 403 2.5% 0.111.3 MB 2.5% 0.37
Jun 18 21:00:00 - 21:59:59 348 2.2% 0.101.0 MB 1.9% 0.29
Jun 18 22:00:00 - 22:59:59 306 1.9% 0.09834.1 KB 1.5% 0.23
Jun 18 23:00:00 - 23:56:29 385 2.4% 0.111.6 MB 2.9% 0.47
TOTAL: 23:55:27 15890 100.0% 0.1853.2 MB 100.0% 0.63
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 1712 1324 0 0 4 0 0 0 384
2newsfeed.neva.ru 624 381 0 0 0 0 0 0 243
3feeder3.feed-ams.uzoreto.com 579 445 0 0 2 0 0 0 132
4ripco-out.feeder.erje.net 350 343 0 0 0 0 0 0 7
5news.snarked.org 288 206 0 0 3 0 0 0 79
6news.lightlink.com 134 0 0 3 0 0 0 0 131
7newsfeed.fu-berlin.de 112 0 0 0 0 0 0 0 112
8ripco-out.news.tnetconsulting.net 49 10 0 0 0 0 0 0 39
9news.cmpublishers.com 15 2 0 0 0 0 0 0 13
10news-in.rahul.net 2 0 0 0 0 0 0 0 2
TOTAL: 10 3865 2711 0 3 9 0 0 0 1142

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 2016
alt.binaries.ftd 148
fido7.su.pol.news 47
scinet.stats 31
perl.cpan.uploads 25
alt.binaries.pictures.scenic 21
alt.binaries.e-book 18
alt.binaries.pictures.purebeauty 16
it.eventi.covid19 16
free.it.scienza.fisica 15
it.comp.os.win.windows10 14
aioe.news.assistenza 12
uk.net.news.moderation 11
alt.fan.states.california 11
alt.binaries.test.yenc 10
linux.debian.ports.hurd 10
alt.binaries.adan 10
alt.binaries.3d.poser 9
alt.binaries.mma 9
grisbi.bugsreports 9
TOTAL: 124 2711

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

GroupsCount
christnet.bible 3
fr.sci.geosciences 3
fr.bienvenue 2
bit.listserv.catholic 1
TOTAL: 4 9

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 854
Too many newsgroups 782
Binary in non-binary group 142
EMP (phl) 78
Scoring filter (9) 34
Scoring filter (8) 24
Scoring filter (10) 14
Scoring filter (18) 8
Scoring filter (11) 6
Scoring filter (12) 4
Scoring filter (14) 4
Scoring filter (13) 2
HTML post 2
TOTAL: 13 1954

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 20
feeder3.feed-ams.uzoreto.com 2
TOTAL: 2 22
Including strange strings
newsfeed.fu-berlin.de 105
newsfeed.neva.ru 53
ripco-out.feed.uzoreto.com 3
TOTAL: 3 161
No colon-space in header
ripco-out.feed.uzoreto.com 2
feeder3.feed-ams.uzoreto.com 2
TOTAL: 2 4
TOTAL: 3 187

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 9848 1282 5730 16 0 0 13%47:40:04
2rahul.net 24498 974 12918 22 0 2 3%47:37:58
3feeder.erje.net 39836 592 29422 328 0 0 1%47:42:24
4fu-berlin.de 28908 64 28842 0 0 0 0%47:49:06
5news.cmpublishers.com 45512 44 30292 866 0 86 0%47:40:04
6news.snarked.org 40822 40 24230 14 0 150 0%47:40:08
7news.tnetconsulting.net 45398 30 30546 6 0 114 0%47:46:56
8news.uzoreto.com 14236 28 13648 48 0 0 0%47:40:38
9newsfeed.neva.ru 26978 14 26202 14 0 106 0%47:42:42
10remote6hme0.ripco.com 20 14 0 6 0 0 70%03:15:44
TOTAL: 10 276056 3082 201830 1320 0 458 1%432:35:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net5.0 MB45.5 KB5.1 MB0.0 KB/s5.2 KB47:37:58
2news.lightlink.com4.1 MB258.3 KB4.3 MB0.0 KB/s3.4 KB47:40:04
3feeder.erje.net1.5 MB899.5 KB2.4 MB0.0 KB/s2.7 KB47:42:24
4fu-berlin.de754.8 KB0.0 KB754.8 KB0.0 KB/s11.8 KB47:49:06
5news.uzoreto.com190.4 KB214.8 KB405.1 KB0.0 KB/s5.3 KB47:40:38
6news.snarked.org158.1 KB55.8 KB213.9 KB0.0 KB/s4.0 KB47:40:08
7news.tnetconsulting.net144.6 KB36.5 KB181.1 KB0.0 KB/s5.0 KB47:46:56
8news.cmpublishers.com122.4 KB2.7 MB2.8 MB0.0 KB/s3.2 KB47:40:04
9newsfeed.neva.ru87.2 KB27.6 KB114.7 KB0.0 KB/s4.1 KB47:42:42
10remote6hme0.ripco.com31.5 KB26.7 KB58.2 KB0.0 KB/s2.9 KB03:15:44
TOTAL: 1012.1 MB4.2 MB16.3 MB0.0 KB/s3.8 KB432:35:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 10683.3 MB 474 4 000:07:26
2shell3 32 5281.4 MB 50 4 003:42:24
TOTAL: 2 318 15964.7 MB 524 8 003:49:56

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 10683.3 MB 474 4 000:07:26
2? 32 5281.4 MB 50 4 003:42:24
TOTAL: 2 318 15964.7 MB 524 8 003:49:50

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 7.692 4.064 0.00003:42:24
ftupet.com 48.482 21.480 0.00000:07:26
scan-141.security.ipip.net 0.200 0.048 0.00000:00:02
117.50.60.193 1.010 0.248 0.00000:00:01
162.243.139.233 0.200 0.052 0.00000:00:00
TOTAL: 5 57.584 25.892 0.00003:49:56

NNRP no permission clients:

SystemConn
117.50.60.193 10
162.243.139.233 2
scan-141.security.ipip.net 2
TOTAL: 3 14

NNRP unrecognized commands (by host):

SystemConn
shell3 2
TOTAL: 1 2

NNRP unrecognized commands (by command):

CommandCount
XTHREAD DBINIT 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

SystemConnPeer
shell3 6 0
TOTAL: 1 6 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 1276 79.9%
2alt 158 9.9%
3sci 70 4.4%
4comp 64 4.0%
5chi 20 1.3%
6ba 2 0.1%
7seattle 2 0.1%
8misc 2 0.1%
9news 2 0.1%
TOTAL: 9 1596100.0%

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

NewsgroupCount
1rec.arts.tv 794
2rec.food.cooking 344
3alt.comp.os.windows-10 122
4rec.bicycles.tech 110
5sci.electronics.repair 70
6comp.protocols.dns.bind 24
7alt.security.alarms 22
8comp.mobile.android 20
9rec.gardens.edible 18
10chi.general 14
11comp.mail.pine 8
12alt.stupidity 8
13rec.radio.broadcasting 6
14chi.weather 6
15comp.sys.mac.apps 4
16comp.sys.ibm.pc.games.rpg 4
17alt.free.newsservers 4
18comp.sys.mac.system 4
19rec.video.cable-tv 2
20alt.autos.ford 2
21rec.radio.swap 2
22misc.phone.mobile.iphone 2
23seattle.general 2
24ba.food 2
25news.groups.questions 2
TOTAL: 25 1596