Daily Usenet report for csiph.com: Aug 22 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	24544	53.6%	5.3 MB	67.8%
innd	11214	24.5%	1.4 MB	17.9%
nnrpd	7122	15.5%	654.7 KB	8.1%
innfeed	2883	6.3%	492.1 KB	6.1%
nocem	60	0.1%	6.9 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	45824	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	318283	58.8%
Negative hits	198586	36.7%
Do not exist	23723	4.4%
Cache misses	426	0.1%
TOTAL: 4	541018	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.099	0.0%	106	0.000	0.934	16.750
article cleanup	00:00:00.172	0.0%	23432	0.000	0.007	0.034
article logging	00:00:00.368	0.0%	24002	0.000	0.015	0.060
article parse	00:00:01.042	0.0%	66539	0.000	0.016	0.062
article write	00:00:05.877	0.0%	19434	0.191	0.302	0.851
artlog/artcncl	00:00:00.000	0.0%	81	0.000	0.000	0.000
artlog/artparse	00:00:00.010	0.0%	361	0.000	0.028	1.000
data move	00:00:00.179	0.0%	529379	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.002	0.0%	101	0.000	0.020	0.250
hishave/artcncl	00:00:00.001	0.0%	106	0.000	0.009	0.500
hishave/artparse	00:00:00.001	0.0%	361	0.000	0.003	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.245	0.0%	542421	0.001	0.008	0.259
history sync	00:00:00.803	0.0%	361	0.000	2.224	7.000
history write	00:00:09.901	0.0%	23430	0.076	0.423	0.812
hiswrite/artcncl	00:00:00.003	0.0%	5	0.000	0.600	1.500
hiswrite/artparse	00:00:00.185	0.0%	356	0.000	0.520	5.000
idle	23:48:11.956	99.8%	533288	85.512	160.686	465.818
nntp read	00:00:02.705	0.0%	534771	0.002	0.005	0.011
overview write	00:00:53.068	0.1%	19434	0.127	2.731	306.087
perl filter	00:00:46.571	0.1%	23420	1.576	1.989	2.902
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.351	0.0%	38919	0.000	0.009	0.026
TOTAL: 23:50:56.377	23:50:17.539	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.050	0.0%	19586	0.000	0.003	0.038
article prepare	00:00:00.001	0.0%	21224	0.000	0.000	0.005
article read	00:00:00.406	0.0%	2261	0.000	0.180	15.000
backlog stats	00:00:00.206	0.0%	379934	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.992	0.0%	282737	0.002	0.007	0.016
data write	00:00:01.792	0.0%	266864	0.002	0.007	0.013
idle	23:53:32.739	100.0%	379934	122.684	226.389	493.046
status file	00:00:00.155	0.0%	244	0.000	0.635	1.000
TOTAL: 23:53:47.162	23:53:37.341	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	14:03:56.228	99.9%	9235	97.433	5483.078	600161.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.155	0.0%	19075	0.000	0.008	0.210
readart	00:00:04.362	0.0%	550	0.000	7.931	64.000
TOTAL: 14:04:50.196	14:04:00.745	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1442	100126	4191	94368	1567	4%	119:44:04
2	csiph-out.news.weretis.net	6	39452	3998	35061	393	10%	48:34:19
3	feeder.eternal-september.org	1	20482	2374	17529	579	11%	22:07:50
4	newsfeeds.xmission.com	3	18452	2176	16267	9	11%	24:36:54
5	news.redatomik.org	4	18471	1659	16732	80	8%	24:10:17
6	news-out.aioe.org	3	18768	1498	17218	52	7%	24:09:20
7	news-out.mixmin.net	2	20193	1147	18554	492	5%	24:08:37
8	newsfeed.fu-berlin.de	12	16417	977	15248	192	5%	23:06:52
9	newsfeed.neva.ru	138	23099	927	21476	696	4%	72:15:22
10	news.fcku.it	2	8628	384	7999	245	4%	23:59:27
11	news.swapon.de	3	1470	58	1412	0	3%	23:37:15
12	nntp.pasdenom.info	2	17357	40	17312	5	0%	23:59:10
13	news.bbs.nz	2	18102	9	18093	0	0%	24:54:08
14	news.freedyn.net	1	19475	7	19464	4	0%	23:22:18
15	localhost	38	5	5	0	0	100%	00:00:09
16	xanadu-bbs.net	12	711	1	707	3	0%	23:16:35
TOTAL: 16		1671	341208	19451	317440	4317	5%	526:02:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.0 MB	0.0 KB	13.2 MB	33.2 MB	60%	5.9 KB
2	csiph-out.news.weretis.net	14.7 MB	4.3 KB	7.7 MB	22.4 MB	65%	5.2 KB
3	newsfeeds.xmission.com	7.8 MB	0.0 KB	264.3 KB	8.1 MB	96%	3.8 KB
4	feeder.eternal-september.org	6.3 MB	0.0 KB	6.7 MB	13.1 MB	48%	4.5 KB
5	news.redatomik.org	4.5 MB	0.0 KB	343.7 KB	4.8 MB	92%	2.8 KB
6	news-out.mixmin.net	4.3 MB	0.0 KB	1.2 MB	5.5 MB	78%	3.4 KB
7	news-out.aioe.org	3.7 MB	0.0 KB	321.4 KB	4.1 MB	92%	2.7 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	515.2 KB	3.0 MB	82%	2.6 KB
9	newsfeed.neva.ru	2.3 MB	0.0 KB	3.1 MB	5.4 MB	43%	3.4 KB
10	news.fcku.it	1.0 MB	0.0 KB	1.7 MB	2.7 MB	37%	4.4 KB
11	news.swapon.de	157.6 KB	0.0 KB	0.0 KB	157.6 KB	100%	2.7 KB
12	nntp.pasdenom.info	153.7 KB	4.3 KB	13.4 KB	171.4 KB	89%	3.8 KB
13	news.bbs.nz	17.5 KB	0.0 KB	0.0 KB	17.5 KB	100%	1.9 KB
14	news.freedyn.net	12.6 KB	0.0 KB	6.7 KB	19.3 KB	65%	1.8 KB
15	xanadu-bbs.net	9.8 KB	0.0 KB	78.4 KB	88.2 KB	11%	22.1 KB
16	localhost	9.2 KB	0.0 KB	0.0 KB	9.2 KB	100%	1.8 KB
TOTAL: 16		67.5 MB	8.5 KB	35.2 MB	102.6 MB	65%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 22 04:15:00 - 04:59:59	453	2.3%	0.17	2.4 MB	3.6%	0.91
Aug 22 05:00:00 - 05:59:59	634	3.3%	0.18	2.4 MB	3.6%	0.69
Aug 22 06:00:00 - 06:59:59	579	3.0%	0.16	2.1 MB	3.2%	0.61
Aug 22 07:00:00 - 07:59:59	663	3.4%	0.18	2.2 MB	3.2%	0.62
Aug 22 08:00:00 - 08:59:59	709	3.6%	0.20	2.6 MB	3.8%	0.74
Aug 22 09:00:00 - 09:59:59	751	3.9%	0.21	2.6 MB	3.8%	0.73
Aug 22 10:00:00 - 10:59:59	849	4.4%	0.24	2.4 MB	3.6%	0.70
Aug 22 11:00:00 - 11:59:59	934	4.8%	0.26	2.7 MB	3.9%	0.76
Aug 22 12:00:00 - 12:59:59	907	4.7%	0.25	2.8 MB	4.1%	0.78
Aug 22 13:00:00 - 13:59:59	893	4.6%	0.25	2.4 MB	3.5%	0.67
Aug 22 14:00:00 - 14:59:59	1230	6.3%	0.34	5.4 MB	7.9%	1.52
Aug 22 15:00:00 - 15:59:59	1340	6.9%	0.37	6.3 MB	9.4%	1.80
Aug 22 16:00:00 - 16:59:59	1213	6.2%	0.34	4.4 MB	6.5%	1.26
Aug 22 17:00:00 - 17:59:59	1102	5.7%	0.31	3.2 MB	4.8%	0.92
Aug 22 18:00:00 - 18:59:59	1097	5.6%	0.30	3.7 MB	5.4%	1.04
Aug 22 19:00:00 - 19:59:59	921	4.7%	0.26	2.6 MB	3.9%	0.75
Aug 22 20:00:00 - 20:59:59	938	4.8%	0.26	3.1 MB	4.5%	0.87
Aug 22 21:00:00 - 21:59:59	895	4.6%	0.25	3.0 MB	4.4%	0.85
Aug 22 22:00:00 - 22:59:59	682	3.5%	0.19	2.4 MB	3.6%	0.69
Aug 22 23:00:00 - 23:59:59	657	3.4%	0.18	1.9 MB	2.9%	0.55
Aug 23 00:00:00 - 00:59:59	506	2.6%	0.14	1.5 MB	2.2%	0.43
Aug 23 01:00:00 - 01:59:59	541	2.8%	0.15	1.7 MB	2.6%	0.50
Aug 23 02:00:00 - 02:59:59	491	2.5%	0.14	1.8 MB	2.7%	0.51
Aug 23 03:00:00 - 03:59:59	398	2.0%	0.11	1.5 MB	2.2%	0.43
Aug 23 04:00:00 - 04:15:00	120	0.6%	0.13	597.1 KB	0.9%	0.66
TOTAL: 24:00:00	19503	100.0%	0.23	67.8 MB	100.0%	0.80

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	1583	128	0	1455
2	newsfeed.neva.ru	697	82	0	615
3	newsfeed.fu-berlin.de	673	25	0	648
4	feeder.eternal-september.org	608	55	0	553
5	news-out.mixmin.net	515	0	0	515
6	csiph-out.news.weretis.net	406	123	1	282
7	news.fcku.it	246	1	0	245
8	news.redatomik.org	125	0	0	125
9	news-out.aioe.org	61	54	0	7
10	news.swapon.de	12	0	0	12
11	newsfeeds.xmission.com	10	4	0	6
12	nntp.pasdenom.info	5	2	1	2
13	news.freedyn.net	3	0	0	3
14	xanadu-bbs.net	3	0	0	3
TOTAL: 14		4947	474	2	4471

