Daily Usenet report for csiph.com: Feb 20 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53657	40.4%	9.3 MB	55.0%
nnrpd	43562	32.8%	3.8 MB	22.6%
innd	32521	24.5%	3.3 MB	19.3%
innfeed	3153	2.4%	532.0 KB	3.1%
nocem	72	0.1%	7.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	132966	100.0%	17.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	527096	54.8%
Negative hits	382094	39.7%
Do not exist	52698	5.5%
Cache misses	598	0.1%
TOTAL: 4	962486	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.411	0.0%	224	0.000	1.835	18.000
article cleanup	00:00:00.307	0.0%	52331	0.000	0.006	0.026
article logging	00:00:00.709	0.0%	52973	0.000	0.013	0.175
article parse	00:00:02.479	0.0%	239466	0.002	0.010	0.058
article write	00:00:51.148	0.1%	25389	0.329	2.015	12.619
artlog/artcncl	00:00:00.002	0.0%	152	0.000	0.013	0.100
artlog/artparse	00:00:00.013	0.0%	485	0.000	0.027	1.000
data move	00:00:00.353	0.0%	946194	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.068	0.0%	219	0.000	0.311	12.000
hishave/artcncl	00:00:00.001	0.0%	224	0.000	0.004	0.200
hishave/artparse	00:00:00.002	0.0%	292	0.000	0.007	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:10.672	0.0%	963709	0.001	0.011	0.145
history sync	00:00:02.454	0.0%	352	0.000	6.972	77.333
history write	00:01:37.825	0.1%	52432	0.089	1.866	20.023
hiswrite/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hiswrite/artparse	00:00:00.443	0.0%	290	0.000	1.528	81.000
idle	23:52:51.749	99.4%	963876	49.859	89.194	285.681
nntp read	00:00:03.247	0.0%	974835	0.001	0.003	0.005
overview write	00:01:38.433	0.1%	25389	0.574	3.877	43.365
perl filter	00:02:29.274	0.2%	52222	1.432	2.858	4.812
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.456	0.0%	50841	0.000	0.009	0.025
TOTAL: 24:00:49.441	23:59:50.046	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	25364	0.000	0.003	0.033
article prepare	00:00:00.001	0.0%	31508	0.000	0.000	0.006
article read	00:00:00.710	0.0%	3333	0.000	0.213	14.200
backlog stats	00:00:00.276	0.0%	602704	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.206	0.0%	437337	0.002	0.005	0.010
data write	00:00:02.943	0.0%	417872	0.004	0.007	0.012
idle	23:52:57.204	100.0%	602704	76.248	142.652	458.665
status file	00:00:00.188	0.0%	259	0.000	0.726	3.000
TOTAL: 23:53:11.490	23:53:03.593	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:25.083	0.0%	299628	0.000	0.084	0.378
idle	124:47:04.102	99.9%	356896	39.143	1258.697	600199.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:11.773	0.0%	715069	0.000	0.016	70.680
readart	00:01:44.631	0.0%	57102	0.000	1.832	61.000
TOTAL: 124:50:49.165	124:49:25.589	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	30677	5384	21967	3326	17%	23:49:43
2	csiph-out.feeder.erje.net	1152	95039	3912	89379	1748	4%	95:17:54
3	feeder.eternal-september.org	27	46668	3339	37347	5982	7%	29:56:24
4	csiph-out.news.weretis.net	3	51328	2472	48502	354	4%	46:53:07
5	news-out.aioe.org	2	23842	2357	21463	22	9%	24:09:01
6	news-out.mixmin.net	2	29465	2137	25653	1675	7%	24:08:55
7	newsfeed.neva.ru	150	63563	2030	51613	9920	3%	71:39:23
8	newsfeed.fu-berlin.de	36	28760	1469	26585	706	5%	31:54:15
9	news.redatomik.org	1	30961	599	30357	5	1%	30:33:22
10	news.freedyn.net	3	30250	531	29328	391	1%	24:08:56
11	newsfeeds.xmission.com	1	21247	379	20866	2	1%	21:30:34
12	xanadu-bbs.net	1	50224	332	46641	3251	0%	24:16:15
13	news.fcku.it	1	7077	286	6791	0	4%	24:37:42
14	news.swapon.de	1	2465	134	2301	30	5%	25:05:28
15	au2pb.net	1	29482	94	29363	25	0%	26:13:07
16	nntp.pasdenom.info	3	23614	38	23566	10	0%	24:08:36
17	news.bbs.geek.nz	2	26302	6	26296	0	0%	23:59:33
18	localhost	37	2	2	0	0	100%	00:00:00
TOTAL: 18		1711	590966	25501	538018	27447	4%	572:22:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	15.8 MB	0.0 KB	40.2 MB	56.0 MB	28%	6.6 KB
2	csiph-out.feeder.erje.net	14.8 MB	382.9 KB	6.5 MB	21.6 MB	68%	3.9 KB
3	feeder.eternal-september.org	8.4 MB	311.5 KB	68.7 MB	77.4 MB	10%	8.5 KB
4	news-out.mixmin.net	7.8 MB	0.0 KB	2.9 MB	10.7 MB	72%	2.9 KB
5	csiph-out.news.weretis.net	7.7 MB	0.0 KB	5.7 MB	13.3 MB	57%	4.8 KB
6	newsfeed.neva.ru	5.9 MB	331.0 KB	98.7 MB	105.0 MB	5%	9.0 KB
7	news-out.aioe.org	5.7 MB	0.0 KB	25.5 KB	5.7 MB	99%	2.4 KB
8	newsfeed.fu-berlin.de	3.4 MB	0.0 KB	1.1 MB	4.4 MB	75%	2.1 KB
9	news.freedyn.net	2.0 MB	0.0 KB	4.1 MB	6.1 MB	32%	6.8 KB
10	news.redatomik.org	1.2 MB	0.0 KB	1.1 MB	2.3 MB	51%	3.9 KB
11	newsfeeds.xmission.com	987.6 KB	0.0 KB	13.3 KB	1000.9 KB	98%	2.6 KB
12	xanadu-bbs.net	791.1 KB	0.0 KB	30.6 MB	31.4 MB	2%	9.0 KB
13	news.fcku.it	496.8 KB	0.0 KB	0.0 KB	496.8 KB	100%	1.7 KB
14	au2pb.net	307.5 KB	0.0 KB	150.2 KB	457.7 KB	67%	3.8 KB
15	news.swapon.de	298.8 KB	0.0 KB	84.7 KB	383.5 KB	77%	2.3 KB
16	nntp.pasdenom.info	79.4 KB	0.0 KB	64.0 KB	143.4 KB	55%	3.0 KB
17	news.bbs.geek.nz	10.9 KB	0.0 KB	0.0 KB	10.9 KB	100%	1.8 KB
18	localhost	4.1 KB	0.0 KB	0.0 KB	4.1 KB	100%	2.0 KB
TOTAL: 18		75.4 MB	1.0 MB	260.0 MB	336.4 MB	22%	6.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 20 04:15:00 - 04:59:59	338	1.3%	0.13	1.1 MB	1.4%	0.41
Feb 20 05:00:00 - 05:59:59	428	1.7%	0.12	1.2 MB	1.6%	0.35
Feb 20 06:00:00 - 06:59:59	548	2.2%	0.15	1.7 MB	2.3%	0.50
Feb 20 07:00:00 - 07:59:59	801	3.2%	0.22	2.5 MB	3.4%	0.72
Feb 20 08:00:00 - 08:59:59	1169	4.6%	0.32	4.2 MB	5.6%	1.20
Feb 20 09:00:00 - 09:59:59	1042	4.1%	0.29	3.0 MB	3.9%	0.84
Feb 20 10:00:00 - 10:59:59	1047	4.1%	0.29	2.8 MB	3.8%	0.81
Feb 20 11:00:00 - 11:59:59	1246	4.9%	0.35	3.6 MB	4.8%	1.04
Feb 20 12:00:00 - 12:59:59	1191	4.7%	0.33	3.6 MB	4.8%	1.02
Feb 20 13:00:00 - 13:59:59	1334	5.3%	0.37	3.7 MB	4.9%	1.06
Feb 20 14:00:00 - 14:59:59	1454	5.7%	0.40	4.1 MB	5.4%	1.17
Feb 20 15:00:00 - 15:59:59	1306	5.1%	0.36	3.6 MB	4.8%	1.04
Feb 20 16:00:00 - 16:59:59	1459	5.7%	0.41	4.1 MB	5.5%	1.18
Feb 20 17:00:00 - 17:59:59	1437	5.7%	0.40	4.1 MB	5.5%	1.17
Feb 20 18:00:00 - 18:59:59	1578	6.2%	0.44	4.3 MB	5.8%	1.24
Feb 20 19:00:00 - 19:59:59	1567	6.2%	0.44	5.0 MB	6.6%	1.41
Feb 20 20:00:00 - 20:59:59	1323	5.2%	0.37	3.9 MB	5.2%	1.10
Feb 20 21:00:00 - 21:59:59	1432	5.6%	0.40	4.1 MB	5.5%	1.18
Feb 20 22:00:00 - 22:59:59	1142	4.5%	0.32	3.5 MB	4.6%	0.98
Feb 20 23:00:00 - 23:59:59	955	3.8%	0.27	2.8 MB	3.7%	0.79
Feb 21 00:00:00 - 00:59:59	778	3.1%	0.22	2.7 MB	3.5%	0.76
Feb 21 01:00:00 - 01:59:59	616	2.4%	0.17	2.1 MB	2.8%	0.59
Feb 21 02:00:00 - 02:59:59	519	2.0%	0.14	1.3 MB	1.8%	0.38
Feb 21 03:00:00 - 03:59:59	549	2.2%	0.15	1.6 MB	2.2%	0.47
Feb 21 04:00:00 - 04:15:00	128	0.5%	0.14	432.5 KB	0.6%	0.48
TOTAL: 24:00:00	25387	100.0%	0.29	75.3 MB	100.0%	0.89

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	10079	762	26	0	9291
2	feeder.eternal-september.org	5967	81	25	0	5861
3	usenet.blueworldhosting.com	3327	726	0	1	2600
4	xanadu-bbs.net	3243	18	0	0	3225
5	csiph-out.feeder.erje.net	1779	273	41	0	1465
6	news-out.mixmin.net	1684	1	0	0	1683
7	newsfeed.fu-berlin.de	1132	39	0	0	1093
8	news.freedyn.net	386	1	0	0	385
9	csiph-out.news.weretis.net	377	209	0	0	168
10	news.swapon.de	61	0	0	0	61
11	news-out.aioe.org	37	20	0	0	17
12	au2pb.net	20	17	0	0	3
13	nntp.pasdenom.info	10	8	0	0	2
14	news.redatomik.org	8	0	0	0	8
15	news.fcku.it	5	0	0	0	5
16	newsfeeds.xmission.com	3	3	0	0	0
TOTAL: 16		28118	2158	92	1	25867

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	216
ut.test	167
mailing.freebsd.pkg-fallout	123
lada.talk	104
pld.cvs.commit	99
gmane.linux.alsa.devel	70
lada.auto	53
mailing.freebsd.svn-ports-all	48
mailing.freebsd.svn-ports-head	47
fa.netbsd.bugs	38
alt.survival	37
spline.eisfair	33
mailing.openbsd.source-changes	32
mailing.openbsd.misc	32
fa.openbsd.source-changes	32
mailing.freebsd.ports-bugs	31
mpc.lists.freebsd.ports.bugs	30
alt.politics.scorched-earth	29
mailing.openssl.cvs	29
gmane.linux.bios.flashrom	25
TOTAL: 235	2158

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
alt.dev.null	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	18008
EMP (phn path)	3785
EMP (phl)	1235
PFM bot misc	446
User-issued cancel	410
Binary: misplaced rar	299
EMP (phn nph)	103
Too many newsgroups	76
HTML Multipart	35
Scoring filter (20)	35
Scoring filter (21)	32
Scoring filter (18)	32
Scoring filter (19)	19
Scoring filter (25)	18
Scoring filter (8)	14
Scoring filter (23)	13
Binary Image: misplaced jpg	12
Binary Payload	12
Scoring filter (11)	10
Scoring filter (12)	9
TOTAL: 43	24720

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	63	0	73	73
TOTAL: 1	63	0	73	73

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	13
	usenet.blueworldhosting.com	4
	TOTAL: 2	17
