Daily Usenet report for csiph.com: Jan 31 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	46744	42.6%	8.7 MB	58.9%
nnrpd	33742	30.8%	2.9 MB	19.4%
innd	25808	23.5%	2.7 MB	18.1%
innfeed	3215	2.9%	535.1 KB	3.5%
nocem	111	0.1%	11.4 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	109621	100.0%	14.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	506704	55.9%
Negative hits	352948	38.9%
Do not exist	45956	5.1%
Cache misses	705	0.1%
TOTAL: 4	906313	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.524	0.0%	277	0.000	1.892	31.000
article cleanup	00:00:00.318	0.0%	45806	0.000	0.007	0.028
article logging	00:00:00.722	0.0%	46321	0.000	0.016	0.172
article parse	00:00:02.560	0.0%	204275	0.000	0.013	0.070
article write	00:01:08.216	0.1%	25046	0.237	2.724	7.851
artlog/artcncl	00:00:00.003	0.0%	190	0.000	0.016	0.125
artlog/artparse	00:00:00.005	0.0%	256	0.000	0.020	0.333
data move	00:00:00.340	0.0%	914287	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.067	0.0%	259	0.000	0.259	29.000
hishave/artcncl	00:00:00.011	0.0%	277	0.000	0.040	1.333
hishave/artparse	00:00:00.001	0.0%	243	0.000	0.004	0.032
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:27.155	0.0%	907170	0.002	0.030	0.177
history sync	00:00:05.157	0.0%	356	0.000	14.486	243.667
history write	00:02:44.105	0.2%	45765	0.071	3.586	21.757
hiswrite/artcncl	00:00:00.073	0.0%	18	0.000	4.056	31.000
hiswrite/artparse	00:00:00.961	0.0%	243	0.000	3.955	48.000
idle	23:51:14.751	99.3%	928750	51.527	92.463	262.687
nntp read	00:00:03.028	0.0%	940666	0.002	0.003	0.005
overview write	00:02:04.545	0.1%	25046	0.792	4.973	32.849
perl filter	00:02:05.404	0.1%	45697	1.418	2.744	4.592
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.463	0.0%	50192	0.000	0.009	0.022
TOTAL: 24:00:57.414	23:59:58.409	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.077	0.0%	26306	0.000	0.003	0.018
article prepare	00:00:00.006	0.0%	46168	0.000	0.000	0.009
article read	00:00:00.882	0.0%	6096	0.000	0.145	7.833
backlog stats	00:00:00.295	0.0%	610258	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.282	0.0%	435818	0.002	0.005	0.009
data write	00:00:03.012	0.0%	416014	0.004	0.007	0.013
idle	23:53:39.579	100.0%	610258	57.664	140.956	380.234
status file	00:00:00.213	0.0%	282	0.000	0.755	5.000
TOTAL: 23:53:57.610	23:53:46.346	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:30.615	0.0%	138605	0.011	0.221	1.937
idle	131:00:36.068	100.0%	171700	74.500	2746.861	600175.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.946	0.0%	344306	0.000	0.006	0.167
readart	00:00:00.180	0.0%	86	0.000	2.093	26.333
TOTAL: 131:02:43.650	131:01:08.809	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	29725	5542	21463	2720	18%	23:44:55
2	csiph-out.feeder.erje.net	1152	89397	4398	83229	1770	4%	94:58:44
3	feeder.eternal-september.org	8	39963	2922	35120	1921	7%	24:28:36
4	csiph-out.news.weretis.net	4	54210	2906	51061	243	5%	51:13:24
5	news-out.aioe.org	2	23704	2699	20955	50	11%	24:09:04
6	newsfeed.neva.ru	143	59193	1665	51160	6368	2%	72:30:13
7	newsfeed.fu-berlin.de	26	26466	1265	25004	197	4%	27:04:10
8	news-out.mixmin.net	3	29008	1230	26209	1569	4%	24:08:50
9	news.redatomik.org	5	27966	873	27092	1	3%	27:39:36
10	news.freedyn.net	3	28903	374	28090	439	1%	24:08:50
11	newsfeeds.xmission.com	1	26353	334	26008	11	1%	25:57:05
12	news.fcku.it	1	7325	330	6989	6	4%	24:23:24
13	xanadu-bbs.net	1	45225	318	39273	5634	0%	25:05:54
14	au2pb.net	1	24891	121	24730	40	0%	24:24:16
15	news.swapon.de	4	2352	101	2249	2	4%	25:32:37
16	nntp.pasdenom.info	2	23479	46	23426	7	0%	23:59:31
17	news.bbs.geek.nz	2	23611	3	23608	0	0%	23:59:43
18	localhost	38	0	0	0	0	0%	00:00:00
TOTAL: 18		1684	561771	25127	515666	20978	4%	567:28:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	16.1 MB	5.5 KB	28.6 MB	44.8 MB	36%	5.5 KB
2	csiph-out.feeder.erje.net	15.8 MB	63.9 KB	6.0 MB	21.9 MB	72%	3.6 KB
3	csiph-out.news.weretis.net	8.8 MB	0.0 KB	9.3 MB	18.1 MB	48%	5.9 KB
4	feeder.eternal-september.org	6.8 MB	64.2 KB	22.5 MB	29.4 MB	23%	6.2 KB
5	news-out.aioe.org	6.4 MB	1.5 KB	67.7 KB	6.5 MB	98%	2.4 KB
6	newsfeed.neva.ru	4.8 MB	173.2 KB	58.6 MB	63.6 MB	7%	8.1 KB
7	news-out.mixmin.net	4.4 MB	4.8 KB	2.2 MB	6.6 MB	66%	2.4 KB
8	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	471.9 KB	3.2 MB	85%	2.3 KB
9	news.redatomik.org	1.7 MB	0.0 KB	2.6 KB	1.7 MB	99%	2.0 KB
10	news.freedyn.net	1.2 MB	0.0 KB	2.4 MB	3.6 MB	34%	4.5 KB
11	newsfeeds.xmission.com	1.2 MB	0.0 KB	175.2 KB	1.4 MB	87%	4.1 KB
12	xanadu-bbs.net	874.1 KB	13.0 KB	55.3 MB	56.2 MB	1%	9.7 KB
13	news.fcku.it	569.0 KB	0.0 KB	8.5 KB	577.6 KB	98%	1.7 KB
14	au2pb.net	382.5 KB	0.0 KB	248.2 KB	630.7 KB	60%	3.9 KB
15	news.swapon.de	249.7 KB	0.0 KB	4.5 KB	254.2 KB	98%	2.5 KB
16	nntp.pasdenom.info	94.5 KB	0.0 KB	18.0 KB	112.5 KB	83%	2.1 KB
17	news.bbs.geek.nz	5.1 KB	0.0 KB	0.0 KB	5.1 KB	100%	1.7 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		72.1 MB	326.0 KB	186.0 MB	258.4 MB	27%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 31 04:15:00 - 04:59:59	339	1.4%	0.13	1.1 MB	1.6%	0.43
Jan 31 05:00:00 - 05:59:59	495	2.0%	0.14	1.7 MB	2.4%	0.49
Jan 31 06:00:00 - 06:59:59	495	2.0%	0.14	1.6 MB	2.2%	0.45
Jan 31 07:00:00 - 07:59:59	668	2.7%	0.19	2.1 MB	2.9%	0.59
Jan 31 08:00:00 - 08:59:59	739	3.0%	0.21	2.2 MB	3.1%	0.64
Jan 31 09:00:00 - 09:59:59	1093	4.4%	0.30	3.2 MB	4.5%	0.92
Jan 31 10:00:00 - 10:59:59	1139	4.5%	0.32	3.5 MB	4.9%	1.00
Jan 31 11:00:00 - 11:59:59	1162	4.6%	0.32	3.0 MB	4.2%	0.87
Jan 31 12:00:00 - 12:59:59	1169	4.7%	0.32	3.2 MB	4.4%	0.91
Jan 31 13:00:00 - 13:59:59	1267	5.1%	0.35	3.7 MB	5.1%	1.04
Jan 31 14:00:00 - 14:59:59	1271	5.1%	0.35	3.3 MB	4.6%	0.94
Jan 31 15:00:00 - 15:59:59	1349	5.4%	0.37	3.9 MB	5.5%	1.12
Jan 31 16:00:00 - 16:59:59	1383	5.5%	0.38	4.1 MB	5.7%	1.17
Jan 31 17:00:00 - 17:59:59	1436	5.7%	0.40	3.6 MB	5.0%	1.03
Jan 31 18:00:00 - 18:59:59	1409	5.6%	0.39	3.7 MB	5.1%	1.05
Jan 31 19:00:00 - 19:59:59	1440	5.8%	0.40	4.0 MB	5.5%	1.12
Jan 31 20:00:00 - 20:59:59	1460	5.8%	0.41	4.4 MB	6.0%	1.24
Jan 31 21:00:00 - 21:59:59	1754	7.0%	0.49	4.7 MB	6.5%	1.34
Jan 31 22:00:00 - 22:59:59	1267	5.1%	0.35	3.5 MB	4.8%	0.98
Jan 31 23:00:00 - 23:59:59	1027	4.1%	0.29	2.8 MB	3.8%	0.78
Feb 1 00:00:00 - 00:59:59	863	3.4%	0.24	2.9 MB	4.0%	0.82
Feb 1 01:00:00 - 01:59:59	628	2.5%	0.17	1.9 MB	2.6%	0.53
Feb 1 02:00:00 - 02:59:59	514	2.1%	0.14	1.7 MB	2.4%	0.49
Feb 1 03:00:00 - 03:59:59	526	2.1%	0.15	1.8 MB	2.5%	0.52
Feb 1 04:00:00 - 04:15:00	146	0.6%	0.16	649.3 KB	0.9%	0.72
TOTAL: 24:00:00	25039	100.0%	0.29	72.1 MB	100.0%	0.85

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	6439	778	35	0	5626
2	xanadu-bbs.net	5544	37	1	0	5506
3	usenet.blueworldhosting.com	2723	980	2	2	1739
4	feeder.eternal-september.org	1949	114	5	0	1830
5	csiph-out.feeder.erje.net	1783	261	7	1	1514
6	news-out.mixmin.net	1591	2	3	0	1586
7	newsfeed.fu-berlin.de	598	32	0	0	566
8	news.freedyn.net	437	1	0	0	436
9	csiph-out.news.weretis.net	290	170	0	0	120
10	news-out.aioe.org	75	43	1	0	31
11	au2pb.net	25	25	0	0	0
12	news.swapon.de	19	0	0	0	19
13	newsfeeds.xmission.com	16	10	0	0	6
14	news.redatomik.org	11	0	0	0	11
15	news.fcku.it	8	0	0	0	8
16	nntp.pasdenom.info	7	6	0	0	1
TOTAL: 16		21515	2459	54	3	18999

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	233
ut.test	157
mailing.freebsd.ports-bugs	129
mailing.freebsd.svn-ports-all	106
mailing.freebsd.svn-ports-head	105
lada.talk	100
mailing.freebsd.pkg-fallout	100
it-alt.sport.calcio.juventus	77
lada.auto	59
pld.cvs.commit	58
24hoursupport.helpdesk	58
alt.binaries.multimedia.tatu	51
mailing.openssl.cvs	46
mailing.openbsd.misc	34
alt.survival	32
mailing.freebsd.svn-src-all	31
fa.freebsd.cvs-all	31
alt.windows7.general	29
mailing.openbsd.tech	28
fa.openbsd.tech	28
TOTAL: 236	2459

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