Unwanted newsgroups [Top 20]:

Newsgroup	Count
gmane.comp.gis.openstreetmap	35
gmane.linux.alsa.devel	32
alt.survival	29
lada.talk	28
alt.windows7.general	25
lada.auto	25
alt.politics	20
mozilla.general	19
alt.politics.usa	16
mozilla.dev.l10n.new-locales	13
lada.commerce	12
it-alt.sport.calcio.juventus	11
spline.eisfair	10
mozilla.support.firefox	9
alt.2600	8
mozilla.dev.security.policy	8
lada.kitchen	8
gmane.comp.gis.openstreetmap.tagging	7
mozilla.dev.platform	7
at.jobs	7
TOTAL: 95	474

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2790
EMP (phn path)	404
EMP (phl)	74
Too many newsgroups	64
HTML Multipart	43
User-issued cancel	41
Scoring filter (9)	25
Binary Payload	19
Scoring filter (15)	13
Scoring filter (10)	13
Scoring filter (14)	7
Scoring filter (12)	6
EMP (phn nph)	6
Scoring filter (8)	5
Scoring filter (11)	4
Scoring filter (13)	4
Scoring filter (17)	3
Scoring filter (16)	3
Binary: misplaced rar	2
User-issued spam cancel	2
TOTAL: 21	3529

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	44	0	44	44
TOTAL: 1	44	9	44	44

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	10
	TOTAL: 1	10
