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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	48864	59.3%	8.6 MB	70.0%
innd	28914	35.1%	3.0 MB	24.1%
innfeed	3226	3.9%	573.0 KB	4.6%
nnrpd	1379	1.7%	151.8 KB	1.2%
nocem	48	0.1%	4.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	82432	100.0%	12.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	465488	51.8%
Negative hits	385173	42.9%
Do not exist	47307	5.3%
Cache misses	849	0.1%
TOTAL: 4	898817	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.684	0.0%	199	0.000	8.462	106.000
article cleanup	00:00:00.334	0.0%	47921	0.000	0.007	0.051
article logging	00:00:00.769	0.0%	48431	0.000	0.016	0.909
article parse	00:00:01.881	0.0%	174516	0.002	0.011	0.432
article write	00:06:35.409	0.5%	24232	0.475	16.318	66.040
artlog/artcncl	00:00:00.002	0.0%	142	0.000	0.014	0.111
artlog/artparse	00:00:00.001	0.0%	200	0.000	0.005	0.250
data move	00:00:00.336	0.0%	781866	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.151	0.0%	198	0.000	0.763	19.000
hishave/artcncl	00:00:00.000	0.0%	199	0.000	0.000	0.000
hishave/artparse	00:00:00.000	0.0%	200	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:16.286	0.0%	905032	0.002	0.018	0.367
history sync	00:00:01.750	0.0%	320	0.000	5.469	103.000
history write	00:01:06.682	0.1%	47382	0.087	1.407	32.451
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.278	0.0%	200	0.000	1.390	98.000
idle	23:46:50.019	99.1%	795408	59.750	107.630	396.857
nntp read	00:00:02.839	0.0%	814217	0.001	0.003	0.008
overview write	00:02:09.842	0.2%	24232	0.235	5.358	111.726
perl filter	00:02:16.861	0.2%	47382	1.548	2.888	4.487
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.407	0.0%	48507	0.000	0.008	0.025
TOTAL: 24:00:25.814	23:59:25.531	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.070	0.0%	24292	0.000	0.003	0.023
article prepare	00:00:00.006	0.0%	27456	0.000	0.000	0.006
article read	00:00:04.151	0.0%	3856	0.000	1.077	23.500
backlog stats	00:00:00.237	0.0%	420117	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.807	0.0%	331183	0.002	0.005	0.011
data write	00:00:02.313	0.0%	316979	0.003	0.007	0.014
idle	23:54:02.307	100.0%	420117	122.114	204.806	597.724
status file	00:00:00.497	0.0%	297	0.000	1.673	13.500
TOTAL: 23:54:18.725	23:54:11.388	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.004	0.0%	97	0.000	0.041	1.000
idle	70:17:52.348	99.8%	735	209.480	344316.120	900188.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.371	0.0%	1868	0.000	0.199	4.493
readart	00:00:27.077	0.0%	7672	0.000	3.529	43.000
TOTAL: 70:27:20.752	70:18:19.800	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.news.weretis.net	8	54141	6427	45741	1973	11%	46:51:55
2	csiph-out.feeder.erje.net	1151	142105	4614	129745	7746	3%	95:08:17
3	feeder.eternal-september.org	5	43646	3270	37747	2629	7%	25:33:27
4	news-out.mixmin.net	2	27603	2574	23771	1258	9%	24:08:53
5	news-out.aioe.org	3	23391	2167	21215	9	9%	24:09:40
6	newsfeed.neva.ru	148	48597	1734	37022	9841	3%	71:56:21
7	newsfeed.fu-berlin.de	69	25589	1126	24172	291	4%	36:26:47
8	newsfeeds.xmission.com	1	22594	860	21709	25	3%	23:21:25
9	news.redatomik.org	1	23351	830	22489	32	3%	23:44:30
10	news.fcku.it	1	9586	388	9061	137	4%	24:00:01
11	xanadu-bbs.net	13	748	212	524	12	28%	23:27:23
12	news.freedyn.net	4	28499	113	28314	72	0%	24:09:34
13	news.swapon.de	3	1998	56	1942	0	2%	24:01:49
14	nntp.pasdenom.info	2	45237	20	45161	56	0%	24:08:58
15	news.bbs.geek.nz	1	20239	10	20229	0	0%	23:59:44
16	localhost	32	0	0	0	0	0%	00:00:00
TOTAL: 16		1444	517324	24401	468842	24081	4%	515:08:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	18.2 MB	4.2 MB	41.8 MB	64.2 MB	28%	5.3 KB
2	csiph-out.news.weretis.net	17.1 MB	40.7 KB	19.1 MB	36.3 MB	47%	4.4 KB
3	news-out.mixmin.net	8.9 MB	8.3 KB	3.0 MB	11.8 MB	74%	3.2 KB
4	feeder.eternal-september.org	8.4 MB	188.4 KB	33.6 MB	42.1 MB	19%	7.3 KB
5	newsfeed.neva.ru	5.2 MB	2.0 MB	102.8 MB	110.0 MB	4%	9.7 KB
6	news-out.aioe.org	5.0 MB	0.0 KB	8.8 KB	5.0 MB	99%	2.3 KB
7	newsfeeds.xmission.com	2.5 MB	0.0 KB	1.9 MB	4.4 MB	56%	5.0 KB
8	newsfeed.fu-berlin.de	2.4 MB	1.8 KB	668.3 KB	3.1 MB	78%	2.2 KB
9	news.redatomik.org	2.2 MB	0.0 KB	187.0 KB	2.3 MB	92%	2.8 KB
10	news.fcku.it	835.5 KB	0.0 KB	2.2 MB	3.0 MB	27%	5.8 KB
11	xanadu-bbs.net	682.8 KB	0.0 KB	123.9 KB	806.6 KB	84%	3.6 KB
12	news.freedyn.net	500.4 KB	0.0 KB	820.8 KB	1.3 MB	37%	7.1 KB
13	news.swapon.de	153.6 KB	0.0 KB	0.0 KB	153.6 KB	100%	2.7 KB
14	nntp.pasdenom.info	46.8 KB	0.0 KB	325.8 KB	372.6 KB	12%	4.9 KB
15	news.bbs.geek.nz	13.5 KB	0.0 KB	0.0 KB	13.5 KB	100%	1.4 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		72.1 MB	6.4 MB	206.3 MB	284.8 MB	25%	6.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 21 04:15:00 - 04:59:59	347	1.4%	0.13	1.4 MB	2.0%	0.53
Oct 21 05:00:00 - 05:59:59	558	2.3%	0.15	1.8 MB	2.5%	0.52
Oct 21 06:00:00 - 06:59:59	869	3.6%	0.24	3.0 MB	4.3%	0.87
Oct 21 07:00:00 - 07:59:59	1128	4.7%	0.31	2.9 MB	4.1%	0.82
Oct 21 08:00:00 - 08:59:59	1329	5.5%	0.37	3.9 MB	5.4%	1.10
Oct 21 09:00:00 - 09:59:59	1170	4.8%	0.33	2.9 MB	4.1%	0.84
Oct 21 10:00:00 - 10:59:59	1150	4.7%	0.32	3.2 MB	4.4%	0.90
Oct 21 11:00:00 - 11:59:59	1098	4.5%	0.30	2.9 MB	4.1%	0.84
Oct 21 12:00:00 - 12:59:59	1308	5.4%	0.36	3.7 MB	5.2%	1.06
Oct 21 13:00:00 - 13:59:59	1231	5.1%	0.34	3.3 MB	4.7%	0.95
Oct 21 14:00:00 - 14:59:59	1455	6.0%	0.40	4.4 MB	6.2%	1.26
Oct 21 15:00:00 - 15:59:59	1311	5.4%	0.36	3.4 MB	4.8%	0.97
Oct 21 16:00:00 - 16:59:59	1308	5.4%	0.36	3.6 MB	5.1%	1.03
Oct 21 17:00:00 - 17:59:59	1436	5.9%	0.40	4.6 MB	6.4%	1.31
Oct 21 18:00:00 - 18:59:59	1523	6.3%	0.42	5.8 MB	8.1%	1.64
Oct 21 19:00:00 - 19:59:59	1180	4.9%	0.33	3.2 MB	4.4%	0.90
Oct 21 20:00:00 - 20:59:59	1113	4.6%	0.31	3.0 MB	4.2%	0.84
Oct 21 21:00:00 - 21:59:59	1100	4.5%	0.31	3.1 MB	4.3%	0.87
Oct 21 22:00:00 - 22:59:59	812	3.4%	0.23	2.4 MB	3.4%	0.69
Oct 21 23:00:00 - 23:59:59	714	2.9%	0.20	2.1 MB	3.0%	0.61
Oct 22 00:00:00 - 00:59:59	589	2.4%	0.16	1.8 MB	2.6%	0.52
Oct 22 01:00:00 - 01:59:59	483	2.0%	0.13	1.4 MB	1.9%	0.39
Oct 22 02:00:00 - 02:59:59	433	1.8%	0.12	1.7 MB	2.4%	0.48
Oct 22 03:00:00 - 03:59:59	444	1.8%	0.12	1.4 MB	1.9%	0.39
Oct 22 04:00:00 - 04:15:00	134	0.6%	0.15	365.1 KB	0.5%	0.41
TOTAL: 24:00:00	24223	100.0%	0.28	71.3 MB	100.0%	0.85

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	9816	295	161	9360
2	csiph-out.feeder.erje.net	7786	313	363	7110
3	feeder.eternal-september.org	2563	71	20	2472
4	csiph-out.news.weretis.net	2066	177	2	1887
5	news-out.mixmin.net	1279	1	2	1276
6	newsfeed.fu-berlin.de	615	39	1	575
7	news.fcku.it	141	0	0	141
8	news.freedyn.net	75	0	0	75
9	nntp.pasdenom.info	55	1	0	54
10	news.redatomik.org	45	0	0	45
11	newsfeeds.xmission.com	24	0	0	24
12	news-out.aioe.org	22	9	0	13
13	xanadu-bbs.net	12	0	0	12
14	news.swapon.de	1	0	0	1
TOTAL: 14		24500	906	549	23045

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	154
ut.test	134
mpc.lists.nanog	56
alt.survival	43
lada.auto	41
gmane.linux.alsa.devel	32
alt.windows7.general	29
mozilla.support.firefox	24
it-alt.sport.calcio.juventus	21
mozilla.dev.security.policy	19
cakewalk.audio	18
lada.commerce	18
gmane.linux.kernel	18
mpc.lists.freebsd.gecko	17
mozilla.tools.taskcluster	14
alt.pub.cafe-bob	12
mozilla.support.seamonkey	12
eagle.support.eng	11
lada.movies	10
mozilla.dev.platform	10
TOTAL: 118	906

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	17603
EMP (phn path)	3656
EMP (phl)	686
EMP (phn nph)	131
Too many newsgroups	44
Scoring filter (18)	32
Scoring filter (12)	20
User-issued cancel	17
Scoring filter (13)	16
Scoring filter (19)	15
Scoring filter (20)	13
HTML Multipart	12
Binary Payload	12
Scoring filter (9)	11
Scoring filter (17)	10
Poison newsgroup	9
Scoring filter (14)	9
Scoring filter (10)	7
Scoring filter (15)	5
Scoring filter (11)	4
TOTAL: 29	22332

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	feeder.eternal-september.org	83
	news.fcku.it	41
	news-out.mixmin.net	22
	csiph-out.news.weretis.net	22
	newsfeeds.xmission.com	21
	news.freedyn.net	3
	newsfeed.fu-berlin.de	2
	nntp.pasdenom.info	2
	news.redatomik.org	2
	xanadu-bbs.net	2
	TOTAL: 10	200
