Daily Usenet report for csiph.com: Dec 20 04:15:02

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	31076	57.0%	6.5 MB	70.4%
innd	14782	27.1%	1.7 MB	19.0%
nnrpd	5440	10.0%	465.9 KB	4.9%
innfeed	3185	5.8%	527.2 KB	5.6%
nocem	67	0.1%	7.5 KB	0.1%
TOTAL: 5	54550	100.0%	9.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	328129	54.3%
Negative hits	246106	40.7%
Do not exist	30052	5.0%
Cache misses	434	0.1%
TOTAL: 4	604721	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.042	0.0%	219	0.000	4.758	53.615
article cleanup	00:00:00.212	0.0%	29854	0.000	0.007	0.045
article logging	00:00:01.080	0.0%	30469	0.000	0.035	3.640
article parse	00:00:01.559	0.0%	77601	0.000	0.020	0.618
article write	00:01:02.260	0.1%	20369	0.202	3.057	68.753
artlog/artcncl	00:00:00.072	0.0%	207	0.000	0.348	5.231
artlog/artparse	00:00:00.020	0.0%	319	0.000	0.063	3.200
data move	00:00:00.184	0.0%	579582	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.021	0.0%	218	0.000	0.096	1.500
hishave/artcncl	00:00:00.009	0.0%	219	0.000	0.041	0.308
hishave/artparse	00:00:00.001	0.0%	319	0.000	0.003	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:14.794	0.0%	606864	0.005	0.024	0.296
history sync	00:00:01.939	0.0%	352	0.000	5.509	187.000
history write	00:00:43.746	0.1%	29842	0.258	1.466	26.128
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.579	0.0%	319	0.000	1.815	32.600
idle	23:42:25.755	99.2%	583910	79.684	146.163	433.822
nntp read	00:00:02.771	0.0%	590776	0.002	0.005	0.010
overview write	00:07:17.808	0.5%	20369	0.320	21.494	3149.812
perl filter	00:00:51.132	0.1%	29604	1.178	1.727	2.534
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.386	0.0%	40795	0.000	0.009	0.036
TOTAL: 23:53:35.861	23:52:45.370	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.051	0.0%	20379	0.000	0.003	0.025
article prepare	00:00:00.001	0.0%	17792	0.000	0.000	0.023
article read	00:00:01.313	0.0%	2533	0.000	0.518	9.833
backlog stats	00:00:00.216	0.0%	372602	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.916	0.0%	290295	0.002	0.007	0.014
data write	00:00:01.578	0.0%	273038	0.001	0.006	0.011
idle	23:53:35.112	100.0%	372602	127.108	230.850	633.419
status file	00:00:00.723	0.0%	307	0.000	2.355	58.000
TOTAL: 23:53:49.167	23:53:40.910	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	13:01:19.834	99.9%	5367	376.641	8734.830	600113.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.057	0.0%	11151	0.000	0.005	0.167
readart	00:00:00.676	0.0%	90	0.000	7.511	89.667
TOTAL: 13:01:49.297	13:01:20.567	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	42946	6730	35571	645	15%	48:54:39
2	csiph-out.feeder.erje.net	1152	91811	4146	84253	3412	4%	94:58:22
3	feeder.eternal-september.org	6	25053	2585	21400	1068	10%	24:51:12
4	news-out.aioe.org	3	20518	1544	18912	62	7%	24:09:01
5	newsfeed.neva.ru	141	28077	1349	23345	3383	4%	71:45:43
6	newsfeed.fu-berlin.de	8	19991	1122	18518	351	5%	24:37:41
7	news.redatomik.org	1	23600	856	22738	6	3%	28:47:21
8	news-out.mixmin.net	3	21626	779	20427	420	3%	24:02:45
9	news.fcku.it	2	10932	726	9806	400	6%	30:33:21
10	newsfeeds.xmission.com	2	17213	465	16747	1	2%	20:58:52
11	news.freedyn.net	2	22109	206	21829	74	0%	24:07:49
12	news.swapon.de	3	1768	54	1714	0	3%	23:05:05
13	nntp.pasdenom.info	2	19247	19	19220	8	0%	23:59:56
14	news.bbs.geek.nz	2	18966	7	18958	1	0%	23:59:45
15	xanadu-bbs.net	23	630	3	625	2	0%	20:33:23
16	localhost	34	0	0	0	0	0%	00:00:00
TOTAL: 16		1389	364487	20591	334063	9833	5%	509:24:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	19.3 MB	9.1 KB	11.3 MB	30.6 MB	62%	4.2 KB
2	csiph-out.feeder.erje.net	17.2 MB	11.0 KB	15.5 MB	32.6 MB	52%	4.4 KB
3	feeder.eternal-september.org	6.4 MB	2.1 KB	4.1 MB	10.5 MB	60%	2.9 KB
4	news-out.aioe.org	3.5 MB	10.5 KB	110.3 KB	3.6 MB	96%	2.3 KB
5	newsfeed.neva.ru	3.0 MB	5.2 KB	20.4 MB	23.4 MB	12%	5.1 KB
6	news-out.mixmin.net	2.9 MB	0.0 KB	1.0 MB	3.9 MB	73%	3.3 KB
7	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	845.8 KB	3.5 MB	76%	2.5 KB
8	news.redatomik.org	1.9 MB	0.0 KB	95.9 KB	2.0 MB	95%	2.3 KB
9	news.fcku.it	1.7 MB	26.8 KB	1.8 MB	3.5 MB	48%	3.1 KB
10	newsfeeds.xmission.com	1.4 MB	1.8 KB	1.8 KB	1.4 MB	99%	3.0 KB
11	news.freedyn.net	901.8 KB	0.0 KB	395.0 KB	1.3 MB	69%	4.6 KB
12	news.swapon.de	124.1 KB	0.0 KB	0.0 KB	124.1 KB	100%	2.3 KB
13	nntp.pasdenom.info	39.9 KB	0.0 KB	19.4 KB	59.2 KB	67%	2.2 KB
14	news.bbs.geek.nz	14.9 KB	3.4 KB	3.4 KB	21.6 KB	68%	2.7 KB
15	xanadu-bbs.net	5.1 KB	0.0 KB	5.5 KB	10.6 KB	48%	2.1 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		60.9 MB	69.9 KB	55.5 MB	116.4 MB	52%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 20 04:15:02 - 04:59:59	294	1.4%	0.11	1022.7 KB	1.7%	0.38
Dec 20 05:00:00 - 05:59:59	531	2.6%	0.15	2.9 MB	4.8%	0.82
Dec 20 06:00:00 - 06:59:59	493	2.4%	0.14	1.8 MB	3.0%	0.51
Dec 20 07:00:00 - 07:59:59	626	3.1%	0.17	2.3 MB	3.9%	0.67
Dec 20 08:00:00 - 08:59:59	805	3.9%	0.22	2.2 MB	3.7%	0.64
Dec 20 09:00:00 - 09:59:59	879	4.3%	0.24	2.3 MB	3.8%	0.65
Dec 20 10:00:00 - 10:59:59	765	3.7%	0.21	1.9 MB	3.1%	0.53
Dec 20 11:00:00 - 11:59:59	886	4.3%	0.25	2.5 MB	4.1%	0.71
Dec 20 12:00:00 - 12:59:59	1084	5.3%	0.30	2.4 MB	4.0%	0.69
Dec 20 13:00:00 - 13:59:59	1037	5.1%	0.29	2.7 MB	4.4%	0.76
Dec 20 14:00:00 - 14:59:59	1097	5.4%	0.30	2.8 MB	4.6%	0.79
Dec 20 15:00:00 - 15:59:59	1155	5.7%	0.32	3.8 MB	6.3%	1.08
Dec 20 16:00:00 - 16:59:59	1186	5.8%	0.33	3.8 MB	6.2%	1.07
Dec 20 17:00:00 - 17:59:59	1267	6.2%	0.35	3.9 MB	6.5%	1.12
Dec 20 18:00:00 - 18:59:59	1266	6.2%	0.35	3.4 MB	5.6%	0.96
Dec 20 19:00:00 - 19:59:59	1063	5.2%	0.30	3.0 MB	5.0%	0.87
Dec 20 20:00:00 - 20:59:59	922	4.5%	0.26	2.5 MB	4.2%	0.72
Dec 20 21:00:00 - 21:59:59	957	4.7%	0.27	2.8 MB	4.6%	0.79
Dec 20 22:00:00 - 22:59:59	986	4.8%	0.27	2.6 MB	4.4%	0.75
Dec 20 23:00:00 - 23:59:59	911	4.5%	0.25	2.8 MB	4.7%	0.81
Dec 21 00:00:00 - 00:59:59	697	3.4%	0.19	2.2 MB	3.6%	0.61
Dec 21 01:00:00 - 01:59:59	569	2.8%	0.16	1.6 MB	2.6%	0.45
Dec 21 02:00:00 - 02:59:59	435	2.1%	0.12	1.3 MB	2.1%	0.37
Dec 21 03:00:00 - 03:59:59	401	2.0%	0.11	1.5 MB	2.5%	0.43
Dec 21 04:00:00 - 04:15:00	106	0.5%	0.12	348.4 KB	0.6%	0.39
TOTAL: 23:59:58	20418	100.0%	0.24	60.4 MB	100.0%	0.72

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	3441	200	1	3240
2	newsfeed.neva.ru	3383	294	2	3087
3	feeder.eternal-september.org	1083	25	1	1057
4	newsfeed.fu-berlin.de	823	47	0	776
5	csiph-out.news.weretis.net	715	281	3	431
6	news-out.mixmin.net	432	2	0	430
7	news.fcku.it	334	1	0	333
8	news.freedyn.net	109	16	0	93
9	news-out.aioe.org	67	28	2	37
10	news.redatomik.org	24	0	0	24
11	news.swapon.de	12	0	0	12
12	nntp.pasdenom.info	8	7	0	1
13	news.bbs.geek.nz	3	0	2	1
14	newsfeeds.xmission.com	2	0	1	1
15	xanadu-bbs.net	2	0	0	2
TOTAL: 15		10438	901	12	9525

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	123
lada.auto	81
mpc.lists.nanog	54
it.annunci.immobiliari	41
it.annunci.varie	39
it.annunci.commerciali	39
lada.photo	36
spline.eisfair	31
it-alt.sport.calcio.juventus	29
gmane.linux.alsa.devel	28
mpc.lists.freebsd.ports.bugs	25
alt.survival	25
mozilla.general	23
abg.test	19
alt.windows7.general	14
mpc.lists.freebsd.x11	13
alt.english.usage	12
free.it.religioni.scientology	11
it-alt.comp.software.mozilla	10
mozilla.support.firefox	10
TOTAL: 115	901

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6959
EMP (phn path)	551
Binary: misplaced rar	377
EMP (phn nph)	203
Too many newsgroups	55
Binary Payload	33
Scoring filter (10)	33
HTML Multipart	28
EMP (phl)	24
Too many newsgroups (low_xpost)	19
User-issued cancel	16
Scoring filter (8)	12
HTML Image Tags	6
HTML post	6
Binary Image: misplaced jpg	5
Scoring filter (17)	5
Scoring filter (12)	5
Scoring filter (11)	2
Scoring filter (13)	2
Scoring filter (15)	2
TOTAL: 24	8347

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	216
	TOTAL: 1	216