Bad Message-ID's offered
	csiph-out.feeder.erje.net	70
	TOTAL: 1	70
Huge articles
	csiph-out.feeder.erje.net	184
	csiph-out.news.weretis.net	83
	feeder.eternal-september.org	76
	news.fcku.it	10
	newsfeeds.xmission.com	4
	news-out.mixmin.net	2
	xanadu-bbs.net	1
	newsfeed.fu-berlin.de	1
	news.redatomik.org	1
	TOTAL: 9	362
Including strange strings
	newsfeed.fu-berlin.de	466
	news.redatomik.org	45
	csiph-out.feeder.erje.net	15
	news.swapon.de	12
	news-out.mixmin.net	11
	csiph-out.news.weretis.net	8
	news-out.aioe.org	7
	feeder.eternal-september.org	3
	newsfeed.neva.ru	2
	newsfeeds.xmission.com	1
	TOTAL: 10	570
TOTAL: 4		1012

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	21457	1131	18360	7	0	5%	23:59:53
2	xanadu-bbs	22328	990	18511	0	30	4%	23:59:53
3	fu-berlin.de	18248	851	17397	0	0	4%	23:59:50
4	usenet.pasdenom.info	21480	200	17634	1631	7	0%	23:59:46
5	news.bbs.nz	35787	186	18430	7	0	0%	23:59:52
6	aioe	17149	34	15991	0	0	0%	23:59:53
7	erje	17367	30	15167	11	0	0%	23:59:53
8	eternal-september	17700	19	15984	0	0	0%	23:58:52
9	news.mixmin.net	17741	18	17015	0	95	0%	23:59:53
10	weretis.net	14560	16	13995	7	0	0%	23:59:53
11	neva.ru	17859	15	17287	202	0	0%	23:59:53
12	xmission	20259	12	16391	1	0	0%	23:59:53
13	news.swapon.de	1669	12	1529	0	0	0%	23:51:54
14	news.redatomik.org	18289	7	16977	2	7	0%	23:59:53
15	news.fcku.it	1534	3	1484	0	0	0%	22:47:11
TOTAL: 15		263427	3524	222152	1868	139	1%	358:36:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	11.3 MB	62.4 KB	11.4 MB	0.1 KB/s	10.2 KB	23:59:53
2	xanadu-bbs	11.0 MB	0.0 KB	11.0 MB	0.1 KB/s	11.3 KB	23:59:53
3	fu-berlin.de	10.3 MB	0.0 KB	10.3 MB	0.1 KB/s	12.4 KB	23:59:50
4	usenet.pasdenom.info	742.7 KB	11.5 MB	12.3 MB	0.1 KB/s	6.9 KB	23:59:46
5	news.bbs.nz	628.0 KB	16.8 KB	644.7 KB	0.0 KB/s	3.3 KB	23:59:52
6	eternal-september	209.4 KB	0.0 KB	209.4 KB	0.0 KB/s	11.0 KB	23:58:52
7	erje	191.5 KB	34.8 KB	226.3 KB	0.0 KB/s	5.5 KB	23:59:53
8	aioe	180.6 KB	0.0 KB	180.6 KB	0.0 KB/s	5.3 KB	23:59:53
9	neva.ru	119.6 KB	1.1 MB	1.2 MB	0.0 KB/s	5.5 KB	23:59:53
10	news.swapon.de	111.7 KB	0.0 KB	111.7 KB	0.0 KB/s	9.3 KB	23:51:54
11	weretis.net	76.5 KB	23.5 KB	99.9 KB	0.0 KB/s	4.3 KB	23:59:53
12	news.mixmin.net	69.0 KB	0.0 KB	69.0 KB	0.0 KB/s	3.8 KB	23:59:53
13	xmission	66.4 KB	1.3 KB	67.7 KB	0.0 KB/s	5.2 KB	23:59:53
14	news.redatomik.org	13.1 KB	44.2 KB	57.3 KB	0.0 KB/s	6.4 KB	23:59:53
15	news.fcku.it	8.7 KB	0.0 KB	8.7 KB	0.0 KB/s	2.9 KB	22:47:11
TOTAL: 15		35.0 MB	12.8 MB	47.7 MB	0.0 KB/s	9.1 KB	358:36:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	46	145	491.6 KB	75	0	0	01:15:10
2	47.41.144.29	13	7	12.5 KB	5	4	3	03:03:09
3	208-71-239-109.cust.centrio.cz	9	3	9.2 KB	3	1	0	02:30:50
4	24.178.44.97	1	2	4.6 KB	1	0	0	00:22:48
5	ip72-222-200-117.ph.ph.cox.net	16	2	3.6 KB	2	0	0	06:06:06
6	mtrlpq2314w-lp130-05-70-55-68-33.dsl.bell.ca	200	1	2.4 KB	1	0	0	00:46:40
TOTAL: 6		285	160	523.8 KB	87	5	3	14:04:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	61	154	508.7 KB	81	4	3	04:41:09
2	*.cust.centrio.cz	9	3	9.2 KB	3	1	0	02:30:50
3	*.ph.ph.cox.net	16	2	3.6 KB	2	0	0	06:06:06
4	*.dsl.bell.ca	200	1	2.4 KB	1	0	0	00:46:40
TOTAL: 4		286	160	523.8 KB	87	5	3	14:04:47

