Daily Usenet report for csiph.com: Mar 11 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	43464	55.7%	7.7 MB	66.7%
innd	27941	35.8%	2.9 MB	25.5%
nnrpd	3299	4.2%	387.2 KB	3.3%
innfeed	3171	4.1%	526.7 KB	4.4%
nocem	94	0.1%	9.5 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	77970	100.0%	11.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	380577	54.7%
Negative hits	271804	39.1%
Do not exist	42445	6.1%
Cache misses	520	0.1%
TOTAL: 4	695346	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.882	0.0%	290	0.000	3.041	37.000
article cleanup	00:00:00.227	0.0%	38132	0.000	0.006	0.025
article logging	00:00:06.190	0.0%	38626	0.000	0.160	1.661
article parse	00:00:56.080	0.1%	648034	0.000	0.087	0.530
article write	00:03:37.155	0.3%	20062	0.237	10.824	79.212
artlog/artcncl	00:00:00.018	0.0%	219	0.000	0.082	1.167
artlog/artparse	00:00:01.673	0.0%	4742	0.000	0.353	9.000
data move	00:00:00.794	0.0%	1170784	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.219	0.0%	290	0.000	0.755	14.000
hishave/artcncl	00:00:00.210	0.0%	290	0.000	0.724	10.000
hishave/artparse	00:00:00.020	0.0%	4742	0.000	0.004	0.091
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:33.673	0.2%	695036	0.002	0.221	0.698
history sync	00:00:07.348	0.0%	359	0.000	20.468	113.667
history write	00:04:46.367	0.3%	37978	0.229	7.540	32.279
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:44.911	0.1%	4742	0.000	9.471	60.500
idle	23:39:37.598	98.5%	1126371	5.670	75.621	454.771
nntp read	00:00:05.400	0.0%	1163195	0.002	0.005	0.017
overview write	00:05:55.813	0.4%	20062	1.919	17.736	718.862
perl filter	00:01:41.458	0.1%	37978	1.621	2.671	5.397
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.368	0.0%	40209	0.000	0.009	0.038
TOTAL: 24:00:57.325	24:00:16.404	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.050	0.0%	20057	0.000	0.002	0.035
article prepare	00:00:00.003	0.0%	36016	0.000	0.000	0.004
article read	00:00:01.286	0.0%	6246	0.000	0.206	2.783
backlog stats	00:00:00.193	0.0%	372228	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.819	0.0%	286917	0.003	0.006	0.014
data write	00:00:01.742	0.0%	270121	0.002	0.006	0.015
idle	23:54:14.026	100.0%	372228	119.832	231.186	589.607
status file	00:00:00.368	0.0%	274	0.000	1.343	39.000
TOTAL: 23:54:29.254	23:54:19.487	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.298	0.0%	104	0.000	2.865	38.000
idle	71:35:43.093	92.0%	3699	79.000	69679.128	900192.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:55:12.052	1.2%	5174819	0.000	0.640	386.500
readart	00:01:39.939	0.0%	21260	0.000	4.701	85.000
TOTAL: 77:46:59.454	72:32:35.382	93.3%	-	-	-	-

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	1151	118273	5940	102780	9553	5%	95:03:50
2	csiph-out.news.weretis.net	3	44837	4709	38535	1593	10%	49:29:58
3	feeder.eternal-september.org	28	40995	2638	29958	8399	6%	31:53:42
4	news-out.aioe.org	3	19389	1682	17659	48	8%	24:08:49
5	news-out.mixmin.net	3	21313	1585	19311	417	7%	24:08:51
6	newsfeed.neva.ru	150	49224	1179	45063	2982	2%	71:58:04
7	newsfeed.fu-berlin.de	20	19208	973	18022	213	5%	25:15:50
8	news.redatomik.org	1	22762	728	22030	4	3%	28:09:34
9	newsfeeds.xmission.com	3	25564	643	24897	24	2%	32:18:42
10	nntp.pasdenom.info	2	18504	78	18423	3	0%	23:59:09
11	news.freedyn.net	3	23916	72	23776	68	0%	24:09:49
12	news.swapon.de	3	2156	67	2085	4	3%	23:53:46
13	news.fcku.it	2	8496	48	8386	62	0%	23:59:50
14	xanadu-bbs.net	16	693	32	659	2	4%	22:30:06
15	localhost	40	5	5	0	0	100%	00:00:00
16	news.bbs.geek.nz	1	16636	1	16635	0	0%	23:59:48
TOTAL: 16		1429	431971	20380	388219	23372	4%	524:59:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.7 MB	956.3 KB	82.5 MB	104.1 MB	19%	6.9 KB
2	csiph-out.news.weretis.net	15.0 MB	39.4 KB	17.6 MB	32.6 MB	45%	5.3 KB
3	feeder.eternal-september.org	7.2 MB	668.8 KB	1.1 GB	1.1 GB	0%	107.5 KB
4	news-out.mixmin.net	5.7 MB	0.0 KB	1.1 MB	6.8 MB	83%	3.5 KB
5	news-out.aioe.org	4.1 MB	3.3 KB	114.2 KB	4.2 MB	97%	2.5 KB
6	newsfeed.neva.ru	3.2 MB	620.5 KB	32.9 MB	36.7 MB	8%	9.0 KB
7	news.redatomik.org	2.1 MB	0.0 KB	162.0 KB	2.2 MB	92%	3.1 KB
8	newsfeed.fu-berlin.de	2.1 MB	1.9 KB	547.4 KB	2.6 MB	79%	2.3 KB
9	newsfeeds.xmission.com	1.6 MB	0.0 KB	1.6 MB	3.2 MB	50%	4.9 KB
10	news.freedyn.net	320.5 KB	0.0 KB	1.0 MB	1.4 MB	23%	9.9 KB
11	news.fcku.it	168.3 KB	0.0 KB	1.9 MB	2.0 MB	8%	19.0 KB
12	xanadu-bbs.net	154.3 KB	0.0 KB	102.4 KB	256.7 KB	60%	7.6 KB
13	nntp.pasdenom.info	153.3 KB	0.0 KB	4.4 KB	157.8 KB	97%	1.9 KB
14	news.swapon.de	148.0 KB	0.0 KB	1.1 MB	1.3 MB	11%	18.2 KB
15	localhost	9.5 KB	0.0 KB	0.0 KB	9.5 KB	100%	1.9 KB
16	news.bbs.geek.nz	7.9 KB	0.0 KB	0.0 KB	7.9 KB	100%	7.9 KB
TOTAL: 16		62.6 MB	2.2 MB	1.3 GB	1.3 GB	4%	31.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 11 04:15:00 - 04:59:59	323	1.6%	0.12	960.5 KB	1.5%	0.36
Mar 11 05:00:00 - 05:59:59	455	2.3%	0.13	1.2 MB	2.0%	0.34
Mar 11 06:00:00 - 06:59:59	449	2.2%	0.12	1.2 MB	2.0%	0.35
Mar 11 07:00:00 - 07:59:59	657	3.3%	0.18	2.4 MB	3.8%	0.67
Mar 11 08:00:00 - 08:59:59	808	4.0%	0.22	2.5 MB	4.1%	0.72
Mar 11 09:00:00 - 09:59:59	836	4.2%	0.23	2.3 MB	3.7%	0.66
Mar 11 10:00:00 - 10:59:59	810	4.0%	0.23	2.1 MB	3.5%	0.60
Mar 11 11:00:00 - 11:59:59	920	4.6%	0.26	2.8 MB	4.5%	0.79
Mar 11 12:00:00 - 12:59:59	1059	5.3%	0.29	3.0 MB	4.9%	0.86
Mar 11 13:00:00 - 13:59:59	1179	5.9%	0.33	4.0 MB	6.6%	1.15
Mar 11 14:00:00 - 14:59:59	1075	5.4%	0.30	2.8 MB	4.6%	0.81
Mar 11 15:00:00 - 15:59:59	1000	5.0%	0.28	3.3 MB	5.4%	0.95
Mar 11 16:00:00 - 16:59:59	1017	5.1%	0.28	2.8 MB	4.6%	0.81
Mar 11 17:00:00 - 17:59:59	1264	6.3%	0.35	3.6 MB	5.8%	1.02
Mar 11 18:00:00 - 18:59:59	1220	6.1%	0.34	3.1 MB	5.1%	0.89
Mar 11 19:00:00 - 19:59:59	1007	5.0%	0.28	2.8 MB	4.5%	0.79
Mar 11 20:00:00 - 20:59:59	894	4.5%	0.25	2.5 MB	4.0%	0.70
Mar 11 21:00:00 - 21:59:59	980	4.9%	0.27	3.1 MB	5.1%	0.89
Mar 11 22:00:00 - 22:59:59	966	4.8%	0.27	2.7 MB	4.4%	0.77
Mar 11 23:00:00 - 23:59:59	828	4.1%	0.23	2.3 MB	3.7%	0.64
Mar 12 00:00:00 - 00:59:59	811	4.0%	0.23	5.3 MB	8.6%	1.50
Mar 12 01:00:00 - 01:59:59	548	2.7%	0.15	1.7 MB	2.7%	0.48
Mar 12 02:00:00 - 02:59:59	461	2.3%	0.13	1.5 MB	2.4%	0.42
Mar 12 03:00:00 - 03:59:59	403	2.0%	0.11	1.3 MB	2.0%	0.36
Mar 12 04:00:00 - 04:15:00	72	0.4%	0.08	192.0 KB	0.3%	0.21
TOTAL: 24:00:00	20042	100.0%	0.23	61.4 MB	100.0%	0.73

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	9552	269	61	9222
2	feeder.eternal-september.org	7720	68	47	7605
3	newsfeed.neva.ru	3026	290	38	2698
4	csiph-out.news.weretis.net	1640	246	2	1392
5	newsfeed.fu-berlin.de	576	28	1	547
6	news-out.mixmin.net	447	1	0	446
7	news.freedyn.net	76	4	4	68
8	news.fcku.it	66	0	0	66
9	news-out.aioe.org	56	44	1	11
10	newsfeeds.xmission.com	18	0	0	18
11	news.redatomik.org	12	0	0	12
12	news.swapon.de	7	0	0	7
13	xanadu-bbs.net	5	0	0	5
14	nntp.pasdenom.info	2	0	0	2
TOTAL: 14		23203	950	154	22099

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	146
ut.test	136
it-alt.sport.calcio.juventus	99
us	36
mpc.lists.freebsd.ports.bugs	33
spline.eisfair	32
lada.auto	31
cakewalk.audio	25
gmane.comp.gis.openstreetmap.tagging	24
mozilla.support.thunderbird	23
mozilla.support.seamonkey	23
alt.survival	19
alt.windows7.general	15
mozilla.support.firefox	15
mozilla.general	13
alt.2600	13
mpc.lists.freebsd.x11	13
alt.politics.scorched-earth	12
spline.eisfair.dev	12
alt.politics	10
TOTAL: 111	950

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13179
EMP (phn path)	2993
Scoring filter (12)	119
Too many newsgroups (meow)	106
Scoring filter (10)	74
EMP (phl)	57
Scoring filter (14)	50
Scoring filter (13)	39
Too many newsgroups	38
Scoring filter (15)	29
User-issued cancel	27
Scoring filter (8)	27
Scoring filter (9)	24
EMP (phn nph)	15
Binary Payload	13
Scoring filter (17)	12
Scoring filter (16)	10
Scoring filter (11)	9
HTML post	8
HTML Multipart	7
TOTAL: 32	16862

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	85	0	96	96
TOTAL: 1	85	0	96	96

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	feeder.eternal-september.org	4607
	news.fcku.it	49
	csiph-out.news.weretis.net	46
	newsfeeds.xmission.com	16
	news-out.mixmin.net	10
	news.freedyn.net	7
	news.swapon.de	4
	xanadu-bbs.net	2
	news.redatomik.org	1
	TOTAL: 9	4742
