Daily Usenet report for news.cmpublishers.com

Jun 18 00:00:04 -- Jun 19 00:00:04

Unknown entries from news log file:

First 50 / 180 lines (27.8%)

Jun 18 20:00:05 cs6 pgpverify[1937]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:00:10 cs6 pgpverify[1957]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:01:11 cs6 pgpverify[2164]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:01:14 cs6 pgpverify[2171]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:02:14 cs6 pgpverify[3281]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:02:16 cs6 pgpverify[3297]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:03:16 cs6 pgpverify[3608]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:03:18 cs6 pgpverify[3615]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:04:18 cs6 pgpverify[3706]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:04:20 cs6 pgpverify[3718]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:05:21 cs6 pgpverify[3921]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:05:22 cs6 pgpverify[3928]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:06:23 cs6 pgpverify[3989]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:06:25 cs6 pgpverify[3996]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:07:25 cs6 pgpverify[4220]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:07:26 cs6 pgpverify[4227]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:08:27 cs6 pgpverify[4414]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:08:29 cs6 pgpverify[4423]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:09:29 cs6 pgpverify[4730]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:09:31 cs6 pgpverify[4737]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:10:31 cs6 pgpverify[4791]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:10:33 cs6 pgpverify[4798]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:11:35 cs6 pgpverify[4986]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:11:38 cs6 pgpverify[4993]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:12:36 cs6 pgpverify[5051]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:12:37 cs6 pgpverify[5058]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:13:37 cs6 pgpverify[5369]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:13:39 cs6 pgpverify[5377]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:14:39 cs6 pgpverify[5441]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:14:41 cs6 pgpverify[5448]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:15:42 cs6 pgpverify[5645]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:15:43 cs6 pgpverify[5652]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:16:44 cs6 pgpverify[5702]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:16:46 cs6 pgpverify[5709]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:17:46 cs6 pgpverify[5927]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:17:48 cs6 pgpverify[5937]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:18:49 cs6 pgpverify[6129]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:18:50 cs6 pgpverify[6136]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:19:50 cs6 pgpverify[6354]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:19:52 cs6 pgpverify[6361]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:20:53 cs6 pgpverify[6418]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:20:54 cs6 pgpverify[6425]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:21:55 cs6 pgpverify[6615]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:21:56 cs6 pgpverify[6622]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:22:57 cs6 pgpverify[6771]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:22:59 cs6 pgpverify[6778]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:23:59 cs6 pgpverify[7026]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:24:01 cs6 pgpverify[7036]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:25:02 cs6 pgpverify[7091]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 20:25:04 cs6 pgpverify[7243]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
innd 11135 49.9%1.2 MB 44.4%
inn 8053 36.1%1.2 MB 43.2%
innfeed 2053 9.2%240.7 KB 8.5%
nnrpd 708 3.2%68.2 KB 2.4%
controlchan 181 0.8%13.5 KB 0.5%
pgpverify 180 0.8%26.8 KB 1.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 7 22311 100.0%2.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 25647 50.9%
Negative hits 16787 33.3%
Do not exist 7986 15.8%
Cache misses 11 0.0%
TOTAL: 4 50431 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%0 0.000 0.000 0.000
article cleanup00:00:00.106 0.0%8080 0.000 0.013 0.028
article logging00:00:00.888 0.0%8099 0.031 0.110 1.623
article parse00:00:00.267 0.0%22013 0.006 0.012 0.024
article write00:00:56.091 0.1%2363 0.667 23.737 114.530
artlog/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.145 0.0%52266 0.001 0.003 0.010
hisgrep/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hishave/artcncl00: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:01:33.850 0.1%50935 0.006 1.843 3.865
history sync00:00:00.123 0.0%306 0.000 0.402 5.250
history write00:01:15.920 0.1%8080 0.071 9.396 27.539
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:55:08.056 99.6%61551 823.801 1398.971 2975.123
nntp read00:00:00.708 0.0%59976 0.009 0.012 0.022
overview write00:00:37.145 0.0%2363 1.000 15.719 232.076
perl filter00:00:25.492 0.0%8079 2.056 3.155 11.491
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.410 0.0%9695 0.020 0.042 0.073
TOTAL: 24:00:11.94623:59:59.201100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.012 0.0%2430 0.000 0.005 0.028
article prepare00:00:00.001 0.0%704 0.000 0.001 0.021
article read00:00:00.038 0.0%169 0.000 0.225 17.000
backlog stats00:00:00.114 0.0%29057 0.000 0.004 0.022
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.169 0.0%15977 0.004 0.011 0.028
data write00:00:00.213 0.0%13729 0.003 0.016 0.039
idle24:01:27.119100.0%29057 1417.866 2976.464 4620.693
status file00:00:00.692 0.0%400 1.000 1.730 12.846
TOTAL: 24:01:33.50224:01:28.358100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:09.091 5.3%426 16.000 21.340 25.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.162 0.1%4061 0.000 0.040 0.526
TOTAL: 00:02:52.74500:00:09.253 5.4%----

