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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	50787	59.5%	8.8 MB	70.2%
innd	30047	35.2%	3.1 MB	24.5%
innfeed	3239	3.8%	553.1 KB	4.3%
nnrpd	1148	1.3%	126.1 KB	1.0%
nocem	78	0.1%	8.1 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	85300	100.0%	12.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	489960	53.7%
Negative hits	371994	40.8%
Do not exist	49294	5.4%
Cache misses	893	0.1%
TOTAL: 4	912141	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.766	0.0%	259	0.000	2.958	25.000
article cleanup	00:00:00.318	0.0%	49813	0.000	0.006	0.025
article logging	00:00:00.600	0.0%	50417	0.000	0.012	0.040
article parse	00:00:01.851	0.0%	193710	0.000	0.010	0.035
article write	00:06:51.462	0.5%	23848	0.533	17.254	96.118
artlog/artcncl	00:00:00.001	0.0%	178	0.000	0.006	0.500
artlog/artparse	00:00:00.002	0.0%	200	0.000	0.010	0.500
data move	00:00:00.384	0.0%	814558	0.000	0.000	0.008
hisgrep/artcncl	00:00:00.060	0.0%	251	0.000	0.239	4.500
hishave/artcncl	00:00:00.003	0.0%	259	0.000	0.012	1.000
hishave/artparse	00:00:00.000	0.0%	199	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:04.275	0.0%	915119	0.001	0.005	0.057
history sync	00:00:01.277	0.0%	352	0.000	3.628	23.000
history write	00:00:27.312	0.0%	49094	0.087	0.556	5.765
hiswrite/artcncl	00:00:00.001	0.0%	8	0.000	0.125	0.500
hiswrite/artparse	00:00:00.115	0.0%	198	0.000	0.581	3.000
idle	23:48:12.259	99.1%	820304	55.132	104.464	351.370
nntp read	00:00:02.994	0.0%	839129	0.000	0.004	0.014
overview write	00:01:40.311	0.1%	23848	0.206	4.206	78.633
perl filter	00:02:28.023	0.2%	49093	1.751	3.015	4.957
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.428	0.0%	47768	0.000	0.009	0.027
TOTAL: 24:00:48.989	23:59:52.442	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.082	0.0%	23801	0.000	0.003	0.022
article prepare	00:00:00.001	0.0%	20544	0.000	0.000	0.004
article read	00:00:02.640	0.0%	2789	0.000	0.947	24.800
backlog stats	00:00:00.202	0.0%	424003	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.727	0.0%	332729	0.001	0.005	0.010
data write	00:00:02.229	0.0%	316200	0.002	0.007	0.014
idle	23:52:43.743	100.0%	424003	119.091	202.743	558.196
status file	00:00:00.744	0.0%	288	0.000	2.583	31.167
TOTAL: 23:52:58.924	23:52:51.368	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.007	0.0%	96	0.000	0.073	1.000
idle	65:59:35.345	99.8%	551	443.000	431171.225	900146.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.010	0.0%	1214	0.000	0.008	0.250
readart	00:00:00.662	0.0%	30	0.000	22.067	74.000
TOTAL: 66:08:07.980	65:59:36.024	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	146488	7079	129873	9536	4%	94:57:54
2	feeder.eternal-september.org	26	44913	3503	35829	5581	7%	28:52:07
3	csiph-out.news.weretis.net	4	63545	3355	57791	2399	5%	54:13:01
4	news-out.mixmin.net	3	26628	2208	23925	495	8%	24:08:51
5	newsfeeds.xmission.com	1	27233	2087	25101	45	7%	29:44:06
6	newsfeed.neva.ru	153	59861	1525	50768	7568	2%	71:29:10
7	news-out.aioe.org	3	23063	1287	21761	15	5%	24:09:52
8	newsfeed.fu-berlin.de	44	22553	1137	21051	365	5%	26:58:16
9	news.redatomik.org	4	22217	890	21244	83	4%	20:57:06
10	news.fcku.it	1	10383	450	9794	139	4%	23:59:58
11	xanadu-bbs.net	11	804	159	626	19	19%	22:47:50
12	news.swapon.de	4	1902	70	1830	2	3%	23:01:31
13	news.freedyn.net	2	28418	56	28220	142	0%	23:57:29
14	nntp.pasdenom.info	2	47467	46	47371	50	0%	24:08:59
15	news.bbs.geek.nz	2	21518	1	21517	0	0%	23:59:45
16	localhost	33	0	0	0	0	0%	00:00:01
TOTAL: 16		1445	546993	23853	496701	26439	4%	517:25:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	23.6 MB	3.5 MB	57.1 MB	84.1 MB	28%	5.2 KB
2	feeder.eternal-september.org	9.0 MB	2.6 MB	72.3 MB	83.9 MB	10%	9.5 KB
3	csiph-out.news.weretis.net	8.9 MB	12.3 KB	23.5 MB	32.5 MB	27%	5.8 KB
4	news-out.mixmin.net	7.8 MB	0.0 KB	1.4 MB	9.3 MB	84%	3.5 KB
5	newsfeeds.xmission.com	6.1 MB	0.0 KB	2.2 MB	8.2 MB	73%	4.0 KB
6	newsfeed.neva.ru	4.2 MB	2.9 MB	77.2 MB	84.4 MB	4%	9.5 KB
7	news-out.aioe.org	2.9 MB	0.0 KB	36.0 KB	2.9 MB	98%	2.3 KB
8	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	794.5 KB	3.1 MB	75%	2.1 KB
9	news.redatomik.org	2.3 MB	0.0 KB	521.1 KB	2.8 MB	81%	2.9 KB
10	news.fcku.it	991.9 KB	0.0 KB	1.7 MB	2.7 MB	35%	4.7 KB
11	xanadu-bbs.net	528.2 KB	0.0 KB	113.9 KB	642.1 KB	82%	3.6 KB
12	news.freedyn.net	221.1 KB	0.0 KB	2.0 MB	2.3 MB	9%	11.7 KB
13	news.swapon.de	184.8 KB	1.4 KB	228.3 KB	414.5 KB	44%	5.8 KB
14	nntp.pasdenom.info	91.0 KB	0.0 KB	423.9 KB	514.9 KB	17%	5.4 KB
15	news.bbs.geek.nz	1.0 KB	0.0 KB	0.0 KB	1.0 KB	100%	1.0 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		69.2 MB	9.0 MB	239.6 MB	317.7 MB	21%	6.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 12 04:15:00 - 04:59:59	414	1.7%	0.15	1.5 MB	2.2%	0.57
Oct 12 05:00:00 - 05:59:59	656	2.8%	0.18	3.1 MB	4.4%	0.87
Oct 12 06:00:00 - 06:59:59	709	3.0%	0.20	2.3 MB	3.4%	0.67
Oct 12 07:00:00 - 07:59:59	1017	4.3%	0.28	2.7 MB	3.9%	0.76
Oct 12 08:00:00 - 08:59:59	1205	5.1%	0.33	2.9 MB	4.2%	0.82
Oct 12 09:00:00 - 09:59:59	1183	5.0%	0.33	3.2 MB	4.6%	0.91
Oct 12 10:00:00 - 10:59:59	1096	4.6%	0.30	2.9 MB	4.2%	0.82
Oct 12 11:00:00 - 11:59:59	1061	4.4%	0.29	2.8 MB	4.0%	0.79
Oct 12 12:00:00 - 12:59:59	1218	5.1%	0.34	3.2 MB	4.7%	0.92
Oct 12 13:00:00 - 13:59:59	1338	5.6%	0.37	3.8 MB	5.5%	1.08
Oct 12 14:00:00 - 14:59:59	1319	5.5%	0.37	3.7 MB	5.4%	1.05
Oct 12 15:00:00 - 15:59:59	1515	6.4%	0.42	4.2 MB	6.1%	1.20
Oct 12 16:00:00 - 16:59:59	1424	6.0%	0.40	4.3 MB	6.2%	1.21
Oct 12 17:00:00 - 17:59:59	1360	5.7%	0.38	4.0 MB	5.8%	1.13
Oct 12 18:00:00 - 18:59:59	1219	5.1%	0.34	3.4 MB	5.0%	0.97
Oct 12 19:00:00 - 19:59:59	1314	5.5%	0.36	3.5 MB	5.1%	0.99
Oct 12 20:00:00 - 20:59:59	1144	4.8%	0.32	3.4 MB	4.9%	0.95
Oct 12 21:00:00 - 21:59:59	1014	4.3%	0.28	2.9 MB	4.3%	0.83
Oct 12 22:00:00 - 22:59:59	938	3.9%	0.26	3.1 MB	4.5%	0.88
Oct 12 23:00:00 - 23:59:59	659	2.8%	0.18	2.0 MB	2.9%	0.57
Oct 13 00:00:00 - 00:59:59	506	2.1%	0.14	1.4 MB	2.1%	0.41
Oct 13 01:00:00 - 01:59:59	496	2.1%	0.14	1.4 MB	2.0%	0.40
Oct 13 02:00:00 - 02:59:59	439	1.8%	0.12	1.3 MB	1.9%	0.38
Oct 13 03:00:00 - 03:59:59	470	2.0%	0.13	1.6 MB	2.3%	0.44
Oct 13 04:00:00 - 04:15:00	131	0.5%	0.15	484.1 KB	0.7%	0.54
TOTAL: 24:00:00	23845	100.0%	0.28	69.0 MB	100.0%	0.82

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	9566	626	285	8655
2	newsfeed.neva.ru	7503	396	235	6872
3	feeder.eternal-september.org	5485	30	199	5256
4	csiph-out.news.weretis.net	2355	684	0	1671
5	newsfeed.fu-berlin.de	818	42	0	776
6	news-out.mixmin.net	520	8	0	512
7	news.fcku.it	140	0	0	140
8	news.freedyn.net	122	0	0	122
9	news.redatomik.org	115	0	0	115
10	nntp.pasdenom.info	50	2	0	48
11	newsfeeds.xmission.com	46	1	0	45
12	xanadu-bbs.net	20	0	0	20
13	news-out.aioe.org	18	14	0	4
14	news.swapon.de	7	0	1	6
TOTAL: 14		26765	1803	720	24242

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	661
lada.talk	171
ut.test	114
fa.freebsd.cvs-all	87
lada.auto	76
fa.openbsd.tech	47
spline.eisfair	33
lada.photo	31
lada.commerce	29
it-alt.sport.calcio.juventus	27
mozilla.support.seamonkey	26
alt.windows7.general	26
fa.freebsd.bugs	26
fa.openbsd.source-changes	24
fa.philos-l	22
alt.survival	18
fa.freebsd.stable	17
it.annunci.varie	16
it.annunci.commerciali	15
it.annunci.immobiliari	15
TOTAL: 140	1803

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	18624
EMP (phn path)	3754
EMP (phl)	641
EMP (phn nph)	132
Too many newsgroups	77
HTML Multipart	35
Binary Payload	29
Scoring filter (12)	20
Scoring filter (18)	17
Scoring filter (19)	14
Scoring filter (8)	13
Poison newsgroup	12
HTML post	11
Scoring filter (10)	11
Scoring filter (17)	11
User-issued cancel	9
Scoring filter (11)	6
Scoring filter (13)	5
Too many newsgroups (low_xpost)	3
HTML Image Tags	2
TOTAL: 28	23438

