Daily Usenet report for csiph.com: Oct 11 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 11 07:57:37 csiph pgpverify[8489]: pgpverify: verification failed
Oct 11 07:57:47 csiph pgpverify[8499]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	36417	64.4%	7.6 MB	75.4%
innd	16135	28.5%	1.8 MB	18.4%
innfeed	3251	5.7%	567.8 KB	5.5%
nnrpd	651	1.2%	70.7 KB	0.7%
nocem	78	0.1%	7.8 KB	0.1%
controlchan	7	0.0%	1.0 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
TOTAL: 7	56541	100.0%	10.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	418939	55.3%
Negative hits	302387	39.9%
Do not exist	35515	4.7%
Cache misses	582	0.1%
TOTAL: 4	757423	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.894	0.0%	260	0.000	3.438	37.143
article cleanup	00:00:00.240	0.0%	35471	0.000	0.007	0.035
article logging	00:00:00.582	0.0%	36061	0.000	0.016	0.321
article parse	00:00:01.193	0.0%	99926	0.002	0.012	0.047
article write	00:06:37.623	0.5%	24073	0.984	16.517	58.824
artlog/artcncl	00:00:00.001	0.0%	171	0.000	0.006	0.250
artlog/artparse	00:00:00.004	0.0%	235	0.000	0.017	0.333
data move	00:00:00.179	0.0%	704628	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.205	0.0%	258	0.000	0.795	20.714
hishave/artcncl	00:00:00.112	0.0%	260	0.000	0.431	15.571
hishave/artparse	00:00:00.001	0.0%	235	0.000	0.004	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:07.918	0.0%	759234	0.001	0.010	0.203
history sync	00:00:01.266	0.0%	349	0.000	3.628	48.667
history write	00:00:34.140	0.0%	35390	0.045	0.965	16.437
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.110	0.0%	234	0.000	0.470	4.000
idle	23:49:46.541	99.2%	706423	69.367	121.438	300.220
nntp read	00:00:02.458	0.0%	721186	0.001	0.003	0.006
overview write	00:01:29.881	0.1%	24073	0.190	3.734	79.663
perl filter	00:01:14.814	0.1%	35390	1.676	2.114	4.391
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.396	0.0%	48217	0.000	0.008	0.022
TOTAL: 24:00:47.753	23:59:58.558	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.061	0.0%	24275	0.000	0.003	0.017
article prepare	00:00:00.007	0.0%	31240	0.000	0.000	0.006
article read	00:00:05.459	0.0%	4995	0.000	1.093	15.960
backlog stats	00:00:00.198	0.0%	434534	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.830	0.0%	338693	0.001	0.005	0.010
data write	00:00:02.414	0.0%	321387	0.003	0.008	0.016
idle	23:53:35.833	100.0%	434534	101.247	197.950	442.584
status file	00:00:00.432	0.0%	298	0.000	1.450	4.000
TOTAL: 23:53:57.750	23:53:46.234	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.047	0.0%	65	0.000	0.723	39.000
idle	40:50:17.669	99.8%	306	285.680	480449.899	900184.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	671	0.000	0.010	0.250
readart	00:00:08.065	0.0%	2671	0.000	3.019	3.021
TOTAL: 40:56:17.597	40:50:25.788	99.8%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	137
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	157

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
grisbi-control@grisbi.org	0	0	0	2	0	0
TOTAL	0	0	0	2	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	117778	7102	103336	7340	6%	94:58:10
2	feeder.eternal-september.org	4	31010	3779	26743	488	12%	25:13:44
3	csiph-out.news.weretis.net	6	43866	3622	39758	486	8%	38:34:20
4	news-out.mixmin.net	2	26277	2843	22644	790	10%	24:08:56
5	newsfeed.neva.ru	146	40889	1676	37631	1582	4%	72:04:28
6	news-out.aioe.org	3	23102	1606	21489	7	6%	24:09:39
7	newsfeed.fu-berlin.de	51	25857	1272	24284	301	4%	33:44:36
8	news.redatomik.org	1	24694	959	23717	18	3%	27:46:28
9	newsfeeds.xmission.com	1	22377	631	21710	36	2%	22:29:16
10	news.fcku.it	2	10667	458	10051	158	4%	23:59:40
11	xanadu-bbs.net	11	769	73	677	19	9%	23:28:43
12	news.swapon.de	2	1999	67	1928	4	3%	24:01:20
13	news.freedyn.net	2	25707	42	25623	42	0%	24:00:19
14	nntp.pasdenom.info	2	31906	19	31863	24	0%	24:09:00
15	news.bbs.geek.nz	2	21716	9	21707	0	0%	23:59:44
16	localhost	36	0	0	0	0	0%	00:00:00
TOTAL: 16		1423	448614	24158	413161	11295	5%	506:48:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	22.6 MB	166.8 KB	23.9 MB	46.7 MB	48%	3.3 KB
2	feeder.eternal-september.org	9.4 MB	330.7 KB	11.5 MB	21.2 MB	44%	5.1 KB
3	csiph-out.news.weretis.net	9.4 MB	0.0 KB	3.8 MB	13.1 MB	71%	3.3 KB
4	news-out.mixmin.net	9.3 MB	6.9 KB	2.3 MB	11.6 MB	80%	3.3 KB
5	newsfeed.neva.ru	4.6 MB	132.0 KB	6.5 MB	11.3 MB	40%	3.5 KB
6	news-out.aioe.org	3.6 MB	0.0 KB	11.7 KB	3.6 MB	99%	2.3 KB
7	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	618.6 KB	3.2 MB	81%	2.1 KB
8	news.redatomik.org	2.5 MB	0.0 KB	615.4 KB	3.1 MB	80%	3.3 KB
9	newsfeeds.xmission.com	1.7 MB	0.0 KB	2.2 MB	3.9 MB	43%	6.0 KB
10	news.fcku.it	953.8 KB	2.0 KB	2.1 MB	3.0 MB	31%	5.0 KB
11	news.freedyn.net	203.7 KB	0.0 KB	2.4 MB	2.6 MB	7%	31.9 KB
12	xanadu-bbs.net	185.4 KB	0.0 KB	329.4 KB	514.8 KB	36%	5.6 KB
13	news.swapon.de	160.1 KB	0.0 KB	931.9 KB	1.1 MB	14%	15.4 KB
14	nntp.pasdenom.info	32.5 KB	0.0 KB	208.3 KB	240.8 KB	13%	5.6 KB
15	news.bbs.geek.nz	17.1 KB	0.0 KB	0.0 KB	17.1 KB	100%	1.9 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		67.3 MB	638.4 KB	57.3 MB	125.2 MB	53%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 11 04:15:00 - 04:59:59	413	1.7%	0.15	1.7 MB	2.5%	0.65
Oct 11 05:00:00 - 05:59:59	632	2.6%	0.18	2.6 MB	3.8%	0.74
Oct 11 06:00:00 - 06:59:59	720	3.0%	0.20	1.9 MB	2.8%	0.54
Oct 11 07:00:00 - 07:59:59	965	4.0%	0.27	2.7 MB	4.0%	0.77
Oct 11 08:00:00 - 08:59:59	1017	4.2%	0.28	2.4 MB	3.6%	0.69
Oct 11 09:00:00 - 09:59:59	1008	4.2%	0.28	2.5 MB	3.8%	0.72
Oct 11 10:00:00 - 10:59:59	1094	4.5%	0.30	2.8 MB	4.2%	0.81
Oct 11 11:00:00 - 11:59:59	1018	4.2%	0.28	2.5 MB	3.7%	0.70
Oct 11 12:00:00 - 12:59:59	1056	4.4%	0.29	2.5 MB	3.7%	0.71
Oct 11 13:00:00 - 13:59:59	1367	5.7%	0.38	3.9 MB	5.7%	1.10
Oct 11 14:00:00 - 14:59:59	1426	5.9%	0.40	3.8 MB	5.6%	1.08
Oct 11 15:00:00 - 15:59:59	1483	6.2%	0.41	4.1 MB	6.1%	1.18
Oct 11 16:00:00 - 16:59:59	1490	6.2%	0.41	4.1 MB	6.1%	1.17
Oct 11 17:00:00 - 17:59:59	1548	6.4%	0.43	4.3 MB	6.3%	1.21
Oct 11 18:00:00 - 18:59:59	1263	5.2%	0.35	3.4 MB	5.1%	0.97
Oct 11 19:00:00 - 19:59:59	1209	5.0%	0.34	3.5 MB	5.2%	1.01
Oct 11 20:00:00 - 20:59:59	1197	5.0%	0.33	3.5 MB	5.1%	0.99
Oct 11 21:00:00 - 21:59:59	1172	4.9%	0.33	3.1 MB	4.6%	0.88
Oct 11 22:00:00 - 22:59:59	917	3.8%	0.25	2.7 MB	4.0%	0.77
Oct 11 23:00:00 - 23:59:59	780	3.2%	0.22	2.2 MB	3.3%	0.64
Oct 12 00:00:00 - 00:59:59	636	2.6%	0.18	1.8 MB	2.7%	0.52
Oct 12 01:00:00 - 01:59:59	564	2.3%	0.16	1.9 MB	2.8%	0.54
Oct 12 02:00:00 - 02:59:59	469	1.9%	0.13	1.4 MB	2.1%	0.39
Oct 12 03:00:00 - 03:59:59	491	2.0%	0.14	1.5 MB	2.2%	0.43
Oct 12 04:00:00 - 04:15:00	126	0.5%	0.14	531.2 KB	0.8%	0.59
TOTAL: 24:00:00	24061	100.0%	0.28	67.5 MB	100.0%	0.80

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	7358	756	17	6585
2	newsfeed.neva.ru	1658	381	22	1255
3	news-out.mixmin.net	835	10	2	823
4	newsfeed.fu-berlin.de	697	29	0	668
5	csiph-out.news.weretis.net	658	408	1	249
6	feeder.eternal-september.org	639	64	38	537
7	news.fcku.it	164	0	1	163
8	news.freedyn.net	38	0	0	38
9	news.redatomik.org	34	0	0	34
10	nntp.pasdenom.info	27	1	0	26
11	newsfeeds.xmission.com	26	0	0	26
12	news-out.aioe.org	22	8	0	14
13	xanadu-bbs.net	19	0	0	19
14	news.swapon.de	11	0	0	11
TOTAL: 14		12186	1657	81	10448

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	592
lada.talk	130
ut.test	122
fa.freebsd.cvs-all	85
fa.openbsd.tech	45
spline.eisfair	34
gmane.linux.alsa.devel	32
lada.auto	28
mpc.lists.freebsd.ports.bugs	27
it-alt.sport.calcio.juventus	25
alt.windows7.general	25
fa.openbsd.source-changes	23
fa.freebsd.bugs	22
fa.philos-l	22
cakewalk.audio	22
it.annunci.commerciali	21
it.annunci.immobiliari	21
it.annunci.varie	21
fa.netbsd.bugs	20
mozilla.support.seamonkey	16
TOTAL: 129	1657

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7585
EMP (phn path)	1055
EMP (phl)	511
EMP (phn nph)	99
Too many newsgroups	59
Scoring filter (10)	38
Scoring filter (12)	32
Scoring filter (9)	26
Scoring filter (11)	25
Scoring filter (8)	23
Binary Payload	22
User-issued cancel	21
HTML Multipart	20
Scoring filter (18)	15
Scoring filter (19)	11
HTML post	11
Scoring filter (17)	9
Scoring filter (13)	8
Scoring filter (16)	8
Binary: misplaced zip	7
TOTAL: 31	9623

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	68	0	79	79
TOTAL: 1	68	0	79	79

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	feeder.eternal-september.org	106
	news.fcku.it	39
	csiph-out.news.weretis.net	26
	newsfeeds.xmission.com	26
	news-out.mixmin.net	19
	xanadu-bbs.net	7
	news.swapon.de	4
	news.redatomik.org	4
	news.freedyn.net	3
	nntp.pasdenom.info	2
	TOTAL: 10	236
