Daily Usenet report for csiph.com: Dec 10 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	28668	55.4%	6.0 MB	68.3%
innd	14095	27.2%	1.7 MB	18.7%
nnrpd	5743	11.1%	623.7 KB	6.9%
innfeed	3162	6.1%	543.2 KB	6.0%
nocem	66	0.1%	6.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	51735	100.0%	8.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	328434	56.3%
Negative hits	226656	38.9%
Do not exist	27892	4.8%
Cache misses	369	0.1%
TOTAL: 4	583351	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.676	0.0%	210	0.000	3.219	49.000
article cleanup	00:00:00.196	0.0%	27964	0.000	0.007	0.036
article logging	00:00:00.802	0.0%	28430	0.000	0.028	1.174
article parse	00:00:00.705	0.0%	71705	0.000	0.010	0.027
article write	00:05:21.812	0.4%	19516	0.625	16.490	107.273
artlog/artcncl	00:00:00.001	0.0%	148	0.000	0.007	0.100
artlog/artparse	00:00:00.000	0.0%	109	0.000	0.000	0.000
data move	00:00:00.159	0.0%	544186	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.084	0.0%	209	0.000	0.402	11.143
hishave/artcncl	00:00:00.006	0.0%	210	0.000	0.029	0.286
hishave/artparse	00:00:00.000	0.0%	103	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.151	0.0%	586479	0.002	0.009	0.158
history sync	00:00:01.329	0.0%	364	0.000	3.651	102.667
history write	00:00:17.746	0.0%	27964	0.062	0.635	13.319
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.037	0.0%	103	0.000	0.359	4.000
idle	23:51:04.502	99.3%	545702	99.928	157.347	478.320
nntp read	00:00:02.637	0.0%	557780	0.001	0.005	0.008
overview write	00:02:15.076	0.2%	19516	0.143	6.921	231.488
perl filter	00:00:50.889	0.1%	27958	1.327	1.820	3.786
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.356	0.0%	39093	0.000	0.009	0.029
TOTAL: 24:00:55.744	24:00:02.164	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.052	0.0%	19554	0.000	0.003	0.020
article prepare	00:00:00.004	0.0%	28480	0.000	0.000	0.006
article read	00:00:05.238	0.0%	4717	0.000	1.110	18.364
backlog stats	00:00:00.331	0.0%	357199	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.303	0.0%	275482	0.003	0.008	0.017
data write	00:00:01.968	0.0%	259811	0.001	0.008	0.019
idle	23:54:55.450	100.0%	357199	159.286	241.029	665.988
status file	00:00:00.646	0.0%	255	0.000	2.533	75.000
TOTAL: 23:55:14.207	23:55:05.992	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.152	0.0%	3613	0.042	0.042	0.042
idle	14:15:48.865	99.8%	24657	82.251	2082.527	600102.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.348	0.0%	49429	0.000	0.007	0.030
readart	00:01:05.158	0.1%	2643	0.981	24.653	188.800
TOTAL: 14:17:36.148	14:16:54.523	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	94385	5476	82802	6107	5%	95:03:30
2	csiph-out.news.weretis.net	6	36846	4613	31977	256	12%	46:23:51
3	feeder.eternal-september.org	4	23938	2796	20531	611	11%	25:26:19
4	news-out.aioe.org	2	18611	1350	17244	17	7%	24:09:57
5	newsfeed.neva.ru	146	35656	1087	33817	752	3%	71:56:06
6	news-out.mixmin.net	3	21703	1067	20184	452	4%	24:08:51
7	news.redatomik.org	1	20871	998	19864	9	4%	27:13:04
8	newsfeeds.xmission.com	3	18874	988	17885	1	5%	24:39:47
9	newsfeed.fu-berlin.de	32	19730	835	18732	163	4%	29:37:41
10	xanadu-bbs.net	13	742	127	600	15	17%	23:02:44
11	news.fcku.it	1	7635	114	7483	38	1%	23:59:54
12	news.freedyn.net	2	20737	72	20614	51	0%	23:59:00
13	news.swapon.de	1	1749	60	1688	1	3%	23:53:35
14	news.bbs.geek.nz	2	19617	5	19612	0	0%	23:59:45
15	nntp.pasdenom.info	2	22637	2	22611	24	0%	23:54:18
16	localhost	33	0	0	0	0	0%	00:00:00
TOTAL: 16		1403	363731	19590	335644	8497	5%	511:28:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	16.7 MB	11.1 KB	13.9 MB	30.6 MB	54%	2.7 KB
2	csiph-out.news.weretis.net	13.1 MB	43.2 KB	2.7 MB	15.8 MB	82%	3.3 KB
3	feeder.eternal-september.org	7.3 MB	2.9 KB	6.3 MB	13.6 MB	53%	4.1 KB
4	news-out.mixmin.net	4.5 MB	43.2 KB	3.2 MB	7.8 MB	58%	5.2 KB
5	news-out.aioe.org	3.1 MB	3.1 KB	30.0 KB	3.2 MB	98%	2.4 KB
6	newsfeed.neva.ru	3.0 MB	0.0 KB	1.6 MB	4.6 MB	65%	2.5 KB
7	newsfeeds.xmission.com	2.7 MB	3.0 KB	3.0 KB	2.7 MB	99%	2.8 KB
8	news.redatomik.org	2.6 MB	0.0 KB	225.3 KB	2.9 MB	92%	2.9 KB
9	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	543.8 KB	2.3 MB	77%	2.4 KB
10	news.freedyn.net	350.6 KB	0.0 KB	276.2 KB	626.8 KB	55%	5.1 KB
11	xanadu-bbs.net	330.5 KB	0.0 KB	26.3 KB	356.8 KB	92%	2.5 KB
12	news.fcku.it	312.7 KB	0.0 KB	1.5 MB	1.8 MB	17%	11.9 KB
13	news.swapon.de	137.8 KB	0.0 KB	80.3 KB	218.1 KB	63%	3.6 KB
14	nntp.pasdenom.info	6.8 KB	0.0 KB	324.1 KB	330.9 KB	2%	12.7 KB
15	news.bbs.geek.nz	5.5 KB	0.0 KB	0.0 KB	5.5 KB	100%	1.1 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		55.9 MB	106.6 KB	30.6 MB	86.7 MB	64%	3.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 10 04:15:00 - 04:59:59	358	1.8%	0.13	913.7 KB	1.6%	0.34
Dec 10 05:00:00 - 05:59:59	422	2.2%	0.12	1.2 MB	2.1%	0.34
Dec 10 06:00:00 - 06:59:59	492	2.5%	0.14	1.3 MB	2.3%	0.37
Dec 10 07:00:00 - 07:59:59	536	2.7%	0.15	1.5 MB	2.7%	0.43
Dec 10 08:00:00 - 08:59:59	732	3.8%	0.20	2.1 MB	3.8%	0.60
Dec 10 09:00:00 - 09:59:59	955	4.9%	0.27	2.8 MB	5.1%	0.81
Dec 10 10:00:00 - 10:59:59	984	5.0%	0.27	2.8 MB	5.1%	0.80
Dec 10 11:00:00 - 11:59:59	924	4.7%	0.26	2.8 MB	5.0%	0.78
Dec 10 12:00:00 - 12:59:59	836	4.3%	0.23	2.2 MB	3.9%	0.62
Dec 10 13:00:00 - 13:59:59	913	4.7%	0.25	2.7 MB	4.9%	0.78
Dec 10 14:00:00 - 14:59:59	958	4.9%	0.27	2.5 MB	4.5%	0.70
Dec 10 15:00:00 - 15:59:59	1136	5.8%	0.32	3.1 MB	5.6%	0.88
Dec 10 16:00:00 - 16:59:59	1060	5.4%	0.29	2.9 MB	5.2%	0.82
Dec 10 17:00:00 - 17:59:59	1031	5.3%	0.29	2.9 MB	5.2%	0.83
Dec 10 18:00:00 - 18:59:59	944	4.8%	0.26	2.5 MB	4.6%	0.72
Dec 10 19:00:00 - 19:59:59	1109	5.7%	0.31	3.3 MB	6.0%	0.94
Dec 10 20:00:00 - 20:59:59	968	5.0%	0.27	2.5 MB	4.6%	0.72
Dec 10 21:00:00 - 21:59:59	984	5.0%	0.27	2.6 MB	4.7%	0.75
Dec 10 22:00:00 - 22:59:59	930	4.8%	0.26	2.4 MB	4.3%	0.68
Dec 10 23:00:00 - 23:59:59	953	4.9%	0.26	2.9 MB	5.2%	0.82
Dec 11 00:00:00 - 00:59:59	681	3.5%	0.19	1.9 MB	3.4%	0.54
Dec 11 01:00:00 - 01:59:59	630	3.2%	0.17	2.2 MB	3.9%	0.61
Dec 11 02:00:00 - 02:59:59	504	2.6%	0.14	1.9 MB	3.3%	0.53
Dec 11 03:00:00 - 03:59:59	372	1.9%	0.10	1.3 MB	2.4%	0.37
Dec 11 04:00:00 - 04:15:00	106	0.5%	0.12	345.3 KB	0.6%	0.38
TOTAL: 24:00:00	19518	100.0%	0.23	55.5 MB	100.0%	0.66

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	6162	187	1	1	5973
2	newsfeed.neva.ru	755	183	0	0	572
3	feeder.eternal-september.org	642	53	1	0	588
4	newsfeed.fu-berlin.de	488	45	0	0	443
5	news-out.mixmin.net	473	4	1	0	468
6	csiph-out.news.weretis.net	295	105	1	0	189
7	news.freedyn.net	51	0	0	0	51
8	news.fcku.it	38	0	0	0	38
9	nntp.pasdenom.info	28	1	0	0	27
10	news-out.aioe.org	25	14	1	0	10
11	news.redatomik.org	16	0	0	0	16
12	xanadu-bbs.net	15	0	0	0	15
13	news.swapon.de	14	0	0	0	14
14	newsfeeds.xmission.com	1	0	1	0	0
15	news.bbs.geek.nz	1	1	0	0	0
TOTAL: 15		9004	593	6	1	8404

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	90
lada.talk	81
alt.windows7.general	64
spline.eisfair	25
alt.survival	22
mozilla.support.seamonkey	22
lada.commerce	20
mozilla.support.firefox	17
lada.auto	15
mozilla.dev.platform	11
mpc.lists.freebsd.gnome	10
alt.philosophy	10
oesterreich.tratsch	9
gmane.comp.gis.openstreetmap	8
it-alt.sport.calcio.juventus	8
test.post	8
mpc.lists.freebsd.gecko	7
mozilla.support.thunderbird	7
alt.binaries.camilla	6
free.it.religioni.scientology	6
TOTAL: 110	593

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

