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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	42710	58.0%	7.5 MB	68.7%
innd	26467	36.0%	2.8 MB	25.4%
innfeed	3139	4.3%	520.0 KB	4.7%
nnrpd	1212	1.6%	131.8 KB	1.2%
nocem	55	0.1%	5.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	73584	100.0%	10.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	399676	51.7%
Negative hits	330439	42.8%
Do not exist	41642	5.4%
Cache misses	749	0.1%
TOTAL: 4	772506	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.838	0.0%	196	0.000	4.276	145.000
article cleanup	00:00:00.298	0.0%	41873	0.000	0.007	0.029
article logging	00:00:00.967	0.0%	42398	0.000	0.023	2.651
article parse	00:00:01.510	0.0%	160318	0.000	0.009	0.020
article write	00:05:08.693	0.4%	20604	0.290	14.982	114.528
artlog/artcncl	00:00:00.002	0.0%	173	0.000	0.012	0.333
artlog/artparse	00:00:00.004	0.0%	259	0.000	0.015	1.000
data move	00:00:00.297	0.0%	682551	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.045	0.0%	194	0.000	0.232	25.000
hishave/artcncl	00:00:00.001	0.0%	196	0.000	0.005	0.056
hishave/artparse	00:00:00.000	0.0%	257	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:07.462	0.0%	774482	0.001	0.010	0.197
history sync	00:00:01.356	0.0%	360	0.000	3.767	57.667
history write	00:00:37.241	0.0%	41463	0.091	0.898	14.304
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.119	0.0%	257	0.000	0.463	6.000
idle	23:49:47.570	99.2%	706087	69.250	121.497	469.724
nntp read	00:00:02.515	0.0%	720279	0.001	0.003	0.006
overview write	00:02:09.350	0.1%	20604	0.503	6.278	128.717
perl filter	00:02:03.994	0.1%	41435	1.548	2.992	5.362
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.412	0.0%	41256	0.000	0.010	0.036
TOTAL: 24:00:52.185	24:00:02.674	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.059	0.0%	20592	0.000	0.003	0.017
article prepare	00:00:00.002	0.0%	33036	0.000	0.000	0.005
article read	00:00:02.571	0.0%	5065	0.000	0.508	12.733
backlog stats	00:00:00.236	0.0%	382790	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.644	0.0%	294996	0.001	0.006	0.010
data write	00:00:02.119	0.0%	276891	0.002	0.008	0.014
idle	23:54:17.418	100.0%	382790	123.204	224.816	678.495
status file	00:00:00.446	0.0%	258	0.000	1.729	57.000
TOTAL: 23:54:33.622	23:54:24.495	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.012	0.0%	95	0.000	0.126	10.000
idle	62:58:00.997	99.8%	550	154.480	412147.267	900158.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.227	0.0%	1174	0.000	0.193	3.593
readart	00:00:33.015	0.0%	1970	0.000	16.759	24.518
TOTAL: 63:07:05.588	62:58:34.251	99.8%	-	-	-	-

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	1153	124194	5877	110090	8227	4%	95:03:20
2	csiph-out.news.weretis.net	3	45507	5286	38718	1503	11%	48:01:23
3	feeder.eternal-september.org	1	36054	2751	31398	1905	7%	23:06:02
4	news-out.aioe.org	3	19687	1278	18356	53	6%	24:09:39
5	newsfeed.neva.ru	150	42642	1154	32698	8790	2%	71:06:48
6	newsfeed.fu-berlin.de	16	21247	1139	19932	176	5%	27:56:36
7	news.redatomik.org	1	20721	1139	19557	25	5%	26:42:43
8	newsfeeds.xmission.com	1	18182	700	17457	25	3%	21:13:45
9	news-out.mixmin.net	2	23128	648	22211	269	2%	24:08:53
10	xanadu-bbs.net	17	481	120	353	8	24%	18:55:14
11	news.freedyn.net	4	23868	118	23685	65	0%	24:10:42
12	news.fcku.it	2	8721	109	8520	92	1%	23:59:32
13	nntp.pasdenom.info	1	38248	54	38149	45	0%	23:59:55
14	news.bbs.geek.nz	1	19751	14	19737	0	0%	23:59:52
15	localhost	34	0	0	0	0	0%	00:00:00
TOTAL: 15		1389	442431	20387	400861	21183	4%	476:34:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	18.0 MB	3.1 MB	41.5 MB	62.7 MB	28%	4.5 KB
2	csiph-out.news.weretis.net	14.7 MB	13.4 KB	18.3 MB	33.1 MB	44%	5.0 KB
3	feeder.eternal-september.org	7.5 MB	128.0 KB	27.4 MB	35.0 MB	21%	7.7 KB
4	newsfeed.neva.ru	3.4 MB	1.8 MB	94.7 MB	99.9 MB	3%	10.3 KB
5	news-out.aioe.org	3.0 MB	0.0 KB	70.9 KB	3.1 MB	97%	2.3 KB
6	news-out.mixmin.net	2.7 MB	0.0 KB	1.3 MB	4.0 MB	67%	4.4 KB
7	news.redatomik.org	2.7 MB	0.0 KB	655.2 KB	3.3 MB	80%	2.9 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	399.3 KB	2.9 MB	86%	2.3 KB
9	newsfeeds.xmission.com	2.2 MB	0.0 KB	1.8 MB	4.0 MB	55%	5.7 KB
10	news.freedyn.net	532.5 KB	0.0 KB	1.4 MB	1.9 MB	27%	10.5 KB
11	xanadu-bbs.net	312.2 KB	0.0 KB	15.2 KB	327.3 KB	95%	2.6 KB
12	news.fcku.it	240.4 KB	0.0 KB	5.0 MB	5.2 MB	4%	26.7 KB
13	nntp.pasdenom.info	98.5 KB	0.0 KB	301.4 KB	399.9 KB	24%	4.0 KB
14	news.bbs.geek.nz	17.8 KB	0.0 KB	0.0 KB	17.8 KB	100%	1.3 KB
15	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 15		57.9 MB	5.0 MB	192.9 MB	255.8 MB	22%	6.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 22 04:15:00 - 04:59:59	272	1.3%	0.10	763.2 KB	1.3%	0.28
Oct 22 05:00:00 - 05:59:59	462	2.2%	0.13	1.3 MB	2.3%	0.38
Oct 22 06:00:00 - 06:59:59	556	2.7%	0.15	2.1 MB	3.5%	0.59
Oct 22 07:00:00 - 07:59:59	919	4.5%	0.26	2.7 MB	4.6%	0.76
Oct 22 08:00:00 - 08:59:59	983	4.8%	0.27	2.8 MB	4.7%	0.79
Oct 22 09:00:00 - 09:59:59	892	4.3%	0.25	2.5 MB	4.2%	0.71
Oct 22 10:00:00 - 10:59:59	897	4.4%	0.25	2.8 MB	4.7%	0.78
Oct 22 11:00:00 - 11:59:59	950	4.6%	0.26	3.0 MB	5.2%	0.86
Oct 22 12:00:00 - 12:59:59	1101	5.3%	0.31	2.7 MB	4.7%	0.78
Oct 22 13:00:00 - 13:59:59	986	4.8%	0.27	2.7 MB	4.6%	0.77
Oct 22 14:00:00 - 14:59:59	1084	5.3%	0.30	3.1 MB	5.3%	0.87
Oct 22 15:00:00 - 15:59:59	1142	5.5%	0.32	3.4 MB	5.8%	0.97
Oct 22 16:00:00 - 16:59:59	1134	5.5%	0.32	3.0 MB	5.1%	0.84
Oct 22 17:00:00 - 17:59:59	1339	6.5%	0.37	3.5 MB	6.0%	0.99
Oct 22 18:00:00 - 18:59:59	1151	5.6%	0.32	3.2 MB	5.4%	0.90
Oct 22 19:00:00 - 19:59:59	1028	5.0%	0.29	2.7 MB	4.6%	0.77
Oct 22 20:00:00 - 20:59:59	1050	5.1%	0.29	2.6 MB	4.5%	0.74
Oct 22 21:00:00 - 21:59:59	948	4.6%	0.26	2.7 MB	4.6%	0.76
Oct 22 22:00:00 - 22:59:59	790	3.8%	0.22	2.3 MB	3.9%	0.64
Oct 22 23:00:00 - 23:59:59	786	3.8%	0.22	2.2 MB	3.8%	0.64
Oct 23 00:00:00 - 00:59:59	584	2.8%	0.16	1.9 MB	3.3%	0.55
Oct 23 01:00:00 - 01:59:59	525	2.5%	0.15	1.5 MB	2.6%	0.43
Oct 23 02:00:00 - 02:59:59	497	2.4%	0.14	1.7 MB	2.9%	0.49
Oct 23 03:00:00 - 03:59:59	405	2.0%	0.11	1.1 MB	1.8%	0.30
Oct 23 04:00:00 - 04:15:00	111	0.5%	0.12	298.6 KB	0.5%	0.33
TOTAL: 24:00:00	20592	100.0%	0.24	58.4 MB	100.0%	0.69

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	9007	122	140	8745
2	csiph-out.feeder.erje.net	8257	290	249	7718
3	feeder.eternal-september.org	1982	49	11	1922
4	csiph-out.news.weretis.net	1553	109	1	1443
5	newsfeed.fu-berlin.de	568	29	0	539
6	news-out.mixmin.net	240	0	0	240
7	news.fcku.it	88	0	0	88
8	news.freedyn.net	63	0	1	62
9	news-out.aioe.org	55	52	0	3
10	nntp.pasdenom.info	44	2	0	42
11	news.redatomik.org	36	0	0	36
12	newsfeeds.xmission.com	31	2	0	29
13	news.swapon.de	14	0	0	14
14	xanadu-bbs.net	8	0	0	8
TOTAL: 14		21946	655	402	20889

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	119
it-alt.sport.calcio.juventus	114
mpc.lists.nanog	53
lada.talk	36
mozilla.support.firefox	32
alt.survival	21
alt.windows7.general	19
mozilla.support.thunderbird	16
spline.fli4l.geschnatter	12
mozilla.dev.security.policy	11
mpc.lists.freebsd.wireless	10
spline.fli4l	9
alt.politics	7
lada.auto	7
mozilla.dev.platform	7
gmane.comp.gis.openstreetmap	6
alt.magick.folk	5
alt.religion	5
oesterreich.tratsch	5
alt.computer.workshop	5
TOTAL: 105	655

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16253
EMP (phn path)	2772
EMP (phl)	682
EMP (phn nph)	147
Binary Payload	55
Too many newsgroups	37
User-issued cancel	33
Scoring filter (19)	16
Scoring filter (17)	15
Scoring filter (18)	15
HTML Multipart	13
Scoring filter (10)	9
Scoring filter (8)	8
Scoring filter (12)	7
Scoring filter (20)	4
HTML post	4
Scoring filter (21)	3
Scoring filter (9)	2
User-issued spam cancel	2
Scoring filter (13)	1
TOTAL: 24	20082

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	48	0	50	50
TOTAL: 1	48	0	50	50

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	26
	TOTAL: 1	26
