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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	49266	41.0%	9.6 MB	58.0%
nnrpd	43902	36.5%	3.9 MB	23.5%
innd	23838	19.8%	2.5 MB	15.4%
innfeed	3215	2.7%	525.8 KB	3.1%
nocem	84	0.1%	8.4 KB	0.0%
TOTAL: 5	120305	100.0%	16.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	583880	56.9%
Negative hits	392680	38.3%
Do not exist	47973	4.7%
Cache misses	1326	0.1%
TOTAL: 4	1025859	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.169	0.0%	752	0.000	1.555	15.000
article cleanup	00:00:00.315	0.0%	47939	0.000	0.007	0.023
article logging	00:00:00.787	0.0%	48448	0.000	0.016	0.219
article parse	00:00:02.605	0.0%	190071	0.001	0.014	0.279
article write	00:01:08.871	0.1%	28040	0.160	2.456	18.654
artlog/artcncl	00:00:00.006	0.0%	519	0.000	0.012	0.250
artlog/artparse	00:00:00.005	0.0%	297	0.000	0.017	1.000
data move	00:00:00.361	0.0%	990600	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.102	0.0%	745	0.000	0.137	2.222
hishave/artcncl	00:00:00.006	0.0%	752	0.000	0.008	0.200
hishave/artparse	00:00:00.001	0.0%	294	0.000	0.003	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:13.695	0.0%	1029020	0.001	0.013	0.094
history sync	00:00:02.919	0.0%	342	0.000	8.535	235.000
history write	00:01:18.261	0.1%	47885	0.083	1.634	18.026
hiswrite/artcncl	00:00:00.002	0.0%	7	0.000	0.286	0.333
hiswrite/artparse	00:00:00.982	0.0%	294	0.000	3.340	85.500
idle	23:53:18.718	99.5%	1012999	52.717	84.895	249.794
nntp read	00:00:03.423	0.0%	1023167	0.001	0.003	0.006
overview write	00:01:34.893	0.1%	28040	0.647	3.384	32.605
perl filter	00:01:55.722	0.1%	47851	1.592	2.418	3.907
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.470	0.0%	56159	0.000	0.008	0.034
TOTAL: 24:00:45.798	23:59:43.313	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	28585	0.000	0.002	0.024
article prepare	00:00:00.003	0.0%	34732	0.000	0.000	0.008
article read	00:00:00.639	0.0%	4433	0.000	0.144	1.700
backlog stats	00:00:00.321	0.0%	667631	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.506	0.0%	480795	0.002	0.005	0.011
data write	00:00:03.146	0.0%	466527	0.003	0.007	0.015
idle	23:53:18.703	100.0%	667631	72.328	128.812	371.206
status file	00:00:00.192	0.0%	305	0.000	0.630	1.000
TOTAL: 23:53:39.300	23:53:25.575	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:06.472	0.0%	103454	0.000	0.063	0.267
idle	85:44:58.230	99.5%	162251	78.688	1902.597	602114.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:20.500	0.0%	417445	0.000	0.049	25.165
readart	00:00:24.982	0.0%	15291	0.000	1.634	12.344
TOTAL: 86:08:32.448	85:45:50.184	99.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	33563	6185	24739	2639	18%	23:44:56
2	csiph-out.feeder.erje.net	1152	106148	5045	99352	1751	4%	94:59:16
3	feeder.eternal-september.org	7	41780	3841	36769	1170	9%	24:17:25
4	csiph-out.news.weretis.net	6	70337	3182	65747	1408	4%	50:01:15
5	news-out.aioe.org	3	27205	2407	24669	129	8%	24:09:39
6	newsfeed.neva.ru	138	60264	2382	51978	5904	3%	71:14:41
7	news-out.mixmin.net	3	32312	1644	28697	1971	5%	24:08:52
8	newsfeed.fu-berlin.de	74	31960	1309	30135	516	4%	36:36:47
9	news.fcku.it	1	8952	415	8537	0	4%	24:15:43
10	newsfeeds.xmission.com	1	30249	359	29865	25	1%	25:01:59
11	news.redatomik.org	1	22134	271	21855	8	1%	20:26:40
12	au2pb.net	1	34731	236	34433	62	0%	26:13:07
13	news.freedyn.net	5	31692	193	31340	159	0%	24:06:45
14	news.swapon.de	1	2594	154	2415	25	5%	23:18:07
15	xanadu-bbs.net	2	45666	103	41351	4212	0%	24:11:05
16	news.bbs.geek.nz	2	28908	22	28886	0	0%	23:59:35
17	nntp.pasdenom.info	4	28705	18	28685	2	0%	25:02:36
18	localhost	35	2	2	0	0	100%	00:00:01
TOTAL: 18		1724	637202	27768	589453	19981	4%	565:48:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.2 MB	67.3 KB	5.4 MB	24.7 MB	77%	3.7 KB
2	usenet.blueworldhosting.com	17.7 MB	14.1 KB	21.1 MB	38.8 MB	45%	4.5 KB
3	feeder.eternal-september.org	9.7 MB	8.3 KB	11.0 MB	20.7 MB	46%	4.2 KB
4	csiph-out.news.weretis.net	8.3 MB	20.0 KB	12.4 MB	20.7 MB	40%	4.6 KB
5	news-out.mixmin.net	6.9 MB	23.0 KB	3.3 MB	10.2 MB	67%	2.9 KB
6	newsfeed.neva.ru	6.7 MB	116.0 KB	45.8 MB	52.6 MB	12%	6.5 KB
7	news-out.aioe.org	5.9 MB	0.0 KB	142.4 KB	6.0 MB	97%	2.4 KB
8	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	1.1 MB	4.1 MB	71%	2.3 KB
9	newsfeeds.xmission.com	954.6 KB	0.0 KB	234.3 KB	1.2 MB	80%	3.1 KB
10	au2pb.net	799.0 KB	0.0 KB	456.8 KB	1.2 MB	63%	4.2 KB
11	news.fcku.it	761.4 KB	0.0 KB	0.0 KB	761.4 KB	100%	1.8 KB
12	news.freedyn.net	756.3 KB	12.2 KB	1.7 MB	2.5 MB	29%	7.2 KB
13	news.redatomik.org	611.1 KB	0.0 KB	15.8 KB	626.9 KB	97%	2.2 KB
14	news.swapon.de	385.3 KB	11.8 KB	81.9 KB	479.1 KB	80%	2.7 KB
15	xanadu-bbs.net	353.4 KB	43.4 KB	41.7 MB	42.0 MB	0%	10.0 KB
16	nntp.pasdenom.info	37.1 KB	0.0 KB	60.8 KB	97.9 KB	37%	4.9 KB
17	news.bbs.geek.nz	36.5 KB	0.0 KB	0.0 KB	36.5 KB	100%	1.7 KB
18	localhost	2.1 KB	0.0 KB	0.0 KB	2.1 KB	100%	1.0 KB
TOTAL: 18		81.9 MB	316.0 KB	144.5 MB	226.8 MB	36%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 2 04:15:00 - 04:59:59	392	1.4%	0.15	1.1 MB	1.4%	0.43
Mar 2 05:00:00 - 05:59:59	805	2.9%	0.22	3.7 MB	4.5%	1.05
Mar 2 06:00:00 - 06:59:59	533	1.9%	0.15	2.0 MB	2.4%	0.56
Mar 2 07:00:00 - 07:59:59	808	2.9%	0.22	2.5 MB	3.0%	0.70
Mar 2 08:00:00 - 08:59:59	1301	4.6%	0.36	3.8 MB	4.6%	1.09
Mar 2 09:00:00 - 09:59:59	1290	4.6%	0.36	3.2 MB	3.9%	0.92
Mar 2 10:00:00 - 10:59:59	1407	5.0%	0.39	3.6 MB	4.3%	1.02
Mar 2 11:00:00 - 11:59:59	1231	4.4%	0.34	3.4 MB	4.1%	0.96
Mar 2 12:00:00 - 12:59:59	1225	4.4%	0.34	3.8 MB	4.6%	1.07
Mar 2 13:00:00 - 13:59:59	1360	4.8%	0.38	3.8 MB	4.6%	1.08
Mar 2 14:00:00 - 14:59:59	1404	5.0%	0.39	3.7 MB	4.5%	1.05
Mar 2 15:00:00 - 15:59:59	1555	5.5%	0.43	4.6 MB	5.5%	1.30
Mar 2 16:00:00 - 16:59:59	1671	6.0%	0.46	5.6 MB	6.8%	1.60
Mar 2 17:00:00 - 17:59:59	1638	5.8%	0.46	4.6 MB	5.5%	1.30
Mar 2 18:00:00 - 18:59:59	1548	5.5%	0.43	5.0 MB	6.1%	1.43
Mar 2 19:00:00 - 19:59:59	1304	4.6%	0.36	3.3 MB	4.0%	0.93
Mar 2 20:00:00 - 20:59:59	1465	5.2%	0.41	4.4 MB	5.3%	1.25
Mar 2 21:00:00 - 21:59:59	1576	5.6%	0.44	4.1 MB	5.0%	1.17
Mar 2 22:00:00 - 22:59:59	1348	4.8%	0.37	4.0 MB	4.8%	1.12
Mar 2 23:00:00 - 23:59:59	1131	4.0%	0.31	3.2 MB	3.8%	0.90
Mar 3 00:00:00 - 00:59:59	832	3.0%	0.23	2.4 MB	2.9%	0.69
Mar 3 01:00:00 - 01:59:59	648	2.3%	0.18	1.9 MB	2.3%	0.53
Mar 3 02:00:00 - 02:59:59	766	2.7%	0.21	2.4 MB	2.9%	0.69
Mar 3 03:00:00 - 03:59:59	604	2.2%	0.17	2.0 MB	2.4%	0.56
Mar 3 04:00:00 - 04:15:00	230	0.8%	0.26	896.1 KB	1.1%	1.00
TOTAL: 24:00:00	28072	100.0%	0.32	82.9 MB	100.0%	0.98

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	5986	1376	18	4592
2	xanadu-bbs.net	4340	27	6	4307
3	usenet.blueworldhosting.com	2634	1225	1	1408
4	news-out.mixmin.net	1986	2	5	1979
5	csiph-out.feeder.erje.net	1776	438	15	1323
6	csiph-out.news.weretis.net	1411	290	3	1118
7	feeder.eternal-september.org	1240	178	3	1059
8	newsfeed.fu-berlin.de	843	39	0	804
9	news.freedyn.net	160	0	1	159
10	news-out.aioe.org	143	125	0	18
11	au2pb.net	77	57	0	20
12	news.swapon.de	51	0	5	46
13	newsfeeds.xmission.com	14	12	0	2
14	news.redatomik.org	9	0	0	9
15	news.fcku.it	5	0	0	5
16	nntp.pasdenom.info	2	0	0	2
TOTAL: 16		20677	3769	57	16851

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	899
ut.test	224
it-alt.sport.calcio.juventus	204
mailing.freebsd.ports-bugs	151
lada.talk	106
mailing.freebsd.svn-ports-all	98
mailing.freebsd.svn-ports-head	88
mailing.database.pgsql-general	67
mailing.freebsd.pkg-fallout	63
lada.auto	60
gmane.linux.alsa.devel	53
mailing.openssl.cvs	46
mailing.openbsd.ports-changes	41
mailing.postfix.users	41
mailing.gcc.gcc-cvs	40
spline.eisfair	38
mozilla.general	37
mailing.openbsd.source-changes	37
mailing.openbsd.tech	37
mailing.freebsd.svn-src-all	35
TOTAL: 270	3769

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11381
EMP (phn path)	2462
EMP (phl)	992
PFM bot misc	250
EMP (phn nph)	200
User-issued cancel	131
Binary: misplaced rar	70
Too many newsgroups	67
Scoring filter (25)	40
Scoring filter (20)	39
Scoring filter (9)	33
Scoring filter (18)	30
HTML Multipart	26
Scoring filter (16)	20
Scoring filter (23)	20
Scoring filter (10)	19
Scoring filter (26)	18
Scoring filter (24)	17
HTML Image Tags	15
Binary Payload	15
TOTAL: 43	16013

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	26
	usenet.blueworldhosting.com	5
	TOTAL: 2	31
