Daily Usenet report for news.ripco.com

Jun 23 00:01:01 -- Jun 23 23:56:45

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Jun 23 07:37:46 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping
Jun 23 07:46:55 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping
Jun 23 08:06:43 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping
Jun 23 08:27:40 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping
Jun 23 09:13:55 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping
Jun 23 14:35:21 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping
Jun 23 16:42:06 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping
Jun 23 17:06:49 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping
Jun 23 17:40:02 s_local@remote5bge0 controlchan[24784]: [ID 702911 news.notice] Unknown "block" control by robomod@news.nic.it
Jun 23 17:40:02 remote5bge0 controlchan[24784]: [ID 702911 news.notice] Unknown "block" control by robomod@news.nic.it
Jun 23 23:43:17 remote5bge0 overchan[24803]: [ID 702911 news.error] input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 19530 33.0%3.9 MB 41.3%
innd 18769 31.7%2.9 MB 31.1%
nnrpd 17335 29.3%1.8 MB 19.2%
innfeed 3592 6.1%798.6 KB 8.3%
overchan 9 0.0%1.3 KB 0.0%
controlchan 4 0.0%0.4 KB 0.0%
TOTAL: 6 59239 100.0%9.4 MB100.0%

History cache:

ReasonCount%Count
Cache misses 186880 51.7%
Do not exist 174924 48.3%
Negative hits 0 0.0%
Positive hits 0 0.0%
TOTAL: 4 361804 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.004 0.0%352 0.000 0.011 0.143
article cleanup00:00:00.476 0.0%38478 0.000 0.012 0.049
article logging00:00:00.796 0.0%38968 0.000 0.020 0.256
article parse00:00:01.448 0.0%95736 0.000 0.015 0.041
article write00:01:48.906 0.1%30978 1.573 3.516 149.211
artlog/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
data move00:00:00.334 0.0%371892 0.000 0.001 0.004
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:02.842 0.0%363298 0.000 0.008 0.020
history sync00:00:00.390 0.0%756 0.000 0.516 3.500
history write00:00:03.190 0.0%38470 0.022 0.083 0.559
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle47:36:12.782 99.8%407446 241.830 420.602 903.045
nntp read00:00:03.026 0.0%408528 0.002 0.007 0.016
overview write00:00:00.260 0.0%30978 0.000 0.008 0.038
perl filter00:01:01.320 0.0%38466 1.299 1.594 2.254
python filter00:00:01.364 0.0%38472 0.000 0.035 0.164
site send00:00:02.298 0.0%92924 0.000 0.025 0.061
TOTAL: 47:41:57.28847:39:19.436 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.124 0.0%31268 0.000 0.004 0.025
article prepare00:00:00.006 0.0%9928 0.000 0.001 0.025
article read00:00:00.428 0.0%1908 0.000 0.224 36.000
backlog stats00:00:00.344 0.0%445506 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:03.494 0.0%292258 0.002 0.012 0.023
data write00:00:04.760 0.0%261586 0.006 0.018 0.031
idle47:50:50.952100.0%445506 232.681 386.641 968.732
status file00:00:02.322 0.0%352 1.000 6.597 270.000
TOTAL: 47:51:21.43647:51:02.430100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:21:39.954 98.9%18240 1.250 663.375 33678.789
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:08.850 0.1%839698 0.000 0.011 0.111
readart00:00:20.810 0.2%2564 0.083 8.116 36.000
TOTAL: 03:23:52.87803:22:09.614 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 38496 14302 20470 3724 37%94:38:58
2news.snarked.org 48 27904 7746 19606 552 27%56:44:48
3newsfeed.neva.ru 584 37486 5378 30328 1780 14%275:44:48
4feeder3.feed-ams.uzoreto.com 574 10166 1608 7920 638 15%47:18:40
5ripco-out.news.tnetconsulting.net 2 32770 984 31712 74 3%47:14:58
6newsfeed.fu-berlin.de 4 14414 586 13804 24 4%45:56:24
7news.lightlink.com 2 8662 128 8460 74 1%43:52:52
8news-in.rahul.net 574 15614 72 15534 8 0%47:19:40
9ripco-out.feeder.erje.net 574 31782 18 31114 650 0%47:17:34
10news.cmpublishers.com 52 7246 10 7236 0 0%47:59:54
11localhost 152 6 6 0 0 100%00:00:00
TOTAL: 11 3714 224546 30838 186184 7524 13%754:08:36
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1ripco-out.feed.uzoreto.com35.6 MB0.0 KB8.2 MB43.8 MB 81%2.5 KB
2news.snarked.org22.2 MB0.0 KB1.5 MB23.7 MB 93%2.9 KB
3ripco-out.news.tnetconsulting.net20.5 MB0.0 KB3.4 MB23.9 MB 85%23.2 KB
4newsfeed.neva.ru15.6 MB0.0 KB6.5 MB22.1 MB 70%3.2 KB
5feeder3.feed-ams.uzoreto.com4.4 MB0.0 KB1.9 MB6.3 MB 69%2.9 KB
6newsfeed.fu-berlin.de3.4 MB0.0 KB174.5 KB3.6 MB 95%6.0 KB
7news.lightlink.com2.2 MB1.2 MB5.2 MB8.6 MB 26%43.4 KB
8news-in.rahul.net1.0 MB0.0 KB21.6 KB1.0 MB 97%13.3 KB
9news.cmpublishers.com36.5 KB0.0 KB0.0 KB36.5 KB100%3.7 KB
10ripco-out.feeder.erje.net34.0 KB0.0 KB4.4 MB4.4 MB 0%6.8 KB
11localhost23.2 KB0.0 KB0.0 KB23.2 KB100%3.9 KB
TOTAL: 11105.0 MB1.2 MB31.3 MB137.5 MB 76%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 23 00:01:01 - 00:59:59 370 2.4% 0.101.6 MB 3.1% 0.47
Jun 23 01:00:00 - 01:59:59 407 2.6% 0.111.2 MB 2.2% 0.33
Jun 23 02:00:00 - 02:59:59 565 3.6% 0.161.6 MB 3.0% 0.45
Jun 23 03:00:00 - 03:59:59 594 3.8% 0.171.8 MB 3.5% 0.53
Jun 23 04:00:00 - 04:59:59 727 4.7% 0.202.6 MB 5.0% 0.75
Jun 23 05:00:00 - 05:59:59 694 4.5% 0.192.3 MB 4.3% 0.64
Jun 23 06:00:00 - 06:59:59 782 5.0% 0.222.3 MB 4.4% 0.66
Jun 23 07:00:00 - 07:59:59 751 4.8% 0.212.0 MB 3.8% 0.56
Jun 23 08:00:00 - 08:59:59 890 5.7% 0.252.9 MB 5.5% 0.83
Jun 23 09:00:00 - 09:59:59 850 5.5% 0.242.1 MB 4.0% 0.60
Jun 23 10:00:00 - 10:59:59 855 5.5% 0.243.3 MB 6.3% 0.95
Jun 23 11:00:00 - 11:59:59 864 5.6% 0.243.2 MB 6.1% 0.92
Jun 23 12:00:00 - 12:59:59 855 5.5% 0.243.2 MB 6.1% 0.92
Jun 23 13:00:00 - 13:59:59 891 5.7% 0.254.1 MB 7.7% 1.15
Jun 23 14:00:00 - 14:59:59 879 5.7% 0.243.0 MB 5.6% 0.84
Jun 23 15:00:00 - 15:59:59 857 5.5% 0.242.8 MB 5.4% 0.81
Jun 23 16:00:00 - 16:59:59 699 4.5% 0.192.6 MB 4.8% 0.73
Jun 23 17:00:00 - 17:59:59 560 3.6% 0.161.7 MB 3.2% 0.48
Jun 23 18:00:00 - 18:59:59 451 2.9% 0.131.5 MB 2.8% 0.42
Jun 23 19:00:00 - 19:59:59 366 2.4% 0.101.9 MB 3.6% 0.54
Jun 23 20:00:00 - 20:59:59 401 2.6% 0.111.2 MB 2.4% 0.36
Jun 23 21:00:00 - 21:59:59 383 2.5% 0.111.1 MB 2.2% 0.32
Jun 23 22:00:00 - 22:59:59 408 2.6% 0.111.1 MB 2.1% 0.32
Jun 23 23:00:00 - 23:56:45 416 2.7% 0.121.6 MB 3.0% 0.47
TOTAL: 23:55:44 15515 100.0% 0.1852.7 MB 100.0% 0.63
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1ripco-out.feed.uzoreto.com 1869 1211 0 0 1 0 0 0 657
2newsfeed.neva.ru 945 341 0 0 2 0 0 0 602
3ripco-out.feeder.erje.net 325 323 0 0 0 0 0 0 2
4feeder3.feed-ams.uzoreto.com 320 146 0 0 0 0 0 0 174
5news.snarked.org 279 145 0 0 16 0 0 0 118
6newsfeed.fu-berlin.de 198 0 0 0 0 0 0 0 198
7ripco-out.news.tnetconsulting.net 38 21 0 0 0 0 0 0 17
8news.lightlink.com 37 3 0 6 0 0 0 0 28
9news-in.rahul.net 4 0 0 0 0 0 0 0 4
TOTAL: 9 4015 2190 0 6 19 0 0 0 1800

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1494
alt.binaries.ftd 122
fido7.su.pol.news 51
scot.general 44
alt.binaries.3d.poser 43
free.it.scienza.fisica 38
alt.binaries.e-book 31
scinet.stats 30
perl.cpan.uploads 28
aioe.news.assistenza 18
alt.binaries.pictures.scenic 17
it.tlc.cellulari.android 17
wales.test 12
alt.binaries.humor.skewed 10
alt.binaries.unoffical.global.chat 10
alt.paranormal.spells 8
mozilla.test 8
free.willey 7
alt.binaries.pictures.nude 7
trollnet.jogos 7
TOTAL: 113 2190

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