Huge articles
	feeder.eternal-september.org	91
	news.fcku.it	61
	csiph-out.news.weretis.net	40
	newsfeeds.xmission.com	28
	news-out.mixmin.net	13
	news.redatomik.org	8
	news.swapon.de	7
	news.freedyn.net	6
	nntp.pasdenom.info	3
	TOTAL: 9	257
Including strange strings
	newsfeed.fu-berlin.de	390
	csiph-out.feeder.erje.net	41
	csiph-out.news.weretis.net	27
	feeder.eternal-september.org	18
	newsfeed.neva.ru	18
	news.redatomik.org	14
	news.swapon.de	7
	news-out.aioe.org	3
	news-out.mixmin.net	2
	news.fcku.it	1
	TOTAL: 11	522
No colon-space in header
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
TOTAL: 4		807

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	34999	3648	16030	66	0	10%	23:50:02
2	news.freedyn.net	21189	1123	19281	3	0	5%	23:51:20
3	xanadu-bbs	21385	767	19640	3	19	3%	23:56:12
4	fu-berlin.de	19133	590	18542	0	0	3%	23:50:02
5	erje	15529	407	14156	54	10	2%	23:55:26
6	usenet.pasdenom.info	22260	320	19087	1118	0	1%	23:57:34
7	news.redatomik.org	19591	51	18579	5	5	0%	23:50:38
8	xmission	22075	27	19046	0	0	0%	23:50:02
9	eternal-september	18467	22	16643	0	0	0%	23:50:02
10	weretis.net	13742	14	13576	0	0	0%	23:50:02
11	aioe	17893	7	17230	5	0	0%	23:50:02
12	news.fcku.it	2147	3	2065	0	0	0%	23:13:58
13	neva.ru	19244	1	18474	16	0	0%	23:56:49
14	bwh	0	0	0	0	19769	0%	23:50:03
15	news.swapon.de	2222	0	2183	3	0	0%	23:50:02
16	news.mixmin.net	19487	0	18889	0	0	0%	23:53:05
17	au2pb	0	0	0	0	19767	0%	23:50:03
TOTAL: 17		269363	6980	233421	1273	39570	2%	405:05:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	9.3 MB	172.6 KB	9.4 MB	0.1 KB/s	2.6 KB	23:50:02
2	news.freedyn.net	5.9 MB	39.4 KB	6.0 MB	0.1 KB/s	5.4 KB	23:51:20
3	xanadu-bbs	5.1 MB	9.6 KB	5.1 MB	0.1 KB/s	6.8 KB	23:56:12
4	fu-berlin.de	4.5 MB	0.0 KB	4.5 MB	0.1 KB/s	7.9 KB	23:50:02
5	erje	1.4 MB	217.9 KB	1.6 MB	0.0 KB/s	3.5 KB	23:55:26
6	usenet.pasdenom.info	711.9 KB	5.6 MB	6.3 MB	0.1 KB/s	4.5 KB	23:57:34
7	news.redatomik.org	146.1 KB	10.1 KB	156.2 KB	0.0 KB/s	2.8 KB	23:50:38
8	xmission	61.0 KB	0.0 KB	61.0 KB	0.0 KB/s	2.3 KB	23:50:02
9	eternal-september	55.3 KB	0.0 KB	55.3 KB	0.0 KB/s	2.5 KB	23:50:02
10	weretis.net	26.5 KB	0.0 KB	26.5 KB	0.0 KB/s	1.9 KB	23:50:02
11	aioe	14.2 KB	10.7 KB	24.9 KB	0.0 KB/s	2.1 KB	23:50:02
12	news.fcku.it	4.2 KB	0.0 KB	4.2 KB	0.0 KB/s	1.4 KB	23:13:58
13	neva.ru	2.1 KB	35.8 KB	37.9 KB	0.0 KB/s	2.2 KB	23:56:49
14	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
15	news.swapon.de	0.0 KB	9.4 KB	9.4 KB	0.0 KB/s	3.1 KB	23:50:02
16	news.mixmin.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:53:05
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
TOTAL: 17		27.3 MB	6.1 MB	33.4 MB	0.0 KB/s	4.1 KB	405:05:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	tempe0.bbox.io	72	4	47.3 KB	2	08:50:46
2	47.192.187.101	7	1	7.0 KB	1	02:47:22
3	challenger.nielsenmedia.com	10	1	7.0 KB	1	02:04:36
TOTAL: 3		89	6	61.3 KB	4	63:07:03

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.bbox.io	72	4	47.3 KB	2	08:50:46
2	*.nielsenmedia.com	10	1	7.0 KB	1	02:04:36
3	unresolved	105	1	7.0 KB	1	49:47:12
4	*.dhcp.mdsn.wi.charter.com	3	0	0.0 KB	0	01:06:52
5	*.chello.nl	9	0	0.0 KB	0	01:17:35
TOTAL: 5		199	6	61.3 KB	4	63:07:03

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
216.239.174.218	7.656	1.829	46:57:08
tempe0.bbox.io	5.875	1.605	08:50:46
47.192.187.101	0.564	0.151	02:47:22
challenger.nielsenmedia.com	0.907	0.218	02:04:36
dhcp-089-099-203-254.chello.nl	0.760	0.168	01:17:35
68-185-181-64.dhcp.mdsn.wi.charter.com	0.268	0.057	01:06:52
196.52.18.18	0.302	0.193	00:02:41
TOTAL: 7	16.332	4.221	63:07:03

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	96
dhcp-089-099-203-254.chello.nl	9
68-185-181-64.dhcp.mdsn.wi.charter.com	3
196.52.18.18	2
TOTAL: 4	110

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	92	0
tempe0.bbox.io	14	0
47.192.187.101	5	0
challenger.nielsenmedia.com	4	0
68-185-181-64.dhcp.mdsn.wi.charter.com	2	0
TOTAL: 5	117	0

Newsgroup request counts (by category):

Category		Count	Pct
1	muc	4	66.7%
2	comp	2	33.3%
TOTAL: 2		6	100.0%

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

Newsgroup		Count
1	comp.lang.awk	2
2	muc.lists.freebsd.current	2
3	muc.lists.freebsd.stable	2
TOTAL: 3		6

Daily Usenet report for csiph.com

Oct 22 04:15:00 -- Oct 23 04:15:00