Huge articles
	xanadu-bbs.net	147
	csiph-out.news.weretis.net	46
	usenet.blueworldhosting.com	42
	feeder.eternal-september.org	34
	news.freedyn.net	11
	news-out.mixmin.net	7
	newsfeed.fu-berlin.de	3
	au2pb.net	2
	nntp.pasdenom.info	1
	newsfeeds.xmission.com	1
	TOTAL: 10	294
Including strange strings
	newsfeed.fu-berlin.de	370
	csiph-out.news.weretis.net	29
	news.swapon.de	26
	csiph-out.feeder.erje.net	24
	feeder.eternal-september.org	23
	news-out.aioe.org	14
	news-out.mixmin.net	9
	newsfeed.neva.ru	6
	news.fcku.it	5
	news.redatomik.org	1
	TOTAL: 13	510
No colon-space in header
	usenet.blueworldhosting.com	3
	TOTAL: 1	3
TOTAL: 4		838

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	51015	2134	25001	39	1	4%	23:59:51
2	news.freedyn.net	31078	1081	26778	10	48	3%	23:59:51
3	xanadu-bbs	31120	1063	26892	11	9	3%	23:59:52
4	fu-berlin.de	26405	798	25601	0	0	3%	23:59:50
5	aioe	27035	522	22545	20	0	1%	23:59:51
6	erje	28261	516	22285	21	36	1%	23:59:52
7	usenet.pasdenom.info	35668	274	26396	1382	184	0%	23:59:51
8	news.redatomik.org	33264	229	26419	56	393	0%	23:59:51
9	au2pb	27750	125	26827	17	0	0%	23:59:42
10	xmission	33316	87	26749	5	0	0%	23:59:52
11	weretis.net	23853	48	23339	3	0	0%	23:59:52
12	news.mixmin.net	30076	38	24984	0	0	0%	23:59:51
13	eternal-september	28526	36	22614	3	0	0%	23:59:51
14	neva.ru	29858	11	23996	138	1	0%	23:59:43
15	news.fcku.it	3541	6	3096	0	0	0%	22:47:40
16	news.swapon.de	2626	5	2288	6	0	0%	23:57:54
17	bwh	22260	2	20824	2	0	0%	23:59:45
TOTAL: 17		465652	6975	376634	1713	672	1%	406:42:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.9 MB	138.9 KB	9.0 MB	0.1 KB/s	8.5 KB	23:59:51
2	xanadu-bbs	8.7 MB	54.2 KB	8.8 MB	0.1 KB/s	8.4 KB	23:59:52
3	fu-berlin.de	8.1 MB	0.0 KB	8.1 MB	0.1 KB/s	10.4 KB	23:59:50
4	news.bbs.geek.nz	5.6 MB	159.8 KB	5.7 MB	0.1 KB/s	2.7 KB	23:59:51
5	erje	1.5 MB	57.6 KB	1.5 MB	0.0 KB/s	3.0 KB	23:59:52
6	aioe	1.4 MB	64.1 KB	1.4 MB	0.0 KB/s	2.7 KB	23:59:51
7	usenet.pasdenom.info	1.0 MB	9.3 MB	10.4 MB	0.1 KB/s	6.4 KB	23:59:51
8	news.redatomik.org	755.3 KB	226.9 KB	982.1 KB	0.0 KB/s	3.4 KB	23:59:51
9	au2pb	376.9 KB	60.9 KB	437.9 KB	0.0 KB/s	3.1 KB	23:59:42
10	xmission	250.0 KB	12.5 KB	262.5 KB	0.0 KB/s	2.9 KB	23:59:52
11	weretis.net	152.5 KB	12.0 KB	164.5 KB	0.0 KB/s	3.2 KB	23:59:52
12	eternal-september	117.4 KB	13.9 KB	131.3 KB	0.0 KB/s	3.4 KB	23:59:51
13	news.mixmin.net	110.7 KB	0.0 KB	110.7 KB	0.0 KB/s	2.9 KB	23:59:51
14	neva.ru	30.5 KB	469.5 KB	500.1 KB	0.0 KB/s	3.4 KB	23:59:43
15	news.fcku.it	21.2 KB	0.0 KB	21.2 KB	0.0 KB/s	3.5 KB	22:47:40
16	news.swapon.de	7.0 KB	12.7 KB	19.7 KB	0.0 KB/s	1.8 KB	23:57:54
17	bwh	2.1 KB	2.8 KB	4.8 KB	0.0 KB/s	1.2 KB	23:59:45
TOTAL: 17		37.0 MB	10.6 MB	47.6 MB	0.0 KB/s	5.6 KB	406:42:59

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	5.86.237.104	7	215	332.9 KB	2	0	1	00:36:03
2	202.89.176.220	288	95	299.1 KB	88	0	0	04:39:54
3	mtrlpq2314w-lp140-01-845526598.dsl.bell.ca	48	30	53.8 KB	19	0	0	00:39:39
4	68-185-181-64.dhcp.mdsn.wi.charter.com	11	2	2.1 KB	2	2	2	03:32:51
5	tempe0.bbox.io	28	1	0.9 KB	1	0	0	07:28:13
TOTAL: 5		382	343	688.9 KB	112	2	3	86:08:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	307	310	632.1 KB	90	0	1	30:00:53
2	*.dsl.bell.ca	48	30	53.8 KB	19	0	0	00:39:39
3	*.dhcp.mdsn.wi.charter.com	11	2	2.1 KB	2	2	2	03:32:51
4	*.bbox.io	28	1	0.9 KB	1	0	0	07:28:13
5	*.dynamic.caiway.nl	24	0	0.0 KB	0	0	0	31:41:37
6	*.chello.nl	20	0	0.0 KB	0	0	0	08:40:34
7	*.nielsenmedia.com	4	0	0.0 KB	0	0	0	01:55:21
8	*.tampfl.dsl-w.verizon.net	7	0	0.0 KB	0	0	0	02:09:16
TOTAL: 8		449	343	688.9 KB	112	2	3	86:08:28

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
041-020-158-163.dynamic.caiway.nl	4.046	4.617	0.000	31:41:37
162.212.88.221	0.900	0.654	0.000	24:44:37
dhcp-089-099-203-254.chello.nl	1.668	0.282	0.000	08:40:34
tempe0.bbox.io	2.627	0.568	0.000	07:28:13
202.89.176.220	30.627	7.109	0.756	04:39:54
68-185-181-64.dhcp.mdsn.wi.charter.com	1.042	0.255	0.005	03:32:51
pool-71-98-160-36.tampfl.dsl-w.verizon.net	0.462	0.119	0.000	02:09:16
challenger.nielsenmedia.com	0.288	0.073	0.000	01:55:21
mtrlpq2314w-lp140-01-845526598.dsl.bell.ca	7.072	2.724	0.000	00:39:39
5.86.237.104	0.692	0.357	0.000	00:36:03
196.52.22.10	0.222	0.241	0.000	00:00:12
94.163.81.74	0.093	0.023	0.000	00:00:01
2.157.143.109	0.101	0.014	0.000	00:00:01
5.86.51.235	0.060	0.023	0.000	00:00:01
TOTAL: 14	49.900	17.059	0.761	86:08:28