Huge articles
	csiph-out.feeder.erje.net	169
	csiph-out.news.weretis.net	126
	feeder.eternal-september.org	10
	news.fcku.it	5
	news-out.mixmin.net	4
	newsfeed.fu-berlin.de	3
	news.freedyn.net	2
	newsfeeds.xmission.com	1
	TOTAL: 8	320
Including strange strings
	newsfeed.fu-berlin.de	463
	csiph-out.news.weretis.net	67
	csiph-out.feeder.erje.net	29
	news.redatomik.org	20
	news-out.mixmin.net	14
	news.swapon.de	12
	news-out.aioe.org	10
	news.fcku.it	3
	news.bbs.geek.nz	1
	feeder.eternal-september.org	1
	TOTAL: 10	620
TOTAL: 3		1156

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	37256	1223	18544	43	0	3%	23:50:12
2	news.freedyn.net	20633	627	19477	0	0	3%	23:52:34
3	xanadu-bbs	22764	547	19868	0	0	2%	23:59:51
4	fu-berlin.de	19053	502	18551	0	0	2%	23:59:47
5	usenet.pasdenom.info	21237	90	19112	1193	2	0%	23:59:53
6	aioe	17757	45	17294	1	0	0%	23:57:56
7	news.redatomik.org	19624	24	19008	0	0	0%	23:57:37
8	erje	16670	23	16099	6	0	0%	23:56:25
9	news.swapon.de	1880	6	1859	0	0	0%	23:55:30
10	neva.ru	18566	4	18188	114	0	0%	23:59:53
11	eternal-september	17718	3	17086	0	0	0%	23:59:39
12	news.mixmin.net	18956	1	18663	0	0	0%	23:51:13
13	xmission	21374	1	19349	0	2	0%	23:59:30
14	weretis.net	13547	1	12733	1	0	0%	23:51:13
15	news.fcku.it	1884	0	1880	0	0	0%	22:09:18
16	bwh	0	0	0	0	19820	0%	23:50:13
17	au2pb	0	0	0	0	19823	0%	23:50:14
TOTAL: 17		268919	3097	237711	1358	39647	1%	405:00:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.3 MB	0.0 KB	7.3 MB	0.1 KB/s	11.9 KB	23:52:34
2	xanadu-bbs	7.0 MB	0.0 KB	7.0 MB	0.1 KB/s	13.1 KB	23:59:51
3	fu-berlin.de	6.9 MB	0.0 KB	6.9 MB	0.1 KB/s	14.0 KB	23:59:47
4	news.bbs.geek.nz	3.3 MB	140.7 KB	3.4 MB	0.0 KB/s	2.8 KB	23:50:12
5	usenet.pasdenom.info	315.0 KB	8.0 MB	8.3 MB	0.1 KB/s	6.7 KB	23:59:53
6	aioe	123.8 KB	0.6 KB	124.4 KB	0.0 KB/s	2.7 KB	23:57:56
7	news.redatomik.org	91.7 KB	0.0 KB	91.7 KB	0.0 KB/s	3.8 KB	23:57:37
8	erje	67.0 KB	11.1 KB	78.2 KB	0.0 KB/s	2.7 KB	23:56:25
9	news.swapon.de	10.4 KB	0.0 KB	10.4 KB	0.0 KB/s	1.7 KB	23:55:30
10	neva.ru	8.4 KB	287.5 KB	295.9 KB	0.0 KB/s	2.5 KB	23:59:53
11	eternal-september	8.0 KB	0.0 KB	8.0 KB	0.0 KB/s	2.7 KB	23:59:39
12	xmission	3.0 KB	0.0 KB	3.0 KB	0.0 KB/s	3.0 KB	23:59:30
13	weretis.net	3.0 KB	1.3 KB	4.3 KB	0.0 KB/s	2.1 KB	23:51:13
14	news.mixmin.net	2.7 KB	0.0 KB	2.7 KB	0.0 KB/s	2.7 KB	23:51:13
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:09:18
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:13
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:14
TOTAL: 17		25.1 MB	8.5 MB	33.5 MB	0.0 KB/s	7.7 KB	405:00:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	141.168.117.108	46	82	213.5 KB	51	01:12:41
2	194-73-239-109.cust.centrio.cz	2	6	11.6 KB	2	01:00:06
3	challenger.nielsenmedia.com	4	2	3.9 KB	1	01:34:24
TOTAL: 3		52	90	229.0 KB	54	13:01:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	46	82	213.5 KB	51	01:12:41
2	*.cust.centrio.cz	2	6	11.6 KB	2	01:00:06
3	*.nielsenmedia.com	4	2	3.9 KB	1	01:34:24
4	*.upc-a.chello.nl	32	0	0.0 KB	0	05:12:15
5	*.static.chello.nl	2	0	0.0 KB	0	00:00:46
6	*.bbox.io	4	0	0.0 KB	0	02:00:10
7	*.dhcp.mdsn.wi.charter.com	7	0	0.0 KB	0	02:01:23
TOTAL: 7		97	90	229.0 KB	54	13:01:48