NNRP auth users [Top 20]:

User	Conn
moussa	46
ctomczyk	9
ohlandl	3
TOTAL: 3	58

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ip72-222-200-117.ph.ph.cox.net	1.093	0.230	0.000	06:06:06
47.41.144.29	1.296	0.295	0.009	03:03:09
208-71-239-109.cust.centrio.cz	0.950	0.126	0.028	02:30:50
141.168.117.108	5.460	1.037	0.155	01:15:10
mtrlpq2314w-lp130-05-70-55-68-33.dsl.bell.ca	14.672	1.994	0.000	00:46:40
24.178.44.97	0.147	0.016	0.000	00:22:48
94.163.237.167	0.057	0.008	0.000	00:00:01
TOTAL: 7	23.675	3.706	0.192	14:04:47

Curious NNRP server explorers [Top 20]:

System	Conn
94.163.237.167	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	9	0
47.41.144.29	4	0
208-71-239-109.cust.centrio.cz	2	0
141.168.117.108	1	0
TOTAL: 4	16	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	113	70.6%
2	alt	46	28.7%
3	perl	1	0.6%
TOTAL: 3		160	100.0%

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

Newsgroup		Count
1	alt.comp.freeware	46
2	comp.ai.philosophy	40
3	comp.os.vms	30
4	comp.sys.ibm.ps2.hardware	17
5	comp.arch	11
6	comp.sys.ibm.pc.games.action	4
7	comp.os.os2.misc	3
8	comp.lang.javascript	3
9	comp.os.cpm	2
10	comp.arch.fpga	2
11	perl.cpan.uploads	1
12	comp.sys.ibm.pc.games.rpg	1
TOTAL: 12		160

Daily Usenet report for csiph.com

Aug 22 04:15:00 -- Aug 23 04:15:00