Daily Usenet report for csiph.com: May 19 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	402263	87.7%	41.5 MB	80.6%
inn	35615	7.8%	7.5 MB	14.5%
innd	17394	3.8%	2.0 MB	3.9%
innfeed	3252	0.7%	529.1 KB	1.0%
nocem	82	0.0%	8.4 KB	0.0%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	458608	100.0%	51.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	411104	56.0%
Negative hits	288330	39.3%
Do not exist	34489	4.7%
Cache misses	551	0.1%
TOTAL: 4	734474	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.140	0.0%	261	0.000	4.368	53.000
article cleanup	00:00:00.243	0.0%	34408	0.000	0.007	0.024
article logging	00:00:05.847	0.0%	34979	0.000	0.167	1.388
article parse	00:00:05.674	0.0%	105647	0.002	0.054	1.085
article write	00:03:00.062	0.2%	23821	0.210	7.559	43.277
artlog/artcncl	00:00:00.051	0.0%	206	0.000	0.248	2.000
artlog/artparse	00:00:00.068	0.0%	466	0.000	0.146	8.500
data move	00:00:00.239	0.0%	695827	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.486	0.0%	260	0.000	1.869	38.000
hishave/artcncl	00:00:00.216	0.0%	261	0.000	0.828	11.333
hishave/artparse	00:00:00.004	0.0%	465	0.000	0.009	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:51.967	0.2%	740343	0.002	0.232	1.439
history sync	00:00:05.696	0.0%	347	0.000	16.415	86.333
history write	00:04:28.216	0.3%	34326	0.081	7.814	48.085
hiswrite/artcncl	00:00:00.005	0.0%	1	5.000	5.000	5.000
hiswrite/artparse	00:00:04.302	0.0%	465	0.000	9.252	408.000
idle	23:40:05.195	98.6%	677473	61.436	125.769	338.068
nntp read	00:00:03.394	0.0%	710226	0.002	0.005	0.023
overview write	00:07:07.378	0.5%	23821	1.094	17.941	123.557
perl filter	00:01:13.802	0.1%	34325	1.543	2.150	3.179
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.421	0.0%	47714	0.000	0.009	0.029
TOTAL: 24:00:55.137	23:59:14.406	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.070	0.0%	23785	0.000	0.003	0.029
article prepare	00:00:00.004	0.0%	25996	0.000	0.000	0.007
article read	00:00:01.897	0.0%	3569	0.000	0.532	38.000
backlog stats	00:00:00.252	0.0%	424555	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.201	0.0%	328654	0.003	0.007	0.012
data write	00:00:01.948	0.0%	314793	0.001	0.006	0.012
idle	23:53:30.724	100.0%	424555	119.234	202.590	538.173
status file	00:00:00.621	0.0%	291	0.000	2.134	61.000
TOTAL: 23:53:48.531	23:53:37.717	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	45:27:57.076	99.1%	667324	90.623	245.274	600084.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.885	0.0%	1545445	0.000	0.006	0.143
readart	00:22:15.727	0.8%	239498	0.000	5.577	226.000
TOTAL: 45:53:05.571	45:50:21.688	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	137509	8918	122216	6375	6%	118:43:28
2	feeder.eternal-september.org	5	32326	3162	27667	1497	9%	24:49:07
3	csiph-out.news.weretis.net	3	49555	2465	46497	593	4%	48:59:18
4	news-out.mixmin.net	3	25484	1995	23055	434	7%	24:08:39
5	newsfeed.neva.ru	141	31224	1766	27941	1517	5%	71:43:39
6	news-out.aioe.org	4	23250	1661	21563	26	7%	24:09:38
7	newsfeed.fu-berlin.de	44	22506	1028	21198	280	4%	29:41:53
8	newsfeeds.xmission.com	3	20312	908	19397	7	4%	20:17:59
9	news.redatomik.org	1	19575	720	18848	7	3%	21:07:58
10	news.fcku.it	1	10018	472	9472	74	4%	23:59:57
11	news.freedyn.net	3	26263	310	25730	223	1%	24:09:43
12	xanadu-bbs.net	8	858	81	728	49	9%	24:50:19
13	nntp.pasdenom.info	2	21975	52	21922	1	0%	23:59:22
14	news.swapon.de	4	1787	37	1748	2	2%	25:10:34
15	localhost	44	8	8	0	0	100%	00:00:01
16	news.bbs.geek.nz	1	21241	5	21236	0	0%	23:59:48
TOTAL: 16		1707	443891	23588	409218	11085	5%	529:51:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	33.7 MB	444.3 KB	36.9 MB	71.0 MB	47%	4.8 KB
2	feeder.eternal-september.org	9.0 MB	52.4 KB	31.2 MB	40.3 MB	22%	8.9 KB
3	csiph-out.news.weretis.net	9.0 MB	3.0 KB	4.9 MB	13.9 MB	64%	4.7 KB
4	news-out.mixmin.net	7.5 MB	8.2 KB	1.2 MB	8.7 MB	85%	3.7 KB
5	newsfeed.neva.ru	5.3 MB	142.9 KB	6.8 MB	12.3 MB	43%	3.8 KB
6	news-out.aioe.org	4.2 MB	0.0 KB	43.4 KB	4.3 MB	99%	2.6 KB
7	newsfeeds.xmission.com	2.8 MB	0.0 KB	364.8 KB	3.2 MB	88%	3.6 KB
8	news.redatomik.org	2.3 MB	0.0 KB	21.7 KB	2.3 MB	99%	3.3 KB
9	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	726.2 KB	3.0 MB	76%	2.3 KB
10	news.fcku.it	1.3 MB	8.2 KB	1.1 MB	2.4 MB	53%	4.5 KB
11	news.freedyn.net	886.4 KB	0.0 KB	547.0 KB	1.4 MB	61%	2.7 KB
12	xanadu-bbs.net	234.8 KB	0.0 KB	90.3 KB	325.0 KB	72%	2.5 KB
13	nntp.pasdenom.info	107.0 KB	0.0 KB	0.7 KB	107.7 KB	99%	2.0 KB
14	news.swapon.de	95.0 KB	0.0 KB	540.1 KB	635.1 KB	14%	16.3 KB
15	news.bbs.geek.nz	37.5 KB	0.0 KB	0.0 KB	37.5 KB	100%	7.5 KB
16	localhost	16.5 KB	0.0 KB	0.0 KB	16.5 KB	100%	2.1 KB
TOTAL: 16		78.9 MB	659.0 KB	84.4 MB	163.9 MB	48%	4.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 19 04:15:00 - 04:59:59	574	2.4%	0.21	3.1 MB	3.9%	1.18
May 19 05:00:00 - 05:59:59	626	2.6%	0.17	2.2 MB	2.8%	0.63
May 19 06:00:00 - 06:59:59	819	3.4%	0.23	3.7 MB	4.7%	1.05
May 19 07:00:00 - 07:59:59	1047	4.4%	0.29	2.8 MB	3.6%	0.81
May 19 08:00:00 - 08:59:59	990	4.2%	0.28	2.8 MB	3.6%	0.81
May 19 09:00:00 - 09:59:59	1002	4.2%	0.28	2.7 MB	3.4%	0.78
May 19 10:00:00 - 10:59:59	1006	4.2%	0.28	2.9 MB	3.7%	0.83
May 19 11:00:00 - 11:59:59	1045	4.4%	0.29	2.8 MB	3.6%	0.80
May 19 12:00:00 - 12:59:59	1431	6.0%	0.40	5.5 MB	6.9%	1.55
May 19 13:00:00 - 13:59:59	1234	5.2%	0.34	3.5 MB	4.5%	1.01
May 19 14:00:00 - 14:59:59	1424	6.0%	0.40	6.2 MB	7.8%	1.76
May 19 15:00:00 - 15:59:59	1437	6.0%	0.40	4.2 MB	5.3%	1.20
May 19 16:00:00 - 16:59:59	1332	5.6%	0.37	4.1 MB	5.2%	1.18
May 19 17:00:00 - 17:59:59	1427	6.0%	0.40	4.9 MB	6.2%	1.40
May 19 18:00:00 - 18:59:59	1398	5.9%	0.39	5.4 MB	6.9%	1.55
May 19 19:00:00 - 19:59:59	1178	4.9%	0.33	3.7 MB	4.6%	1.04
May 19 20:00:00 - 20:59:59	1057	4.4%	0.29	3.1 MB	3.9%	0.89
May 19 21:00:00 - 21:59:59	1024	4.3%	0.28	3.0 MB	3.8%	0.85
May 19 22:00:00 - 22:59:59	998	4.2%	0.28	3.2 MB	4.0%	0.91
May 19 23:00:00 - 23:59:59	722	3.0%	0.20	2.1 MB	2.7%	0.61
May 20 00:00:00 - 00:59:59	559	2.3%	0.16	1.7 MB	2.1%	0.47
May 20 01:00:00 - 01:59:59	464	1.9%	0.13	1.6 MB	2.0%	0.46
May 20 02:00:00 - 02:59:59	462	1.9%	0.13	1.6 MB	2.1%	0.46
May 20 03:00:00 - 03:59:59	455	1.9%	0.13	1.7 MB	2.2%	0.49
May 20 04:00:00 - 04:15:00	104	0.4%	0.12	369.8 KB	0.5%	0.41
TOTAL: 24:00:00	23815	100.0%	0.28	79.2 MB	100.0%	0.94

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	6422	374	56	5992
2	newsfeed.neva.ru	1528	291	19	1218
3	feeder.eternal-september.org	1470	61	5	1404
4	newsfeed.fu-berlin.de	681	39	0	642
5	csiph-out.news.weretis.net	623	150	1	472
6	news-out.mixmin.net	478	0	1	477
7	news.freedyn.net	223	19	0	204
8	news.fcku.it	68	4	1	63
9	xanadu-bbs.net	38	0	0	38
10	news-out.aioe.org	35	24	0	11
11	news.redatomik.org	14	0	0	14
12	newsfeeds.xmission.com	11	0	0	11
13	news.swapon.de	3	0	0	3
14	nntp.pasdenom.info	1	1	0	0
TOTAL: 14		11595	963	83	10549

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	125
lada.talk	124
it-alt.sport.calcio.juventus	76
lada.auto	68
mpc.lists.freebsd.ports.bugs	46
mozilla.dev.security.policy	39
alt.windows7.general	36
spline.eisfair	33
it.annunci.immobiliari	26
it.annunci.commerciali	26
it.annunci.varie	25
mozilla.dev.platform	23
alt.survival	16
mozilla.support.thunderbird	14
gmane.linux.alsa.devel	13
gmane.comp.gis.openstreetmap.tagging	12
mozilla.support.seamonkey	11
lada.commerce	11
mozilla.test	10
cakewalk.audio	9
TOTAL: 123	963

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	8075
EMP (phn path)	1094
EMP (phl)	167
Too many newsgroups	45
HTML Multipart	25
Scoring filter (8)	24
Scoring filter (10)	22
User-issued cancel	20
Binary Payload	14
Scoring filter (9)	7
Scoring filter (11)	5
HTML post	5
Binary Image: misplaced jpg	2
EMP (phn nph)	2
HTML Image Tags	2
Scoring filter (12)	1
PFM bot misc	1
U2 violation - invalid distribution	1
TOTAL: 18	9512

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	72	0	77	77
TOTAL: 1	72	0	77	77

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.feeder.erje.net	249
	feeder.eternal-september.org	153
	csiph-out.news.weretis.net	30
	news.fcku.it	12
	news-out.mixmin.net	8
	newsfeeds.xmission.com	7
	newsfeed.fu-berlin.de	3
	news.freedyn.net	2
	news.redatomik.org	1
	news.swapon.de	1
	TOTAL: 10	466