NNRP auth users [Top 20]:

User	Conn
moussa	45
ctomczyk	2
TOTAL: 2	47

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.258	0.426	0.000	05:12:15
96-42-18-226.dhcp.mdsn.wi.charter.com	0.406	0.115	0.000	02:01:23
tempe0.bbox.io	0.492	0.080	0.000	02:00:10
challenger.nielsenmedia.com	0.433	0.067	0.000	01:34:24
141.168.117.108	4.706	1.127	0.213	01:12:41
194-73-239-109.cust.centrio.cz	0.281	0.047	0.008	01:00:06
095-096-102-220.static.chello.nl	0.176	0.032	0.000	00:00:46
TOTAL: 7	8.752	1.894	0.221	13:01:48

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	32
96-42-18-226.dhcp.mdsn.wi.charter.com	7
tempe0.bbox.io	4
095-096-102-220.static.chello.nl	2
TOTAL: 4	45

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	4	0
96-42-18-226.dhcp.mdsn.wi.charter.com	4	0
challenger.nielsenmedia.com	3	0
194-73-239-109.cust.centrio.cz	2	0
141.168.117.108	1	0
TOTAL: 5	14	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	78	86.7%
2	alt	12	13.3%
TOTAL: 2		90	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.pc.games.action	21
2	comp.os.vms	15
3	alt.comp.freeware	12
4	comp.arch	12
5	comp.os.cpm	8
6	comp.ai.philosophy	7
7	comp.lang.javascript	6
8	comp.sys.ibm.pc.hardware.storage	3
9	comp.lang.awk	2
10	comp.os.os2.announce	1
11	comp.ai.doc-analysis.misc	1
12	comp.arch.fpga	1
13	comp.windows.x	1
TOTAL: 13		90

Daily Usenet report for csiph.com

Dec 20 04:15:02 -- Dec 21 04:15:00