Groups	Count
escape.announce	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6314
EMP (phn path)	632
EMP (phl)	606
EMP (phn nph)	108
Too many newsgroups	25
Scoring filter (9)	17
Binary Payload	12
Scoring filter (16)	12
Scoring filter (13)	11
Scoring filter (15)	11
HTML Multipart	10
Scoring filter (10)	10
User-issued cancel	10
Scoring filter (8)	9
Scoring filter (12)	9
Scoring filter (18)	8
Scoring filter (11)	5
Scoring filter (19)	4
Binary Image: misplaced jpg	3
HTML post	3
TOTAL: 24	7827

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	news-out.mixmin.net	50
	csiph-out.news.weretis.net	22
	feeder.eternal-september.org	13
	news.fcku.it	6
	news.redatomik.org	6
	news.freedyn.net	3
	newsfeed.fu-berlin.de	2
	nntp.pasdenom.info	2
	news.swapon.de	1
	TOTAL: 9	105
Including strange strings
	newsfeed.fu-berlin.de	330
	csiph-out.feeder.erje.net	67
	csiph-out.news.weretis.net	19
	news-out.mixmin.net	16
	news.swapon.de	13
	news-out.aioe.org	8
	feeder.eternal-september.org	6
	news.redatomik.org	6
	newsfeed.neva.ru	1
	TOTAL: 9	466