Including strange strings
	newsfeed.fu-berlin.de	386
	csiph-out.feeder.erje.net	54
	csiph-out.news.weretis.net	46
	news-out.mixmin.net	44
	newsfeed.neva.ru	13
	news-out.aioe.org	9
	feeder.eternal-september.org	6
	news.redatomik.org	6
	newsfeeds.xmission.com	4
	news.fcku.it	1
	TOTAL: 11	570
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 3		1037

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	24803	1043	22416	44	1	4%	23:59:44
2	news.bbs.geek.nz	43117	1040	21907	92	2	2%	23:59:46
3	xanadu-bbs	25510	920	22808	1	6	3%	23:59:53
4	fu-berlin.de	22498	760	21737	0	0	3%	23:59:28
5	usenet.pasdenom.info	25604	198	21489	2073	2	0%	23:59:53
6	aioe	21606	55	20016	2	0	0%	23:59:50
7	news.redatomik.org	22929	22	22223	2	0	0%	23:59:52
8	weretis.net	17507	14	17230	2	0	0%	23:59:50
9	xmission	24299	14	21907	4	0	0%	23:59:53
10	neva.ru	21183	10	20652	118	0	0%	23:59:53
11	news.swapon.de	1824	9	1761	0	0	0%	23:57:34
12	news.mixmin.net	21945	9	20712	0	1	0%	23:59:53
13	eternal-september	21161	8	19554	0	0	0%	23:59:25
14	erje	14859	6	14741	51	4	0%	23:59:53
15	news.fcku.it	2336	1	2318	1	0	0%	23:01:33
16	bwh	0	0	0	0	23015	0%	23:58:42
17	au2pb	0	0	0	0	23043	0%	23:58:42
TOTAL: 17		311181	4109	271471	2390	46074	1%	406:53:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	11.1 MB	340.2 KB	11.5 MB	0.1 KB/s	10.8 KB	23:59:44
2	xanadu-bbs	10.6 MB	5.1 KB	10.6 MB	0.1 KB/s	11.8 KB	23:59:53
3	fu-berlin.de	10.1 MB	0.0 KB	10.1 MB	0.1 KB/s	13.7 KB	23:59:28
4	news.bbs.geek.nz	2.9 MB	462.5 KB	3.3 MB	0.0 KB/s	3.0 KB	23:59:46
5	usenet.pasdenom.info	881.1 KB	12.3 MB	13.2 MB	0.2 KB/s	5.9 KB	23:59:53
6	aioe	158.4 KB	2.8 KB	161.2 KB	0.0 KB/s	2.8 KB	23:59:50
7	news.redatomik.org	88.2 KB	15.7 KB	103.9 KB	0.0 KB/s	4.3 KB	23:59:52
8	xmission	37.6 KB	7.3 KB	44.9 KB	0.0 KB/s	2.5 KB	23:59:53
9	weretis.net	36.9 KB	6.7 KB	43.6 KB	0.0 KB/s	2.7 KB	23:59:50
10	news.mixmin.net	20.6 KB	0.0 KB	20.6 KB	0.0 KB/s	2.3 KB	23:59:53
11	news.swapon.de	18.1 KB	0.0 KB	18.1 KB	0.0 KB/s	2.0 KB	23:57:34
12	neva.ru	17.5 KB	379.0 KB	396.5 KB	0.0 KB/s	3.1 KB	23:59:53
13	eternal-september	16.6 KB	0.0 KB	16.6 KB	0.0 KB/s	2.1 KB	23:59:25
14	erje	13.0 KB	318.3 KB	331.3 KB	0.0 KB/s	5.8 KB	23:59:53
15	news.fcku.it	1.6 KB	2.0 KB	3.6 KB	0.0 KB/s	1.8 KB	23:01:33
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		36.0 MB	13.8 MB	49.8 MB	0.0 KB/s	7.8 KB	406:53:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	496	104824	299.1 MB	473	0	0	31:51:31
2	tempe0.bbox.io	7	134	220.0 KB	4	3	2	02:46:04
3	144.138.90.254	288	57	150.1 KB	57	0	0	03:39:41
4	96-42-16-100.dhcp.mdsn.wi.charter.com	12	8	16.6 KB	7	5	4	03:22:22
5	204-73-239-109.cust.centrio.cz	8	7	13.8 KB	5	0	0	01:30:20
6	dynamic-194-228-32-249.ipv4.broadband.iol.cz	2	2	4.7 KB	2	0	0	00:00:22
7	dhcp-089-099-203-254.chello.nl	19	1	5.2 KB	1	0	0	02:42:35
TOTAL: 7		832	105033	299.5 MB	549	8	6	45:52:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	496	104824	299.1 MB	473	0	0	31:51:31
2	*.bbox.io	7	134	220.0 KB	4	3	2	02:46:04
3	unresolved	288	57	150.1 KB	57	0	0	03:39:41
4	*.dhcp.mdsn.wi.charter.com	12	8	16.6 KB	7	5	4	03:22:22
5	*.cust.centrio.cz	8	7	13.8 KB	5	0	0	01:30:20
6	*.ipv4.broadband.iol.cz	2	2	4.7 KB	2	0	0	00:00:22
7	*.chello.nl	19	1	5.2 KB	1	0	0	02:42:35
TOTAL: 7		832	105033	299.5 MB	549	8	6	45:52:57