Including strange strings
	newsfeed.fu-berlin.de	347
	csiph-out.news.weretis.net	48
	feeder.eternal-september.org	31
	csiph-out.feeder.erje.net	28
	news-out.mixmin.net	22
	news.redatomik.org	12
	news-out.aioe.org	12
	newsfeed.neva.ru	10
	newsfeeds.xmission.com	2
	news.swapon.de	1
	TOTAL: 10	513
TOTAL: 2		713

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	42848	2432	20764	44	0	5%	23:50:34
2	news.freedyn.net	24279	1076	22971	8	0	4%	23:53:17
3	xanadu-bbs	25242	899	23099	2	0	3%	23:59:56
4	fu-berlin.de	22767	759	22008	0	0	3%	23:50:00
5	usenet.pasdenom.info	25614	131	22848	1224	111	0%	23:59:56
6	xmission	25948	106	22408	3	0	0%	23:59:56
7	news.redatomik.org	22709	43	22336	5	0	0%	23:50:43
8	weretis.net	16390	35	16033	4	0	0%	23:50:00
9	aioe	20156	31	19412	2	0	0%	23:50:00
10	eternal-september	20753	18	19750	1	0	0%	23:59:41
11	news.fcku.it	2760	4	2733	0	0	0%	22:48:35
12	neva.ru	21486	1	21154	11	0	0%	23:59:56
13	news.swapon.de	1997	1	1987	0	0	0%	23:52:21
14	erje	19543	1	19449	15	0	0%	23:50:44
15	news.mixmin.net	20763	0	20252	1	0	0%	23:54:56
16	au2pb	0	0	0	0	23257	0%	23:50:02
17	bwh	0	0	0	0	23250	0%	23:50:02
TOTAL: 17		313255	5537	277204	1320	46618	1%	405:10:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.7 MB	80.4 KB	8.8 MB	0.1 KB/s	8.3 KB	23:53:17
2	xanadu-bbs	8.2 MB	8.9 KB	8.2 MB	0.1 KB/s	9.3 KB	23:59:56
3	fu-berlin.de	7.8 MB	0.0 KB	7.8 MB	0.1 KB/s	10.6 KB	23:50:00
4	news.bbs.geek.nz	6.2 MB	127.8 KB	6.3 MB	0.1 KB/s	2.6 KB	23:50:34
5	usenet.pasdenom.info	420.2 KB	8.7 MB	9.1 MB	0.1 KB/s	6.9 KB	23:59:56
6	xmission	354.9 KB	5.3 KB	360.1 KB	0.0 KB/s	3.3 KB	23:59:56
7	aioe	120.6 KB	5.3 KB	125.9 KB	0.0 KB/s	3.8 KB	23:50:00
8	news.redatomik.org	116.8 KB	12.4 KB	129.2 KB	0.0 KB/s	2.7 KB	23:50:43
9	weretis.net	108.9 KB	16.9 KB	125.8 KB	0.0 KB/s	3.2 KB	23:50:00
10	eternal-september	47.2 KB	1.0 KB	48.2 KB	0.0 KB/s	2.5 KB	23:59:41
11	news.fcku.it	10.1 KB	0.0 KB	10.1 KB	0.0 KB/s	2.5 KB	22:48:35
12	news.swapon.de	6.5 KB	0.0 KB	6.5 KB	0.0 KB/s	6.5 KB	23:52:21
13	neva.ru	2.9 KB	28.4 KB	31.3 KB	0.0 KB/s	2.6 KB	23:59:56
14	erje	1.0 KB	48.4 KB	49.5 KB	0.0 KB/s	3.1 KB	23:50:44
15	news.mixmin.net	0.0 KB	2.2 KB	2.2 KB	0.0 KB/s	2.2 KB	23:54:56
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:02
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:02
TOTAL: 17		32.1 MB	9.1 MB	41.1 MB	0.0 KB/s	6.1 KB	405:10:39

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	5	60	116.5 KB	4	01:00:08
2	challenger.nielsenmedia.com	36	22	60.3 KB	8	05:54:40
3	47.192.187.101	4	2	2.9 KB	1	01:37:32
4	tempe0.bbox.io	35	1	0.9 KB	1	05:27:55
TOTAL: 4		80	85	180.6 KB	14	70:27:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.19-1.cable.virginm.net	5	60	116.5 KB	4	01:00:08
2	*.nielsenmedia.com	36	22	60.3 KB	8	05:54:40
3	unresolved	103	2	2.9 KB	1	48:14:56
4	*.bbox.io	35	1	0.9 KB	1	05:27:55
5	*.dhcp.mdsn.wi.charter.com	3	0	0.0 KB	0	01:28:18
6	*.chello.nl	51	0	0.0 KB	0	08:21:20
TOTAL: 6		233	85	180.6 KB	14	70:27:18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
216.239.174.218	7.727	1.978	46:36:51
dhcp-089-099-203-254.chello.nl	4.129	1.175	08:21:20
challenger.nielsenmedia.com	3.060	0.792	05:54:40
tempe0.bbox.io	3.021	0.813	05:27:55
47.192.187.101	0.421	0.075	01:37:32
68-185-181-64.dhcp.mdsn.wi.charter.com	0.243	0.093	01:28:18
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	0.585	0.189	01:00:08
196.52.18.39	0.337	0.721	00:00:31
94.167.148.253	0.095	0.016	00:00:00
TOTAL: 9	19.618	5.852	70:27:18

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	97
dhcp-089-099-203-254.chello.nl	51
68-185-181-64.dhcp.mdsn.wi.charter.com	3
94.167.148.253	1
196.52.18.39	1
TOTAL: 5	153

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	89	0
tempe0.bbox.io	10	0
challenger.nielsenmedia.com	10	4
47.192.187.101	3	0
68-185-181-64.dhcp.mdsn.wi.charter.com	2	0
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1	0
TOTAL: 6	115	4

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	71	83.5%
2	alt	14	16.5%
TOTAL: 2		85	100.0%

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

Newsgroup		Count
1	comp.lang.pl1	43
2	comp.lang.awk	24
3	alt.os.linux.mint	14
4	comp.os.linux.setup	3
5	comp.sys.ibm.as400.misc	1
TOTAL: 5		85

Daily Usenet report for csiph.com

Oct 21 04:15:00 -- Oct 22 04:15:00