Including strange strings
	newsfeed.fu-berlin.de	351
	csiph-out.news.weretis.net	46
	csiph-out.feeder.erje.net	34
	news-out.mixmin.net	28
	newsfeed.neva.ru	15
	news-out.aioe.org	7
	news.redatomik.org	5
	xanadu-bbs.net	3
	news.swapon.de	3
	feeder.eternal-september.org	2
	TOTAL: 11	495
TOTAL: 3		5241

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	33704	4415	14888	81	2	13%	23:59:53
2	news.freedyn.net	20646	782	19185	2	0	3%	23:59:53
3	xanadu-bbs	21532	669	19337	0	8	3%	23:59:53
4	fu-berlin.de	18869	602	18267	0	0	3%	23:59:53
5	erje	14459	375	13539	157	21	2%	23:59:53
6	usenet.pasdenom.info	22063	167	18133	1662	12	0%	23:59:53
7	news.redatomik.org	19619	31	18713	5	0	0%	23:59:47
8	aioe	17626	18	16667	5	0	0%	23:59:53
9	weretis.net	14458	10	14115	6	0	0%	23:59:53
10	xmission	20604	9	18680	0	2	0%	23:59:41
11	eternal-september	17625	7	16337	1	0	0%	23:59:53
12	news.mixmin.net	18316	5	17599	0	0	0%	23:59:53
13	news.swapon.de	2191	3	2138	2	0	0%	23:54:49
14	neva.ru	18926	1	18016	9	0	0%	23:59:53
15	au2pb	0	0	0	0	19387	0%	23:58:42
16	news.fcku.it	1707	0	1645	0	0	0%	21:48:34
17	bwh	0	0	0	0	19227	0%	23:58:42
TOTAL: 17		262345	7094	227259	1930	38659	2%	405:38:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	11.9 MB	615.1 KB	12.5 MB	0.1 KB/s	2.8 KB	23:59:53
2	news.freedyn.net	5.7 MB	2.1 KB	5.7 MB	0.1 KB/s	7.5 KB	23:59:53
3	xanadu-bbs	5.4 MB	0.0 KB	5.4 MB	0.1 KB/s	8.2 KB	23:59:53
4	fu-berlin.de	5.2 MB	0.0 KB	5.2 MB	0.1 KB/s	8.9 KB	23:59:53
5	erje	1.3 MB	880.2 KB	2.2 MB	0.0 KB/s	4.2 KB	23:59:53
6	usenet.pasdenom.info	539.3 KB	7.3 MB	7.8 MB	0.1 KB/s	4.4 KB	23:59:53
7	news.redatomik.org	109.7 KB	11.7 KB	121.4 KB	0.0 KB/s	3.4 KB	23:59:47
8	aioe	38.2 KB	24.1 KB	62.3 KB	0.0 KB/s	2.7 KB	23:59:53
9	weretis.net	34.0 KB	47.4 KB	81.4 KB	0.0 KB/s	5.1 KB	23:59:53
10	xmission	26.0 KB	0.0 KB	26.0 KB	0.0 KB/s	2.9 KB	23:59:41
11	eternal-september	13.1 KB	1.5 KB	14.6 KB	0.0 KB/s	1.8 KB	23:59:53
12	news.mixmin.net	11.0 KB	0.0 KB	11.0 KB	0.0 KB/s	2.2 KB	23:59:53
13	news.swapon.de	5.4 KB	4.1 KB	9.5 KB	0.0 KB/s	1.9 KB	23:54:49
14	neva.ru	3.1 KB	26.5 KB	29.6 KB	0.0 KB/s	3.0 KB	23:59:53
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:48:34
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		30.3 MB	8.9 MB	39.2 MB	0.0 KB/s	4.4 KB	405:38:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-02-70-24-220-69.dsl.bell.ca	209	20	24.3 KB	20	0	0	00:56:45
2	mtrlpq2314w-lp140-03-70-24-216-197.dsl.bell.ca	156	16	46.4 KB	5	0	0	15:04:15
3	dhcp-089-099-203-254.chello.nl	15	12	23.1 KB	6	0	0	02:00:39
4	68-185-181-64.dhcp.mdsn.wi.charter.com	19	12	24.7 KB	9	5	5	05:49:43
5	tempe0.bbox.io	11	1	2.0 KB	1	0	0	03:30:52
TOTAL: 5		410	61	120.5 KB	41	5	5	77:46:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	365	36	70.7 KB	25	0	0	16:01:00
2	*.chello.nl	15	12	23.1 KB	6	0	0	02:00:39
3	*.dhcp.mdsn.wi.charter.com	19	12	24.7 KB	9	5	5	05:49:43
4	*.bbox.io	11	1	2.0 KB	1	0	0	03:30:52
5	unresolved	140	0	0.0 KB	0	0	0	49:54:37
6	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
TOTAL: 6		551	61	120.5 KB	41	5	5	77:46:54