Control commands to INND:

CommandNumber
flush 6
flushlogs 2
go 2
logmode 2
lowmark 1
mode 674
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 697

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org00018000
TOTAL00018000

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1cmpublishers-out.news.weretis.net 186 12563 1983 6987 3593 15%48:06:38
2usenet.blueworldhosting.com 713 4383 119 3099 1165 2%45:58:39
3news.samoylyk.net 2 7538 99 7175 264 1%28:20:58
4nntp.club.cc.cmu.edu 603 8508 78 7725 705 0%87:32:33
5news.snarked.org 151 2213 8 2191 14 0%155:50:42
6cmpublishers-out.news.tnetconsulting.net 24 0 0 0 0 0%23:14:17
7localhost 25 0 0 0 0 0%00:00:00
TOTAL: 7 1704 35205 2287 27177 5741 6%389:03:47
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1cmpublishers-out.news.weretis.net5.5 MB0.0 KB10.1 MB15.5 MB 35%2.9 KB
2news.samoylyk.net1.2 MB0.0 KB1.8 MB3.0 MB 41%8.5 KB
3usenet.blueworldhosting.com404.2 KB0.0 KB4.3 MB4.7 MB 8%3.7 KB
4nntp.club.cc.cmu.edu150.9 KB0.0 KB3.4 MB3.5 MB 4%4.6 KB
5news.snarked.org63.6 KB0.0 KB45.2 KB108.8 KB 58%4.9 KB
6cmpublishers-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
7localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 77.3 MB0.0 KB19.5 MB26.8 MB 27%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 18 00:00:04 - 00:59:59 66 2.8% 0.02229.5 KB 3.2% 0.06
Jun 18 01:00:00 - 01:59:59 72 3.0% 0.02263.3 KB 3.7% 0.07
Jun 18 02:00:00 - 02:59:59 47 2.0% 0.01167.4 KB 2.3% 0.05
Jun 18 03:00:00 - 03:59:59 73 3.1% 0.02234.1 KB 3.3% 0.07
Jun 18 04:00:00 - 04:59:59 68 2.9% 0.02236.9 KB 3.3% 0.07
Jun 18 05:00:00 - 05:59:59 56 2.4% 0.02245.4 KB 3.4% 0.07
Jun 18 06:00:00 - 06:59:59 66 2.8% 0.02178.9 KB 2.5% 0.05
Jun 18 07:00:00 - 07:59:59 82 3.5% 0.02249.2 KB 3.5% 0.07
Jun 18 08:00:00 - 08:59:59 79 3.3% 0.02231.6 KB 3.2% 0.06
Jun 18 09:00:00 - 09:59:59 99 4.2% 0.03269.7 KB 3.8% 0.07
Jun 18 10:00:00 - 10:59:59 135 5.7% 0.04367.7 KB 5.2% 0.10
Jun 18 11:00:00 - 11:59:59 135 5.7% 0.04357.4 KB 5.0% 0.10
Jun 18 12:00:00 - 12:59:59 115 4.9% 0.03290.1 KB 4.1% 0.08
Jun 18 13:00:00 - 13:59:59 119 5.0% 0.03290.0 KB 4.1% 0.08
Jun 18 14:00:00 - 14:59:59 103 4.4% 0.03301.8 KB 4.2% 0.08
Jun 18 15:00:00 - 15:59:59 104 4.4% 0.03270.5 KB 3.8% 0.08
Jun 18 16:00:00 - 16:59:59 120 5.1% 0.03296.2 KB 4.2% 0.08
Jun 18 17:00:00 - 17:59:59 140 5.9% 0.04298.5 KB 4.2% 0.08
Jun 18 18:00:00 - 18:59:59 85 3.6% 0.02333.2 KB 4.7% 0.09
Jun 18 19:00:00 - 19:59:59 96 4.1% 0.03243.4 KB 3.4% 0.07
Jun 18 20:00:00 - 20:59:59 222 9.4% 0.06774.3 KB 10.9% 0.22
Jun 18 21:00:00 - 21:59:59 163 6.9% 0.05577.8 KB 8.1% 0.16
Jun 18 22:00:00 - 22:59:59 70 3.0% 0.02235.0 KB 3.3% 0.07
Jun 18 23:00:00 - 00:00:04 48 2.0% 0.01187.1 KB 2.6% 0.05
TOTAL: 24:00:00 2363 100.0% 0.037.0 MB 100.0% 0.08
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1cmpublishers-out.news.weretis.net 3615 3028 0 0 0 0 0 0 587
2usenet.blueworldhosting.com 1162 252 0 0 0 0 0 0 910
3nntp.club.cc.cmu.edu 704 246 0 0 0 0 0 0 458
4news.samoylyk.net 196 144 0 0 0 0 0 0 52
5news.snarked.org 13 9 0 0 0 0 0 0 4
TOTAL: 5 5690 3679 0 0 0 0 0 0 2011

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test 301
alt.computer.workshop 216
fr.soc.politique 163
free.pt 143
it.hobby.fai-da-te 94
pl.soc.prawo 66
alt.arts.poetry.comments 64
de.talk.tagesgeschehen 63
fido7.ru.military 60
alt.prophecies.nostradamus 60
it.sport.calcio.milan 46
uk.railway 45
nl.politiek 42
de.etc.sprache.deutsch 42
alt.anonymous.messages 41
linux.kernel.git 41
linux.debian.changes.devel 41
de.comp.os.unix.linux.misc 40
de.sci.electronics 36
de.etc.bahn.misc 35
TOTAL: 328 3679

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 1083
EMP (md5) 629
Too many newsgroups 180
EMP (phn nph) 74
Scoring filter (9) 5
User-issued cancel 5
HTML Multipart 4
Too many newsgroups (meow) 4
Scoring filter (8) 3
Scoring filter (10) 3
Scoring filter (12) 1
TOTAL: 11 1991

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
cmpublishers-out.news.weretis.net 19
TOTAL: 1 19
TOTAL: 1 19

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1CMU 2424 61 2216 3 0 0 2%23:59:56
2news.snarked.org 3215 38 2298 19 0 51 1%23:59:22
3TNet 2662 20 2338 0 0 0 0%23:57:37
4news.samoylyk.net 2446 10 2161 25 0 8 0%23:59:55
5news.freedyn.de 0 0 0 0 0 2358 0%23:59:24
6news.ripco.com 0 0 0 0 0 2352 0%23:59:25
7bwh 2193 0 2060 0 0 0 0%23:59:56
8aioe 0 0 0 0 0 1965 0%23:59:59
9weretis.net 438 0 350 0 0 0 0%17:54:05
TOTAL: 9 13378 129 11423 47 0 6734 0%209:49:39
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1CMU279.3 KB5.3 KB284.6 KB0.0 KB/s4.4 KB23:59:56
2news.snarked.org100.9 KB151.4 KB252.3 KB0.0 KB/s4.4 KB23:59:22
3TNet77.8 KB0.0 KB77.8 KB0.0 KB/s3.9 KB23:57:37
4news.samoylyk.net34.0 KB64.6 KB98.7 KB0.0 KB/s2.8 KB23:59:55
5news.freedyn.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:24
6news.ripco.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:25
7bwh0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
8aioe0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
9weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s017:54:05
TOTAL: 9492.1 KB221.3 KB713.4 KB0.0 KB/s4.1 KB209:49:39
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1cs6.cmpublishers.com 213 00.0 KB 0 0 000:00:10
TOTAL: 1 213 00.0 KB 0 0 000:02:52

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cmpublishers.com 213 00.0 KB 0 0 000:00:10
TOTAL: 1 213 00.0 KB 0 0 000:00:10

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
206.168.34.40 0.041 0.024 0.00000:01:20
206.168.34.212 0.028 0.028 0.00000:01:06
206.168.34.208 0.004 0.007 0.00000:00:15
cs6.cmpublishers.com 1.243 1.003 0.00000:00:10
azpdeg7r3iwk.stretchoid.com 0.007 0.007 0.00000:00:00
45.142.193.185 0.005 0.005 0.00000:00:00
TOTAL: 6 1.328 1.074 0.00000:02:52

NNRP no permission clients:

SystemConn
206.168.34.40 6
206.168.34.212 5
45.142.193.185 1
azpdeg7r3iwk.stretchoid.com 1
206.168.34.208 1
TOTAL: 5 14