Including strange strings
	newsfeed.fu-berlin.de	402
	news-out.mixmin.net	39
	csiph-out.feeder.erje.net	39
	feeder.eternal-september.org	30
	news.redatomik.org	22
	csiph-out.news.weretis.net	18
	newsfeed.neva.ru	16
	news-out.aioe.org	13
	news.swapon.de	7
	news.fcku.it	3
	TOTAL: 10	589
TOTAL: 2		825

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	42305	3834	19488	86	0	9%	23:59:51
2	news.freedyn.net	24500	953	23047	4	0	3%	23:59:51
3	xanadu-bbs	26551	694	23275	14	246	2%	23:59:52
4	fu-berlin.de	22416	561	21855	0	0	2%	23:50:03
5	usenet.pasdenom.info	26151	305	22812	923	0	1%	23:59:37
6	erje	17733	191	16596	13	0	1%	23:50:03
7	news.redatomik.org	23073	87	22311	32	0	0%	23:52:26
8	xmission	24924	27	22693	1	0	0%	23:59:40
9	aioe	21130	24	20155	11	0	0%	23:59:57
10	weretis.net	19192	19	18869	0	12	0%	24:02:39
11	news.mixmin.net	20820	2	20104	0	0	0%	23:50:46
12	news.swapon.de	2051	2	2008	0	0	0%	23:43:37
13	au2pb	0	0	0	0	23361	0%	23:50:08
14	news.fcku.it	3169	0	3085	0	0	0%	22:48:00
15	neva.ru	22279	0	21381	27	0	0%	23:59:52
16	eternal-september	20605	0	19330	0	0	0%	23:59:52
17	bwh	0	0	0	0	23306	0%	23:50:07
TOTAL: 17		316899	6699	277009	1111	46925	2%	405:36:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	9.3 MB	325.6 KB	9.6 MB	0.1 KB/s	2.5 KB	23:59:51
2	news.freedyn.net	6.1 MB	8.9 KB	6.1 MB	0.1 KB/s	6.5 KB	23:59:51
3	xanadu-bbs	5.5 MB	33.3 KB	5.6 MB	0.1 KB/s	8.0 KB	23:59:52
4	fu-berlin.de	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	9.2 KB	23:50:03
5	usenet.pasdenom.info	735.2 KB	5.8 MB	6.5 MB	0.1 KB/s	5.4 KB	23:59:37
6	erje	473.8 KB	49.0 KB	522.8 KB	0.0 KB/s	2.6 KB	23:50:03
7	news.redatomik.org	238.0 KB	101.4 KB	339.4 KB	0.0 KB/s	2.9 KB	23:52:26
8	xmission	92.8 KB	4.4 KB	97.2 KB	0.0 KB/s	3.5 KB	23:59:40
9	aioe	63.3 KB	27.1 KB	90.4 KB	0.0 KB/s	2.6 KB	23:59:57
10	weretis.net	62.8 KB	0.0 KB	62.8 KB	0.0 KB/s	3.3 KB	24:02:39
11	news.mixmin.net	3.8 KB	0.0 KB	3.8 KB	0.0 KB/s	1.9 KB	23:50:46
12	news.swapon.de	3.2 KB	0.0 KB	3.2 KB	0.0 KB/s	1.6 KB	23:43:37
13	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:08
14	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:48:00
15	neva.ru	0.0 KB	81.1 KB	81.1 KB	0.0 KB/s	3.0 KB	23:59:52
16	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
TOTAL: 17		27.6 MB	6.4 MB	34.0 MB	0.0 KB/s	4.5 KB	405:36:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	23	1	9.9 KB	1	0	0	03:34:51
TOTAL: 1		23	1	9.9 KB	1	0	0	40:56:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.bbox.io	23	1	9.9 KB	1	03:34:51
2	*.chello.nl	9	0	0.0 KB	0	01:27:39
3	*.hsd1.nj.comcast.net	4	0	0.0 KB	0	02:09:46
4	*.dhcp.mdsn.wi.charter.com	2	0	0.0 KB	0	01:00:07
5	*.19-1.cable.virginm.net	1	0	0.0 KB	0	00:07:48
6	unresolved	65	0	0.0 KB	0	32:36:01
TOTAL: 6		104	1	9.9 KB	1	40:56:16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
216.239.174.218	5.266	1.102	32:35:46
tempe0.bbox.io	1.672	0.468	03:34:51
c-73-195-247-24.hsd1.nj.comcast.net	0.414	0.117	02:09:46
dhcp-089-099-203-254.chello.nl	0.757	0.156	01:27:39
68-185-181-64.dhcp.mdsn.wi.charter.com	0.190	0.039	01:00:07
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	0.052	0.026	00:07:48
196.52.16.52	0.193	0.289	00:00:15
TOTAL: 7	8.544	2.197	40:56:16

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	64
dhcp-089-099-203-254.chello.nl	9
c-73-195-247-24.hsd1.nj.comcast.net	4
68-185-181-64.dhcp.mdsn.wi.charter.com	2
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1
196.52.16.52	1
TOTAL: 6	81

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	65	0
tempe0.bbox.io	6	0
c-73-195-247-24.hsd1.nj.comcast.net	4	0
68-185-181-64.dhcp.mdsn.wi.charter.com	2	0
TOTAL: 4	77	0

Newsgroup request counts (by category):

Category		Count	Pct
1	muc	1	100.0%
TOTAL: 1		1	100.0%

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

Newsgroup		Count
1	muc.lists.freebsd.stable	1
TOTAL: 1		1

Daily Usenet report for csiph.com

Oct 11 04:15:00 -- Oct 12 04:15:00