GroupsCount
fr.comp.securite 16
pl.praca.oferowana 1
alt.christnet.religion 1
bit.listserv.catholic 1
TOTAL: 4 19

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1424
Too many newsgroups 1076
Binary in non-binary group 320
EMP (phl) 130
Scoring filter (8) 82
Scoring filter (9) 44
Scoring filter (10) 16
Scoring filter (11) 6
Scoring filter (16) 2
HTML post 2
Poison newsgroup 2
TOTAL: 11 3104

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
ripco-out.feeder.erje.net 26
ripco-out.feed.uzoreto.com 4
feeder3.feed-ams.uzoreto.com 4
TOTAL: 3 34
Including strange strings
newsfeed.fu-berlin.de 185
newsfeed.neva.ru 53
ripco-out.feed.uzoreto.com 6
ripco-out.news.tnetconsulting.net 1
TOTAL: 4 245
No colon-space in header
ripco-out.feed.uzoreto.com 2
TOTAL: 1 2
TOTAL: 3 281

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.lightlink.com 12112 956 7184 14 0 4 7%47:40:10
2rahul.net 24456 878 13502 54 0 14 3%47:40:10
3feeder.erje.net 31116 56 29778 96 0 26 0%47:40:48
4news.tnetconsulting.net 40874 56 29868 4 0 42 0%47:44:38
5news.snarked.org 38404 32 22974 12 0 68 0%47:40:10
6fu-berlin.de 27888 20 27868 0 0 0 0%47:40:10
7news.cmpublishers.com 44288 18 30466 172 0 90 0%47:41:20
8newsfeed.neva.ru 25940 12 25512 18 0 0 0%47:39:32
9news.uzoreto.com 15034 6 14716 28 0 0 0%47:40:10
10remote6hme0.ripco.com 6 0 0 6 0 0 0%01:12:04
TOTAL: 10 260118 2034 201868 404 0 244 0%430:19:12
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1rahul.net11.4 MB116.2 KB11.5 MB0.1 KB/s12.6 KB47:40:10
2news.lightlink.com5.7 MB53.2 KB5.7 MB0.0 KB/s6.0 KB47:40:10
3news.tnetconsulting.net207.5 KB6.0 KB213.5 KB0.0 KB/s3.6 KB47:44:38
4news.snarked.org191.2 KB34.8 KB225.9 KB0.0 KB/s5.1 KB47:40:10
5feeder.erje.net181.3 KB390.5 KB571.8 KB0.0 KB/s3.8 KB47:40:48
6newsfeed.neva.ru160.1 KB57.5 KB217.6 KB0.0 KB/s7.3 KB47:39:32
7fu-berlin.de65.2 KB0.0 KB65.2 KB0.0 KB/s3.3 KB47:40:10
8news.cmpublishers.com55.5 KB496.2 KB551.8 KB0.0 KB/s2.9 KB47:41:20
9news.uzoreto.com25.8 KB115.7 KB141.5 KB0.0 KB/s4.2 KB47:40:10
10remote6hme0.ripco.com0.0 KB22.8 KB22.8 KB0.0 KB/s3.8 KB01:12:04
TOTAL: 1017.9 MB1.3 MB19.2 MB0.0 KB/s8.1 KB430:19:12
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ftupet.com 286 10722.9 MB 420 2 000:07:16
2shell3 18 4181.1 MB 55 4 003:16:26
TOTAL: 2 304 14904.0 MB 475 6 003:23:43

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.com 286 10722.9 MB 420 2 000:07:16
2? 18 4181.1 MB 55 4 003:16:26
TOTAL: 2 304 14904.0 MB 475 6 003:23:43

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
shell3 5.076 3.590 0.00003:16:26
ftupet.com 47.186 21.500 0.00000:07:16
worker-18.sfj.censys-scanner.com 0.200 0.050 0.00000:00:00
TOTAL: 3 52.462 25.140 0.00003:23:43

NNRP no permission clients:

SystemConn
worker-18.sfj.censys-scanner.com 2
TOTAL: 1 2

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 1316 88.1%
2comp 86 5.8%
3alt 31 2.1%
4misc 26 1.7%
5sci 16 1.1%
6news 10 0.7%
7chi 8 0.5%
TOTAL: 7 1493100.0%

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

NewsgroupCount
1rec.arts.tv 856
2rec.food.cooking 408
3rec.bicycles.tech 34
4comp.sys.mac.system 32
5alt.stupidity 27
6misc.phone.mobile.iphone 26
7comp.mobile.android 24
8sci.electronics.repair 16
9comp.mail.sendmail 16
10rec.radio.broadcasting 8
11news.groups 6
12comp.sys.mac.vintage 4
13chi.weather 4
14comp.protocols.dns.bind 4
15rec.gardens.edible 4
16news.groups.questions 4
17rec.bicycles.misc 2
18comp.unix.bsd.freebsd.misc 2
19alt.tv.sopranos 2
20chi.forsale 2
21rec.radio.swap 2
22comp.security.ssh 2
23alt.comp.google 2
24comp.sys.ibm.pc.games.rpg 2
25rec.boats.paddle 2
26chi.general 2
TOTAL: 26 1493