Daily Usenet report for news.cmpublishers.com

Jul 1 00:00:07 -- Jul 2 00:00:05

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Jul 1 00:04:03 cs6 pgpverify[25752]: pgpverify: /usr/bin/gpg1: no such file
\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00 1 00:24:23 cs6 innd: usenet.blueworldhosting.com:131 checkpoint seconds 129 accepted 0 refused 3 rejected 1 duplicate 0 accepted size 0 duplicate size 0 rejected size 876
Jul 1 01:17:59 cs6 innfeed[3391]: news.snarked.org:0 connect: Network is unreachable
Jul 1 01:17:59 cs6 innfeed[3391]: news.ripco.com:0 connect: Network is unreachable
Jul 1 01:18:00 cs6 innfeed[3391]: weretis.net:0 connect: Network is unreachable
Jul 1 01:18:00 cs6 innfeed[3391]: CMU:0 connect: Network is unreachable
Jul 1 01:18:00 cs6 innfeed[3391]: bwh:0 connect: Network is unreachable
Jul 1 01:18:00 cs6 innfeed[3391]: news.freedyn.de:0 connect: Network is unreachable
Jul 1 01:18:00 cs6 innfeed[3391]: news.samoylyk.net:0 connect: Network is unreachable
Jul 1 01:18:00 cs6 innfeed[3391]: TNet:0 connect: Network is unreachable
Jul 1 05:45:03 cs6 pgpverify[31946]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
innd 10549 48.6%1.1 MB 42.9%
inn 8287 38.2%1.2 MB 45.4%
innfeed 2179 10.0%246.9 KB 9.1%
nnrpd 677 3.1%67.3 KB 2.5%
controlchan 10 0.0%1.3 KB 0.0%
pgpverify 2 0.0%0.2 KB 0.0%
nocem 2 0.0%0.3 KB 0.0%
TOTAL: 7 21706 100.0%2.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 26145 49.9%
Negative hits 18203 34.7%
Do not exist 8043 15.3%
Cache misses 40 0.1%
TOTAL: 4 52431 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.050 0.0%1 50.000 50.000 50.000
article cleanup00:00:00.096 0.0%8042 0.003 0.012 0.017
article logging00:00:00.642 0.0%8049 0.034 0.080 0.227
article parse00:00:00.286 0.0%22552 0.007 0.013 0.026
article write00:00:56.718 0.1%2092 0.837 27.112 115.122
artlog/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.133 0.0%54128 0.001 0.002 0.005
hisgrep/artcncl00:00:00.022 0.0%1 22.000 22.000 22.000
hishave/artcncl00:00:00.005 0.0%1 5.000 5.000 5.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:56.415 0.1%52430 1.218 2.220 4.730
history sync00:00:00.356 0.0%277 0.000 1.285 26.000
history write00:01:31.373 0.1%8042 6.130 11.362 31.874
idle21:54:42.328 99.6%61781 876.743 1276.806 2216.652
nntp read00:00:00.651 0.0%60832 0.006 0.011 0.018
overview write00:00:22.198 0.0%2092 0.477 10.611 69.813
perl filter00:00:26.920 0.0%8042 2.058 3.347 5.334
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.383 0.0%8432 0.010 0.045 0.096
TOTAL: 22:00:10.00221:59:58.576100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.018 0.0%2274 0.000 0.008 0.024
article prepare00:00:00.000 0.0%128 0.000 0.000 0.000
article read00:00:00.078 0.0%29 0.000 2.690 21.500
backlog stats00:00:00.115 0.0%24836 0.000 0.005 0.019
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.161 0.0%13647 0.003 0.012 0.025
data write00:00:00.163 0.0%11639 0.004 0.014 0.030
idle22:01:46.252100.0%24836 2059.927 3193.197 6440.383
status file00:00:00.723 0.0%387 0.933 1.868 4.882
TOTAL: 22:01:58.76522:01:47.510100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:08.926 33.7%422 20.000 21.152 24.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.168 0.6%4018 0.000 0.042 0.158
TOTAL: 00:00:26.49100:00:09.094 34.3%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 1
logmode 2
lowmark 1
mode 681
name 1
pause 1
paused 1
reload 2
reserve 1
TOTAL: 11 698

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
moderator@dana.de000100
us-control@lists.killfile.org000100
TOTAL001201

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1cmpublishers-out.news.weretis.net 5 13185 1779 7685 3721 13%43:05:16
2usenet.blueworldhosting.com 719 4079 147 2840 1092 3%46:10:44
3news.samoylyk.net 3 6486 86 6164 236 1%23:34:55
4nntp.club.cc.cmu.edu 586 8894 55 7883 956 0%64:11:22
5news.snarked.org 177 2077 9 2056 12 0%172:06:24
6localhost 23 0 0 0 0 0%00:00:00
7cmpublishers-out.news.tnetconsulting.net 49 0 0 0 0 0%48:18:31
TOTAL: 7 1562 34721 2076 26628 6017 5%397:27:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1cmpublishers-out.news.weretis.net5.2 MB0.0 KB11.1 MB16.3 MB 32%3.0 KB
2news.samoylyk.net487.7 KB0.0 KB897.2 KB1.4 MB 35%4.3 KB
3usenet.blueworldhosting.com439.2 KB0.0 KB4.4 MB4.9 MB 8%4.0 KB
4nntp.club.cc.cmu.edu185.9 KB0.0 KB4.7 MB4.9 MB 3%5.0 KB
5news.snarked.org52.2 KB0.0 KB363.7 KB415.9 KB 12%19.8 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
7cmpublishers-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 76.4 MB0.0 KB21.5 MB27.9 MB 22%3.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 1 00:00:07 - 00:59:59 32 1.5% 0.01112.9 KB 1.6% 0.03
Jul 1 01:00:00 - 01:59:59 107 5.0% 0.03332.6 KB 4.8% 0.09
Jul 1 02:00:00 - 02:59:59 44 2.0% 0.01282.9 KB 4.1% 0.08
Jul 1 03:00:00 - 03:59:59 56 2.6% 0.02191.0 KB 2.8% 0.05
Jul 1 04:00:00 - 04:59:59 89 4.1% 0.02608.7 KB 8.8% 0.17
Jul 1 05:00:00 - 05:59:59 52 2.4% 0.01161.3 KB 2.3% 0.04
Jul 1 06:00:00 - 06:59:59 64 3.0% 0.02153.2 KB 2.2% 0.04
Jul 1 07:00:00 - 07:59:59 89 4.1% 0.02335.9 KB 4.9% 0.09
Jul 1 08:00:00 - 08:59:59 97 4.5% 0.03424.5 KB 6.1% 0.12
Jul 1 09:00:00 - 09:59:59 105 4.9% 0.03245.2 KB 3.5% 0.07
Jul 1 10:00:00 - 10:59:59 106 4.9% 0.03207.6 KB 3.0% 0.06
Jul 1 11:00:00 - 11:59:59 90 4.2% 0.03188.7 KB 2.7% 0.05
Jul 1 12:00:00 - 12:59:59 91 4.2% 0.03233.0 KB 3.4% 0.06
Jul 1 13:00:00 - 13:59:59 97 4.5% 0.03205.6 KB 3.0% 0.06
Jul 1 14:00:00 - 14:59:59 94 4.3% 0.03199.4 KB 2.9% 0.06
Jul 1 15:00:00 - 15:59:59 174 8.1% 0.05513.0 KB 7.4% 0.14
Jul 1 16:00:00 - 16:59:59 126 5.8% 0.04371.7 KB 5.4% 0.10
Jul 1 17:00:00 - 17:59:59 115 5.3% 0.03319.4 KB 4.6% 0.09
Jul 1 18:00:00 - 18:59:59 96 4.4% 0.03321.8 KB 4.6% 0.09
Jul 1 19:00:00 - 19:59:59 126 5.8% 0.04316.9 KB 4.6% 0.09
Jul 1 20:00:00 - 20:59:59 65 3.0% 0.02178.2 KB 2.6% 0.05
Jul 1 21:00:00 - 21:59:59 96 4.4% 0.03328.4 KB 4.7% 0.09
Jul 1 22:00:00 - 22:59:59 98 4.5% 0.03450.5 KB 6.5% 0.13
Jul 1 23:00:00 - 23:59:59 51 2.4% 0.01230.1 KB 3.3% 0.06
Jul 2 00:00:00 - 00:00:05 1 0.0% 0.209.0 KB 0.1% 1.81
TOTAL: 23:59:58 2161 100.0% 0.036.8 MB 100.0% 0.08
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1cmpublishers-out.news.weretis.net 3830 3369 0 0 0 0 0 0 461
2usenet.blueworldhosting.com 1086 368 0 0 0 0 0 0 718
3nntp.club.cc.cmu.edu 956 365 0 0 0 0 0 0 591
4news.samoylyk.net 241 184 0 0 0 0 0 0 57
5news.snarked.org 12 11 0 0 0 0 0 0 1
TOTAL: 5 6125 4297 0 0 0 0 0 0 1828

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test 305
free.pt 207
alt.arts.poetry.comments 200
fr.soc.politique 131
aus.cars 111
alt.computer.workshop 83
uk.railway 81
de.etc.sprache.deutsch 79
alt.checkmate 79
fr.soc.environnement 78
linux.kernel.git 70
fr.sci.maths 69
ger.ct 63
de.talk.tagesgeschehen 62
alt.buddha.short.fat.guy 58
rec.arts.tv 57
de.etc.fahrzeug.auto 56
it.hobby.fai-da-te 54
it.sport.calcio.milan 53
fido7.ru.military 50
TOTAL: 452 4297

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 939
EMP (md5) 345
Too many newsgroups 257
User-issued cancel 199
EMP (phn nph) 45
Too many newsgroups (meow) 31
MID-Bot 5
HTML Multipart 4
Scoring filter (8) 3
TOTAL: 9 1828

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
usenet.blueworldhosting.com 1
TOTAL: 1 1
Including strange strings
cmpublishers-out.news.weretis.net 7
TOTAL: 1 7
TOTAL: 2 8

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.snarked.org 2969 17 2132 6 0 70 0%23:02:12
2CMU 2309 12 2090 0 0 26 0%23:02:12
3weretis.net 271 1 267 0 0 22 0%15:21:22
4news.samoylyk.net 2075 1 2044 0 0 37 0%23:02:12
5TNet 2374 1 2156 0 0 24 0%23:02:12
6news.freedyn.de 0 0 0 0 0 2161 0%23:02:12
7bwh 1905 0 1832 0 0 3 0%23:02:12
8aioe 0 0 0 0 0 1849 0%23:02:16
9news.ripco.com 0 0 0 0 0 2147 0%23:02:12
TOTAL: 9 11903 32 10521 6 0 6339 0%199:39:02
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.snarked.org53.8 KB27.1 KB80.9 KB0.0 KB/s3.5 KB23:02:12
2CMU19.7 KB0.0 KB19.7 KB0.0 KB/s1.6 KB23:02:12
3weretis.net2.7 KB0.0 KB2.7 KB0.0 KB/s2.7 KB15:21:22
4news.samoylyk.net2.7 KB0.0 KB2.7 KB0.0 KB/s2.7 KB23:02:12
5TNet2.7 KB0.0 KB2.7 KB0.0 KB/s2.7 KB23:02:12
6news.freedyn.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:02:12
7bwh0.0 KB0.0 KB0.0 KB0.0 KB/s023:02:12
8aioe0.0 KB0.0 KB0.0 KB0.0 KB/s023:02:16
9news.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:02:12
TOTAL: 981.6 KB27.1 KB108.7 KB0.0 KB/s2.9 KB199:39:02
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1cs6.cmpublishers.com 211 00.0 KB 0 0 000:00:09
TOTAL: 1 211 00.0 KB 0 0 000:00:26

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cmpublishers.com 211 00.0 KB 0 0 000:00:09
TOTAL: 1 211 00.0 KB 0 0 000:00:09

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
206.168.34.91 0.012 0.019 0.00000:00:14
cs6.cmpublishers.com 1.242 0.927 0.00000:00:09
206.168.34.194 0.029 0.013 0.00000:00:01
faithful.monitoring.internet-measurement.com 0.004 0.007 0.00000:00:00
205.210.31.109 0.007 0.004 0.00000:00:00
TOTAL: 5 1.294 0.970 0.00000:00:26

NNRP no permission clients:

SystemConn
206.168.34.194 4
206.168.34.91 3
faithful.monitoring.internet-measurement.com 1
205.210.31.109 1
TOTAL: 4 9