Bad Message-ID's offered
	csiph-out.feeder.erje.net	3
	usenet.blueworldhosting.com	1
	TOTAL: 2	4
Huge articles
	xanadu-bbs.net	112
	usenet.blueworldhosting.com	90
	csiph-out.news.weretis.net	39
	feeder.eternal-september.org	28
	news.freedyn.net	13
	news-out.mixmin.net	8
	news.redatomik.org	3
	newsfeed.fu-berlin.de	1
	au2pb.net	1
	TOTAL: 9	295
Including strange strings
	newsfeed.fu-berlin.de	505
	csiph-out.feeder.erje.net	32
	news.swapon.de	31
	feeder.eternal-september.org	18
	csiph-out.news.weretis.net	17
	news-out.aioe.org	15
	news-out.mixmin.net	11
	news.fcku.it	5
	newsfeed.neva.ru	4
	news.redatomik.org	4
	TOTAL: 10	642
No colon-space in header
	csiph-out.feeder.erje.net	183
	usenet.blueworldhosting.com	6
	TOTAL: 2	189
TOTAL: 5		1147

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	28452	1529	23310	11	0	5%	23:59:51
2	xanadu-bbs	27368	1314	23727	1	21	4%	23:59:57
3	fu-berlin.de	23576	1092	22484	0	0	4%	23:59:54
4	news.bbs.geek.nz	46338	846	23293	31	1	1%	23:59:56
5	aioe	23661	481	20188	41	0	2%	23:59:55
6	usenet.pasdenom.info	32488	215	23392	1741	7	0%	23:59:56
7	news.redatomik.org	28814	108	23443	11	0	0%	23:59:56
8	au2pb	24924	82	24012	7	0	0%	23:59:57
9	xmission	30771	41	23729	5	0	0%	23:59:57
10	weretis.net	21226	20	20737	7	1	0%	23:59:56
11	news.fcku.it	2152	17	1855	0	0	0%	22:40:49
12	eternal-september	25355	15	20433	1	0	0%	23:59:56
13	news.mixmin.net	24697	13	21623	1	0	0%	23:59:52
14	neva.ru	26703	7	21615	217	0	0%	23:59:56
15	news.swapon.de	2497	3	2170	5	0	0%	23:59:56
16	erje	24263	2	21379	25	0	0%	23:59:43
17	bwh	19791	2	18702	5	0	0%	23:59:48
TOTAL: 17		413076	5787	336092	2109	30	1%	406:39:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.6 MB	92.7 KB	9.6 MB	0.1 KB/s	6.4 KB	23:59:51
2	xanadu-bbs	8.9 MB	2.2 KB	8.9 MB	0.1 KB/s	6.9 KB	23:59:57
3	fu-berlin.de	8.4 MB	0.0 KB	8.4 MB	0.1 KB/s	7.9 KB	23:59:54
4	news.bbs.geek.nz	2.4 MB	99.8 KB	2.5 MB	0.0 KB/s	2.9 KB	23:59:56
5	aioe	1.3 MB	101.3 KB	1.4 MB	0.0 KB/s	2.8 KB	23:59:55
6	usenet.pasdenom.info	991.0 KB	9.3 MB	10.3 MB	0.1 KB/s	5.4 KB	23:59:56
7	news.redatomik.org	367.9 KB	20.2 KB	388.0 KB	0.0 KB/s	3.3 KB	23:59:56
8	au2pb	236.3 KB	13.9 KB	250.2 KB	0.0 KB/s	2.8 KB	23:59:57
9	xmission	136.0 KB	9.4 KB	145.4 KB	0.0 KB/s	3.2 KB	23:59:57
10	weretis.net	65.0 KB	161.4 KB	226.5 KB	0.0 KB/s	8.4 KB	23:59:56
11	news.mixmin.net	52.5 KB	1.6 KB	54.2 KB	0.0 KB/s	3.9 KB	23:59:52
12	news.fcku.it	49.1 KB	0.0 KB	49.1 KB	0.0 KB/s	2.9 KB	22:40:49
13	eternal-september	31.2 KB	2.0 KB	33.3 KB	0.0 KB/s	2.1 KB	23:59:56
14	neva.ru	15.1 KB	717.2 KB	732.3 KB	0.0 KB/s	3.3 KB	23:59:56
15	news.swapon.de	7.4 KB	11.0 KB	18.4 KB	0.0 KB/s	2.3 KB	23:59:56
16	erje	5.1 KB	98.8 KB	103.8 KB	0.0 KB/s	3.8 KB	23:59:43
17	bwh	4.1 KB	10.7 KB	14.8 KB	0.0 KB/s	2.1 KB	23:59:48
TOTAL: 17		32.4 MB	10.7 MB	43.1 MB	0.0 KB/s	5.6 KB	406:39:15

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	55	130.1 KB	52	0	0	04:35:29
2	mtrlpq2314w-lp140-01-845526598.dsl.bell.ca	48	24	54.6 KB	13	0	0	00:35:46
3	71.94.21.184	10	5	9.5 KB	5	0	0	65:58:48
4	68-185-181-64.dhcp.mdsn.wi.charter.com	8	2	4.1 KB	2	2	2	02:48:30
5	tempe0.bbox.io	32	2	4.1 KB	1	0	0	04:52:11
6	dhcp-089-099-203-254.chello.nl	23	1	1.0 KB	1	0	0	10:43:24
TOTAL: 6		409	89	203.4 KB	74	2	2	124:50:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	304	60	139.6 KB	57	0	0	103:50:39
2	*.dsl.bell.ca	48	24	54.6 KB	13	0	0	00:35:46
3	*.dhcp.mdsn.wi.charter.com	8	2	4.1 KB	2	2	2	02:48:30
4	*.bbox.io	32	2	4.1 KB	1	0	0	04:52:11
5	*.chello.nl	23	1	1.0 KB	1	0	0	10:43:24
6	*.tampfl.dsl-w.verizon.net	5	0	0.0 KB	0	0	0	02:00:12
TOTAL: 6		420	89	203.4 KB	74	2	2	124:50:45