Groups	Count
alt.culture.china	1
alt.binaries.sounds.mp3.jazz.vocals	1
sci.med.orthopedics	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13527
EMP (phn path)	2703
EMP (phl)	945
Binary: misplaced rar	321
EMP (phn nph)	232
PFM bot misc	222
Too many newsgroups	53
User-issued cancel	37
Binary Payload	24
HTML Multipart	17
Scoring filter (20)	13
Scoring filter (12)	9
Scoring filter (16)	9
Scoring filter (11)	8
HTML post	8
Scoring filter (10)	7
Scoring filter (8)	7
Scoring filter (19)	6
Binary: misplaced par2	5
Too many newsgroups (meow)	5
TOTAL: 28	18173

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	100	0	123	123
TOTAL: 1	100	0	123	123

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	50
	usenet.blueworldhosting.com	5
	TOTAL: 2	55
Bad Message-ID's offered
	csiph-out.feeder.erje.net	10
	usenet.blueworldhosting.com	3
	TOTAL: 2	13
Huge articles
	usenet.blueworldhosting.com	90
	csiph-out.news.weretis.net	65
	xanadu-bbs.net	41
	feeder.eternal-september.org	33
	news.freedyn.net	12
	newsfeed.fu-berlin.de	1
	newsfeeds.xmission.com	1
	TOTAL: 7	243