Curious NNRP server explorers [Top 20]:

System	Conn
041-020-158-163.dynamic.caiway.nl	24
dhcp-089-099-203-254.chello.nl	20
162.212.88.221	8
pool-71-98-160-36.tampfl.dsl-w.verizon.net	7
challenger.nielsenmedia.com	4
94.163.81.74	1
5.86.51.235	1
2.157.143.109	1
196.52.22.10	1
TOTAL: 9	67

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	16	0
tempe0.bbox.io	12	0
68-185-181-64.dhcp.mdsn.wi.charter.com	5	0
pool-71-98-160-36.tampfl.dsl-w.verizon.net	4	0
challenger.nielsenmedia.com	3	0
5.86.237.104	1	0
TOTAL: 6	41	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	154	44.9%
2	comp	100	29.2%
3	aioe	84	24.5%
4	rec	2	0.6%
5	uk	1	0.3%
6	sci	1	0.3%
7	de	1	0.3%
TOTAL: 7		343	100.0%

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

Newsgroup		Count
1	alt.de.test	131
2	aioe.test.tor	84
3	comp.os.vms	19
4	comp.arch	14
5	comp.arch.embedded	14
6	alt.drugs.heroin	13
7	comp.sys.ibm.as400.misc	9
8	comp.os.cpm	8
9	comp.os.os2.apps	7
10	alt.happy.valley	6
11	comp.sys.ibm.ps2.hardware	5
12	comp.ai.neural-nets	5
13	comp.os.linux.networking	4
14	comp.sys.ibm.pc.games.action	3
15	comp.ai.philosophy	2
16	comp.os.os2.misc	2
17	comp.sys.hp.hardware	1
18	sci.electronics.basics	1
19	comp.ai.nat-lang	1
20	de.alt.folklore.ddr	1
21	alt.home.repair	1
22	comp.os.msdos.djgpp	1
23	comp.os.os2.programmer.tools	1
24	uk.legal	1
25	alt.atheism	1
26	rec.arts.tv	1
27	alt.agnosticism	1
28	comp.ai.genetic	1
29	rec.music.classical.recordings	1
30	comp.lang.javascript	1
31	comp.ai.alife	1
32	comp.lang.python	1
33	alt.usage.english	1
TOTAL: 33		343

Daily Usenet report for csiph.com

Mar 2 04:15:00 -- Mar 3 04:15:00