NNRP auth users [Top 20]:

User	Conn
moussa	288
ohlandl	2
TOTAL: 2	290

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71.94.21.184	8.967	14.345	0.000	65:58:48
162.212.88.221	0.550	0.531	0.000	33:16:00
dhcp-089-099-203-254.chello.nl	1.839	0.440	0.000	10:43:24
tempe0.bbox.io	2.733	0.560	0.000	04:52:11
202.89.176.220	27.114	7.227	0.691	04:35:29
68-185-181-64.dhcp.mdsn.wi.charter.com	0.648	0.181	0.006	02:48:30
pool-71-98-160-36.tampfl.dsl-w.verizon.net	0.335	0.068	0.000	02:00:12
mtrlpq2314w-lp140-01-845526598.dsl.bell.ca	7.115	2.663	0.000	00:35:46
196.52.22.18	0.212	0.260	0.000	00:00:19
125.64.94.200	0.093	0.017	0.000	00:00:03
TOTAL: 10	49.606	26.292	0.697	124:50:45

Curious NNRP server explorers [Top 20]:

System	Conn
pool-71-98-160-36.tampfl.dsl-w.verizon.net	5
162.212.88.221	4
125.64.94.200	1
196.52.22.18	1
TOTAL: 4	11

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	5
TOTAL: 1	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	20	0
tempe0.bbox.io	9	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
pool-71-98-160-36.tampfl.dsl-w.verizon.net	4	0
71.94.21.184	2	2
125.64.94.200	1	1
TOTAL: 6	40	3

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	60	67.4%
2	alt	25	28.1%
3	uk	2	2.2%
4	soc	1	1.1%
5	rec	1	1.1%
TOTAL: 5		89	100.0%

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

Newsgroup		Count
1	comp.os.vms	22
2	comp.arch	12
3	alt.drugs.heroin	10
4	comp.sys.ibm.ps2.hardware	7
5	comp.os.cpm	7
6	comp.sys.ibm.pc.games.action	3
7	alt.happy.valley	3
8	comp.ai.neural-nets	2
9	alt.sports.football.pro.sd-chargers	2
10	comp.sys.ibm.pc.games.rpg	2
11	comp.os.msdos.programmer	2
12	alt.comp.freeware	2
13	alt.binaries.test	1
14	alt.test	1
15	uk.rec.cycling	1
16	alt.binaries.teevee	1
17	alt.binaries.mp3	1
18	rec.arts.tv	1
19	alt.atheism	1
20	comp.sys.ibm.pc.games.war-historical	1
21	comp.ai.edu	1
22	uk.net.news.moderation	1
23	soc.culture.usa	1
24	comp.sys.ibm.pc.games.sports	1
25	alt.usage.english	1
26	alt.stupidity	1
27	alt.free.nntp	1
TOTAL: 27		89

Daily Usenet report for csiph.com

Feb 20 04:15:00 -- Feb 21 04:15:00