Including strange strings
	newsfeed.fu-berlin.de	387
	news-out.aioe.org	29
	csiph-out.news.weretis.net	23
	news.swapon.de	17
	csiph-out.feeder.erje.net	16
	news-out.mixmin.net	11
	feeder.eternal-september.org	10
	news.redatomik.org	7
	news.fcku.it	7
	newsfeed.neva.ru	5
	TOTAL: 11	515
No colon-space in header
	usenet.blueworldhosting.com	13
	TOTAL: 1	13
TOTAL: 5		839

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	erje	24927	3590	16828	107	0	14%	23:59:54
2	news.freedyn.net	27374	1484	23169	4	0	5%	23:59:53
3	xanadu-bbs	26552	1381	23334	0	12	5%	23:59:54
4	fu-berlin.de	23431	1212	22219	0	0	5%	23:59:43
5	news.bbs.geek.nz	45512	748	22986	9	0	1%	23:59:49
6	aioe	23385	472	19595	29	0	2%	23:59:42
7	usenet.pasdenom.info	31958	243	22953	1796	4	0%	23:59:53
8	news.redatomik.org	28227	157	22857	10	1301	0%	23:59:53
9	au2pb	24321	95	23539	9	0	0%	23:59:50
10	xmission	29599	28	23302	2	0	0%	23:59:48
11	neva.ru	26719	21	21729	121	0	0%	23:59:53
12	eternal-september	24691	11	19872	0	0	0%	23:58:58
13	news.mixmin.net	26281	11	22166	1	0	0%	23:59:52
14	news.fcku.it	2560	8	2189	0	0	0%	22:16:17
15	weretis.net	20794	2	20398	1	2	0%	23:59:53
16	bwh	19486	1	18117	0	0	0%	23:59:54
17	news.swapon.de	2503	0	2244	2	0	0%	23:57:31
TOTAL: 17		408320	9464	327497	2091	1319	2%	406:10:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	erje	10.6 MB	586.1 KB	11.2 MB	0.1 KB/s	3.1 KB	23:59:54
2	news.freedyn.net	9.3 MB	7.3 KB	9.4 MB	0.1 KB/s	6.4 KB	23:59:53
3	xanadu-bbs	8.9 MB	0.0 KB	8.9 MB	0.1 KB/s	6.6 KB	23:59:54
4	fu-berlin.de	8.3 MB	0.0 KB	8.3 MB	0.1 KB/s	7.0 KB	23:59:43
5	news.bbs.geek.nz	2.3 MB	24.0 KB	2.3 MB	0.0 KB/s	3.1 KB	23:59:49
6	aioe	1.4 MB	152.6 KB	1.5 MB	0.0 KB/s	3.1 KB	23:59:42
7	usenet.pasdenom.info	1022.4 KB	9.3 MB	10.3 MB	0.1 KB/s	5.2 KB	23:59:53
8	news.redatomik.org	508.7 KB	45.8 KB	554.5 KB	0.0 KB/s	3.3 KB	23:59:53
9	au2pb	434.6 KB	81.3 KB	515.8 KB	0.0 KB/s	5.0 KB	23:59:50
10	neva.ru	87.2 KB	429.1 KB	516.3 KB	0.0 KB/s	3.6 KB	23:59:53
11	xmission	79.3 KB	5.0 KB	84.3 KB	0.0 KB/s	2.8 KB	23:59:48
12	news.mixmin.net	67.3 KB	2.3 KB	69.7 KB	0.0 KB/s	5.8 KB	23:59:52
13	eternal-september	36.2 KB	0.0 KB	36.2 KB	0.0 KB/s	3.3 KB	23:58:58
14	news.fcku.it	28.1 KB	0.0 KB	28.1 KB	0.0 KB/s	3.5 KB	22:16:17
15	weretis.net	6.0 KB	1.2 KB	7.2 KB	0.0 KB/s	2.4 KB	23:59:53
16	bwh	1.7 KB	0.0 KB	1.7 KB	0.0 KB/s	1.7 KB	23:59:54
17	news.swapon.de	0.0 KB	3.8 KB	3.8 KB	0.0 KB/s	1.9 KB	23:57:31
TOTAL: 17		43.0 MB	10.6 MB	53.6 MB	0.0 KB/s	4.7 KB	406:10:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	68	187.1 KB	64	04:22:33
2	pool-71-98-160-84.tampfl.dsl-w.verizon.net	9	8	17.3 KB	3	01:21:00
3	challenger.nielsenmedia.com	4	8	17.8 KB	2	01:00:28
4	tempe0.bbox.io	21	2	6.0 KB	1	06:27:53
TOTAL: 4		322	86	228.2 KB	70	131:02:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	318	68	187.1 KB	64	24:04:33
2	*.nielsenmedia.com	4	8	17.8 KB	2	01:00:28
3	*.tampfl.dsl-w.verizon.net	9	8	17.3 KB	3	01:21:00
4	*.bbox.io	21	2	6.0 KB	1	06:27:53
5	*.chello.nl	64	0	0.0 KB	0	27:40:59
6	*.159.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	00:00:00
TOTAL: 6		417	86	228.2 KB	70	131:02:39

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
h208.137.22.98.dynamic.ip.windstream.net	0.816	1.303	0.000	70:27:43
dhcp-089-099-203-254.chello.nl	5.336	1.126	0.000	27:40:59
71.94.21.184	5.071	5.572	0.000	19:42:00
tempe0.bbox.io	1.806	0.366	0.000	06:27:53
202.89.176.220	29.284	6.695	0.731	04:22:33
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.877	0.187	0.000	01:21:00
challenger.nielsenmedia.com	0.451	0.063	0.000	01:00:28
TOTAL: 7	43.641	15.312	0.731	131:02:39

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	64
71.94.21.184	30
h62.159.22.98.dynamic.ip.windstream.net	1
TOTAL: 3	95

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	30
TOTAL: 1	30

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	30
TOTAL: 1	30

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	51	0
tempe0.bbox.io	9	0
challenger.nielsenmedia.com	2	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
h208.137.22.98.dynamic.ip.windstream.net	1	0
TOTAL: 5	65	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	84	97.7%
2	news	2	2.3%
TOTAL: 2		86	100.0%

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

Newsgroup		Count
1	comp.arch	35
2	comp.lang.awk	16
3	comp.ai.philosophy	15
4	comp.os.vms	4
5	comp.sys.ibm.pc.games.action	3
6	comp.os.os2.apps	3
7	comp.sys.ibm.pc.hardware.storage	2
8	comp.os.os2.ecomstation	2
9	news.software.nntp	2
10	comp.sys.ibm.pc.games.war-historical	2
11	comp.os.msdos.djgpp	1
12	comp.sys.ibm.pc.games.naval	1
TOTAL: 12		86

Daily Usenet report for csiph.com

Jan 31 04:15:00 -- Feb 1 04:15:00