No colon-space in header
	csiph-out.feeder.erje.net	6
	TOTAL: 1	6
TOTAL: 3		577

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	34175	2690	16081	62	7	7%	23:59:53
2	news.freedyn.net	19838	913	18514	12	7	4%	23:59:41
3	xanadu-bbs	22315	704	18681	0	1	3%	23:59:56
4	fu-berlin.de	18508	598	17910	0	0	3%	23:59:55
5	usenet.pasdenom.info	20226	93	17491	1932	67	0%	23:59:55
6	news.redatomik.org	18542	30	17882	2	0	0%	23:59:52
7	erje	14051	22	13891	30	0	0%	23:59:56
8	aioe	17303	13	16631	0	0	0%	23:59:55
9	xmission	20491	3	17926	1	4	0%	23:59:56
10	weretis.net	13242	3	12899	3	0	0%	23:59:56
11	neva.ru	17930	2	17478	21	0	0%	23:59:55
12	eternal-september	16692	1	15942	0	0	0%	23:59:47
13	news.swapon.de	1830	0	1801	0	0	0%	23:57:44
14	au2pb	0	0	0	0	18842	0%	23:58:42
15	news.mixmin.net	17890	0	17368	0	0	0%	23:59:36
16	news.fcku.it	1414	0	1405	0	0	0%	22:18:14
17	bwh	0	0	0	0	18809	0%	23:58:42
TOTAL: 17		254447	5072	221900	2063	37737	1%	406:11:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	7.3 MB	219.6 KB	7.5 MB	0.1 KB/s	2.8 KB	23:59:53
2	news.freedyn.net	5.2 MB	216.2 KB	5.5 MB	0.1 KB/s	6.0 KB	23:59:41
3	xanadu-bbs	4.8 MB	0.0 KB	4.8 MB	0.1 KB/s	7.0 KB	23:59:56
4	fu-berlin.de	4.5 MB	0.0 KB	4.5 MB	0.1 KB/s	7.8 KB	23:59:55
5	usenet.pasdenom.info	266.8 KB	7.0 MB	7.2 MB	0.1 KB/s	3.7 KB	23:59:55
6	news.redatomik.org	80.3 KB	17.1 KB	97.4 KB	0.0 KB/s	3.0 KB	23:59:52
7	erje	76.4 KB	170.6 KB	246.9 KB	0.0 KB/s	4.7 KB	23:59:56
8	aioe	29.0 KB	0.0 KB	29.0 KB	0.0 KB/s	2.2 KB	23:59:55
9	neva.ru	7.0 KB	56.3 KB	63.3 KB	0.0 KB/s	2.8 KB	23:59:55
10	eternal-september	4.2 KB	0.0 KB	4.2 KB	0.0 KB/s	4.2 KB	23:59:47
11	xmission	2.4 KB	8.1 KB	10.5 KB	0.0 KB/s	2.6 KB	23:59:56
12	weretis.net	2.4 KB	35.0 KB	37.4 KB	0.0 KB/s	6.2 KB	23:59:56
13	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:44
14	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
15	news.mixmin.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:36
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:18:14
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		22.3 MB	7.7 MB	30.0 MB	0.0 KB/s	4.3 KB	406:11:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	mtrlpq2314w-lp140-06-70-55-73-71.dsl.bell.ca	120	36	124.3 KB	24	00:36:49
2	g235242.upc-g.chello.nl	2	6	11.3 KB	1	00:03:48
TOTAL: 2		122	42	135.7 KB	25	14:17:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.dsl.bell.ca	120	36	124.3 KB	24	00:36:49
2	*.upc-g.chello.nl	2	6	11.3 KB	1	00:03:48
3	*.chello.nl	16	0	0.0 KB	0	02:28:08
4	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	00:30:01
5	*.bbox.io	6	0	0.0 KB	0	02:30:15
6	*.164.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	04:21:21
7	*.drr02.clwr.fl.frontiernet.net	6	0	0.0 KB	0	02:47:02
8	*.nielsenmedia.com	3	0	0.0 KB	0	01:00:08
TOTAL: 8		155	42	135.7 KB	25	14:17:35

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
h27.164.22.98.dynamic.ip.windstream.net	0.279	0.226	04:21:21
47-192-187-101.drr02.clwr.fl.frontiernet.net	0.417	0.080	02:47:02
tempe0.bbox.io	0.608	0.120	02:30:15
dhcp-089-099-203-254.chello.nl	1.019	0.304	02:28:08
challenger.nielsenmedia.com	0.209	0.040	01:00:08
mtrlpq2314w-lp140-06-70-55-73-71.dsl.bell.ca	14.159	2.958	00:36:49
c-174-61-130-222.hsd1.wa.comcast.net	0.048	0.034	00:30:01
g235242.upc-g.chello.nl	0.169	0.054	00:03:48
TOTAL: 8	16.908	3.816	14:17:35

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	16
47-192-187-101.drr02.clwr.fl.frontiernet.net	6
tempe0.bbox.io	6
challenger.nielsenmedia.com	3
c-174-61-130-222.hsd1.wa.comcast.net	1
h27.164.22.98.dynamic.ip.windstream.net	1
TOTAL: 6	33

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	5	0
47-192-187-101.drr02.clwr.fl.frontiernet.net	5	0
challenger.nielsenmedia.com	2	1
h27.164.22.98.dynamic.ip.windstream.net	1	0
c-174-61-130-222.hsd1.wa.comcast.net	1	0
TOTAL: 5	14	1

Newsgroup request counts (by category):

Category		Count	Pct
1	uk	13	31.0%
2	alt	12	28.6%
3	comp	7	16.7%
4	talk	4	9.5%
5	rec	4	9.5%
6	sci	1	2.4%
7	fr	1	2.4%
TOTAL: 7		42	100.0%

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

Newsgroup		Count
1	alt.home.repair	7
2	comp.sys.ibm.ps2.hardware	6
3	uk.media.radio.archers	6
4	talk.politics.misc	4
5	uk.legal	3
6	uk.d-i-y	3
7	alt.russian.z1	2
8	alt.society.liberalism	2
9	rec.arts.tv	2
10	sci.logic	1
11	fr.soc.politique	1
12	rec.boats	1
13	alt.happy.valley	1
14	comp.databases.oracle.server	1
15	uk.people.silversurfers	1
16	rec.food.cooking	1
TOTAL: 16		42

Daily Usenet report for csiph.com

Dec 10 04:15:00 -- Dec 11 04:15:00