NNRP auth users [Top 20]:

User	Conn
ohlandl	5
TOTAL: 1	5

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.352	1.565	0.000	49:36:27
mtrlpq2314w-lp140-03-70-24-216-197.dsl.bell.ca	11.972	12.325	0.000	15:04:15
68-185-181-64.dhcp.mdsn.wi.charter.com	2.005	0.377	0.022	05:49:43
tempe0.bbox.io	1.101	0.213	0.000	03:30:52
dhcp-089-099-203-254.chello.nl	1.542	0.269	0.000	02:00:39
mtrlpq2314w-lp130-02-70-24-220-69.dsl.bell.ca	114.119	331.729	0.000	00:56:45
c-174-61-130-222.hsd1.wa.comcast.net	0.062	0.000	0.000	00:30:00
24.114.83.168	1.434	5.541	0.000	00:12:29
24.114.106.47	7.036	13.956	0.000	00:05:41
TOTAL: 9	146.623	365.975	0.022	77:46:54

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	100
24.114.106.47	35
24.114.83.168	5
c-174-61-130-222.hsd1.wa.comcast.net	1
TOTAL: 4	141

NNRP client timeouts [Top 20]:

System	Conn	Peer
mtrlpq2314w-lp140-03-70-24-216-197.dsl.bell.ca	189	3
216.239.174.218	97	0
68-185-181-64.dhcp.mdsn.wi.charter.com	8	0
mtrlpq2314w-lp130-02-70-24-220-69.dsl.bell.ca	8	8
tempe0.bbox.io	7	0
c-174-61-130-222.hsd1.wa.comcast.net	1	0
TOTAL: 6	310	11

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	33	56.9%
2	comp	25	43.1%
TOTAL: 2		58	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	24
2	alt.atheism	9
3	alt.activism.death-penalty	5
4	alt.baldspot	2
5	alt.autos.toyota	1
6	alt.autos.honda	1
7	alt.algebra.help	1
8	alt.arts.poetry.comments	1
9	alt.bbs.ads	1
10	alt.agnosticism	1
11	alt.assassination.jfk	1
12	comp.sys.sun.wanted	1
13	alt.anagrams	1
14	alt.autos.ford	1
15	alt.aquaria.tropical.fish.hobbist	1
16	alt.astronomy	1
17	alt.bbs	1
18	alt.battlestar-galactica	1
19	alt.appalachian	1
20	alt.airports.uk.edinburgh	1
21	alt.abortion	1
22	alt.autos.subaru	1
TOTAL: 22		58

Daily Usenet report for csiph.com

Mar 11 04:15:00 -- Mar 12 04:15:00