NNRP auth users [Top 20]:

User	Conn
moussa	288
ctomczyk	8
ohlandl	4
kev009	2
TOTAL: 4	302

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	73.622	55.945	0.000	31:51:31
144.138.90.254	24.962	6.613	1.020	03:39:41
96-42-16-100.dhcp.mdsn.wi.charter.com	1.274	0.294	0.015	03:22:22
tempe0.bbox.io	0.846	0.181	0.007	02:46:04
dhcp-089-099-203-254.chello.nl	1.566	0.423	0.000	02:42:35
204-73-239-109.cust.centrio.cz	0.829	0.132	0.024	01:30:20
dynamic-194-228-32-249.ipv4.broadband.iol.cz	0.275	0.031	0.007	00:00:22
TOTAL: 7	103.374	63.619	1.073	45:52:57

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	5	0
96-42-16-100.dhcp.mdsn.wi.charter.com	2	0
204-73-239-109.cust.centrio.cz	2	0
TOTAL: 3	9	0

Newsgroup request counts (by category):

Category		Count	Pct
1	rec	49541	47.2%
2	uk	29672	28.3%
3	can	13566	12.9%
4	sac	8397	8.0%
5	alt	2677	2.5%
6	free	971	0.9%
7	comp	209	0.2%
TOTAL: 7		105033	100.0%

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

Newsgroup		Count
1	rec.arts.tv	49541
2	uk.politics.misc	29672
3	can.politics	13566
4	sac.politics	8397
5	alt.messianic	2669
6	free.usenet	970
7	comp.sys.ibm.ps2.hardware	151
8	comp.arch.embedded	11
9	comp.arch.fpga	9
10	comp.lang.javascript	9
11	comp.os.vms	9
12	comp.arch	6
13	comp.windows.x	4
14	comp.os.msdos.programmer	3
15	comp.sys.ibm.pc.games.action	3
16	alt.suicide.methods	2
17	alt.binaries.series.tv.divx.french.d	2
18	comp.sys.ibm.as400.misc	2
19	alt.conspiracy.jfk	1
20	free.at	1
21	alt.life.sucks	1
22	alt.sports.college.pac-10	1
23	comp.os.cpm	1
24	comp.sys.ibm.pc.hardware.storage	1
25	alt.drugs.lsd	1
TOTAL: 25		105033

Daily Usenet report for csiph.com

May 19 04:15:00 -- May 20 04:15:00