NoCeM on Spool:

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

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	79
	newsfeeds.xmission.com	30
	csiph-out.news.weretis.net	28
	news.fcku.it	27
	news-out.mixmin.net	12
	news.redatomik.org	11
	news.freedyn.net	5
	nntp.pasdenom.info	3
	xanadu-bbs.net	2
	news.swapon.de	1
	TOTAL: 11	199
Including strange strings
	newsfeed.fu-berlin.de	425
	csiph-out.news.weretis.net	53
	csiph-out.feeder.erje.net	30
	news-out.mixmin.net	28
	feeder.eternal-september.org	26
	news.redatomik.org	14
	newsfeed.neva.ru	9
	newsfeeds.xmission.com	7
	news.swapon.de	5
	news-out.aioe.org	4
	TOTAL: 12	604
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		808

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	43781	1607	21613	24	1	3%	24:09:39
2	news.freedyn.net	24190	891	22965	12	0	3%	24:09:44
3	xanadu-bbs	26388	722	22949	0	0	2%	23:59:48
4	fu-berlin.de	22393	597	21796	0	0	2%	24:09:32
5	usenet.pasdenom.info	25897	138	22668	990	1	0%	23:59:55
6	xmission	23758	61	21121	2	0	0%	23:59:56
7	weretis.net	19025	31	18528	1	0	0%	24:06:57
8	news.redatomik.org	22924	24	22139	5	13	0%	24:09:45
9	eternal-september	20338	24	19176	0	0	0%	23:59:52
10	aioe	21159	12	20127	4	0	0%	24:08:52
11	erje	16904	4	16731	11	0	0%	24:09:04
12	news.mixmin.net	21300	3	20584	1	0	0%	24:08:30
13	news.fcku.it	3108	0	3088	0	0	0%	22:26:29
14	news.swapon.de	2013	0	1981	0	0	0%	23:54:24
15	au2pb	0	0	0	0	23237	0%	24:07:16
16	bwh	0	0	0	0	23230	0%	24:08:05
17	neva.ru	21807	0	21284	14	0	0%	23:59:49
TOTAL: 17		314985	4114	276750	1064	46482	1%	407:47:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.3 MB	84.1 KB	6.4 MB	0.1 KB/s	7.3 KB	24:09:44
2	xanadu-bbs	5.9 MB	0.0 KB	5.9 MB	0.1 KB/s	8.4 KB	23:59:48
3	fu-berlin.de	5.6 MB	0.0 KB	5.6 MB	0.1 KB/s	9.6 KB	24:09:32
4	news.bbs.geek.nz	4.2 MB	65.9 KB	4.3 MB	0.1 KB/s	2.7 KB	24:09:39
5	usenet.pasdenom.info	387.5 KB	6.2 MB	6.6 MB	0.1 KB/s	6.0 KB	23:59:55
6	xmission	239.8 KB	15.4 KB	255.1 KB	0.0 KB/s	4.0 KB	23:59:56
7	weretis.net	104.9 KB	1.2 KB	106.1 KB	0.0 KB/s	3.3 KB	24:06:57
8	news.redatomik.org	89.3 KB	14.2 KB	103.5 KB	0.0 KB/s	3.6 KB	24:09:45
9	eternal-september	71.4 KB	0.0 KB	71.4 KB	0.0 KB/s	3.0 KB	23:59:52
10	aioe	29.4 KB	12.5 KB	41.8 KB	0.0 KB/s	2.6 KB	24:08:52
11	erje	18.0 KB	30.1 KB	48.1 KB	0.0 KB/s	3.2 KB	24:09:04
12	news.mixmin.net	6.5 KB	2.7 KB	9.2 KB	0.0 KB/s	2.3 KB	24:08:30
13	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:26:29
14	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:54:24
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:07:16
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:08:05
17	neva.ru	0.0 KB	27.9 KB	27.9 KB	0.0 KB/s	2.0 KB	23:59:49
TOTAL: 17		23.0 MB	6.5 MB	29.5 MB	0.0 KB/s	5.8 KB	407:47:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	tempe0.bbox.io	45	23	109.2 KB	12	08:06:10
2	cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1	7	18.2 KB	1	00:30:56
TOTAL: 2		46	30	127.4 KB	13	66:08:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.bbox.io	45	23	109.2 KB	12	08:06:10
2	*.19-1.cable.virginm.net	1	7	18.2 KB	1	00:30:56
3	*.chello.nl	33	0	0.0 KB	0	05:21:33
4	unresolved	97	0	0.0 KB	0	47:05:52
5	*.dhcp.mdsn.wi.charter.com	3	0	0.0 KB	0	01:30:03
6	*.hsd1.nj.comcast.net	8	0	0.0 KB	0	03:33:30
TOTAL: 6		187	30	127.4 KB	13	66:08:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
216.239.174.218	7.512	2.107	47:05:52
tempe0.bbox.io	4.504	1.030	08:06:10
dhcp-089-099-203-254.chello.nl	2.754	0.618	05:21:33
c-73-195-247-24.hsd1.nj.comcast.net	0.666	0.189	03:33:30
68-185-181-64.dhcp.mdsn.wi.charter.com	0.299	0.063	01:30:03
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	0.122	0.016	00:30:56
TOTAL: 6	15.857	4.023	66:08:06

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	97
dhcp-089-099-203-254.chello.nl	33
c-73-195-247-24.hsd1.nj.comcast.net	8
68-185-181-64.dhcp.mdsn.wi.charter.com	3
TOTAL: 4	141

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	92	0
tempe0.bbox.io	13	0
c-73-195-247-24.hsd1.nj.comcast.net	7	0
68-185-181-64.dhcp.mdsn.wi.charter.com	3	0
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1	0
TOTAL: 5	116	0

Newsgroup request counts (by category):

Category		Count	Pct
1	muc	21	70.0%
2	comp	9	30.0%
TOTAL: 2		30	100.0%

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

Newsgroup		Count
1	muc.lists.freebsd.stable	17
2	comp.lang.pl1	7
3	muc.lists.freebsd.fs	4
4	comp.sys.ibm.ps2.hardware	2
TOTAL: 4		30

Daily Usenet report for csiph.com

Oct 12 04:15:00 -- Oct 13 04:15:00