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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53449	46.2%	10.3 MB	63.0%
nnrpd	33518	28.9%	2.8 MB	17.4%
innd	25546	22.1%	2.6 MB	16.2%
innfeed	3216	2.8%	560.6 KB	3.4%
nocem	61	0.1%	6.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	115791	100.0%	16.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	618364	56.3%
Negative hits	426244	38.8%
Do not exist	52178	4.8%
Cache misses	1096	0.1%
TOTAL: 4	1097882	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.629	0.0%	538	0.000	3.028	20.667
article cleanup	00:00:00.323	0.0%	52216	0.000	0.006	0.026
article logging	00:00:00.817	0.0%	52764	0.000	0.015	0.130
article parse	00:00:02.686	0.0%	224390	0.003	0.012	0.114
article write	00:01:02.189	0.1%	30128	0.254	2.064	5.560
artlog/artcncl	00:00:00.006	0.0%	474	0.000	0.013	0.500
artlog/artparse	00:00:00.004	0.0%	299	0.000	0.013	0.500
data move	00:00:00.404	0.0%	1082173	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.071	0.0%	537	0.000	0.132	8.000
hishave/artcncl	00:00:00.005	0.0%	538	0.000	0.009	0.017
hishave/artparse	00:00:00.003	0.0%	290	0.000	0.010	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:29.424	0.0%	1101816	0.001	0.027	0.119
history sync	00:00:03.378	0.0%	341	0.000	9.906	66.000
history write	00:02:24.077	0.2%	52181	0.116	2.761	9.264
hiswrite/artcncl	00:00:00.005	0.0%	1	5.000	5.000	5.000
hiswrite/artparse	00:00:00.840	0.0%	288	0.000	2.917	37.000
idle	23:50:56.728	99.3%	1092868	46.319	78.561	206.116
nntp read	00:00:03.489	0.0%	1105967	0.002	0.003	0.006
overview write	00:02:10.361	0.2%	30128	0.610	4.327	61.612
perl filter	00:02:17.907	0.2%	52132	1.621	2.645	4.233
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.513	0.0%	60309	0.000	0.009	0.040
TOTAL: 24:00:40.304	23:59:34.859	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.084	0.0%	30120	0.000	0.003	0.025
article prepare	00:00:00.004	0.0%	31284	0.000	0.000	0.005
article read	00:00:00.615	0.0%	3552	0.000	0.173	5.889
backlog stats	00:00:00.339	0.0%	706242	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.570	0.0%	514122	0.002	0.005	0.008
data write	00:00:03.329	0.0%	496172	0.004	0.007	0.013
idle	23:52:44.160	100.0%	706242	69.807	121.721	318.283
status file	00:00:00.205	0.0%	287	0.000	0.714	3.000
TOTAL: 23:53:04.089	23:52:51.306	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:09.322	0.0%	18600	0.000	0.501	0.901
idle	71:28:20.841	100.0%	51631	68.000	4983.456	600225.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.792	0.0%	195701	0.000	0.014	0.582
readart	00:00:11.080	0.0%	4469	0.000	2.479	49.000
TOTAL: 71:29:59.130	71:28:44.035	100.0%	-	-	-	-

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	35812	6491	26373	2948	18%	23:45:01
2	feeder.eternal-september.org	9	47390	4101	40786	2503	8%	26:05:02
3	csiph-out.feeder.erje.net	1152	114189	3784	108561	1844	3%	95:03:40
4	csiph-out.news.weretis.net	3	65879	3471	61989	419	5%	49:19:36
5	news-out.mixmin.net	3	33849	2738	29320	1791	8%	24:08:52
6	news-out.aioe.org	3	28907	2595	26263	49	8%	24:19:54
7	newsfeed.neva.ru	144	64782	2264	55489	7029	3%	71:51:58
8	newsfeed.fu-berlin.de	66	34737	1411	33066	260	4%	36:06:01
9	news.freedyn.net	3	33792	709	32804	279	2%	24:09:54
10	newsfeeds.xmission.com	1	34133	689	33431	13	2%	28:36:34
11	news.redatomik.org	2	26950	671	26218	61	2%	22:14:31
12	xanadu-bbs.net	1	49876	516	44177	5183	1%	23:36:32
13	news.fcku.it	1	10224	495	9724	5	4%	25:45:55
14	news.swapon.de	5	2458	177	2273	8	7%	24:14:06
15	au2pb.net	1	35458	94	35333	31	0%	26:13:07
16	nntp.pasdenom.info	3	30430	36	30389	5	0%	24:08:36
17	news.bbs.geek.nz	2	24293	4	24289	0	0%	20:14:13
18	localhost	35	0	0	0	0	0%	00:00:00
TOTAL: 18		1722	673159	30246	620485	22428	4%	569:53:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	17.9 MB	0.9 KB	28.3 MB	46.2 MB	38%	5.0 KB
2	csiph-out.feeder.erje.net	15.2 MB	176.0 KB	5.3 MB	20.7 MB	73%	3.8 KB
3	csiph-out.news.weretis.net	10.9 MB	0.0 KB	6.6 MB	17.6 MB	62%	4.6 KB
4	feeder.eternal-september.org	10.0 MB	5.3 KB	31.1 MB	41.1 MB	24%	6.4 KB
5	news-out.mixmin.net	8.8 MB	32.0 KB	3.1 MB	12.0 MB	73%	2.7 KB
6	newsfeed.neva.ru	6.4 MB	124.5 KB	56.4 MB	62.9 MB	10%	6.9 KB
7	news-out.aioe.org	6.2 MB	0.0 KB	85.0 KB	6.3 MB	98%	2.4 KB
8	newsfeed.fu-berlin.de	3.1 MB	1.8 KB	594.1 KB	3.7 MB	84%	2.3 KB
9	news.freedyn.net	2.8 MB	0.0 KB	1.2 MB	4.0 MB	69%	4.1 KB
10	newsfeeds.xmission.com	2.1 MB	3.6 KB	288.9 KB	2.4 MB	88%	3.5 KB
11	news.redatomik.org	1.4 MB	0.0 KB	2.1 MB	3.5 MB	40%	4.9 KB
12	xanadu-bbs.net	1.3 MB	0.0 KB	51.6 MB	52.9 MB	2%	9.5 KB
13	news.fcku.it	902.0 KB	0.0 KB	9.6 KB	911.6 KB	98%	1.8 KB
14	news.swapon.de	436.8 KB	0.0 KB	51.9 KB	488.7 KB	89%	2.6 KB
15	au2pb.net	270.5 KB	4.9 KB	143.2 KB	418.7 KB	64%	3.3 KB
16	nntp.pasdenom.info	72.7 KB	0.0 KB	120.6 KB	193.3 KB	37%	4.7 KB
17	news.bbs.geek.nz	4.7 KB	0.0 KB	0.0 KB	4.7 KB	100%	1.2 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		87.9 MB	349.0 KB	187.1 MB	275.4 MB	31%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 2 04:15:00 - 04:59:59	421	1.4%	0.16	1.5 MB	1.7%	0.56
Feb 2 05:00:00 - 05:59:59	839	2.8%	0.23	3.3 MB	3.8%	0.94
Feb 2 06:00:00 - 06:59:59	609	2.0%	0.17	1.8 MB	2.1%	0.52
Feb 2 07:00:00 - 07:59:59	836	2.8%	0.23	2.1 MB	2.4%	0.61
Feb 2 08:00:00 - 08:59:59	1188	3.9%	0.33	3.6 MB	4.1%	1.02
Feb 2 09:00:00 - 09:59:59	1402	4.7%	0.39	4.0 MB	4.6%	1.14
Feb 2 10:00:00 - 10:59:59	1475	4.9%	0.41	4.5 MB	5.2%	1.29
Feb 2 11:00:00 - 11:59:59	1204	4.0%	0.33	3.3 MB	3.7%	0.93
Feb 2 12:00:00 - 12:59:59	1285	4.3%	0.36	3.3 MB	3.7%	0.93
Feb 2 13:00:00 - 13:59:59	1429	4.7%	0.40	3.9 MB	4.4%	1.10
Feb 2 14:00:00 - 14:59:59	1654	5.5%	0.46	4.3 MB	4.9%	1.22
Feb 2 15:00:00 - 15:59:59	1698	5.6%	0.47	4.6 MB	5.3%	1.32
Feb 2 16:00:00 - 16:59:59	1798	6.0%	0.50	5.9 MB	6.7%	1.68
Feb 2 17:00:00 - 17:59:59	1860	6.2%	0.52	5.2 MB	5.9%	1.48
Feb 2 18:00:00 - 18:59:59	1947	6.5%	0.54	6.5 MB	7.4%	1.84
Feb 2 19:00:00 - 19:59:59	1694	5.6%	0.47	4.5 MB	5.1%	1.27
Feb 2 20:00:00 - 20:59:59	1623	5.4%	0.45	4.8 MB	5.5%	1.36
Feb 2 21:00:00 - 21:59:59	1777	5.9%	0.49	4.9 MB	5.6%	1.39
Feb 2 22:00:00 - 22:59:59	1417	4.7%	0.39	3.7 MB	4.3%	1.07
Feb 2 23:00:00 - 23:59:59	1058	3.5%	0.29	2.8 MB	3.2%	0.79
Feb 3 00:00:00 - 00:59:59	855	2.8%	0.24	2.5 MB	2.9%	0.72
Feb 3 01:00:00 - 01:59:59	664	2.2%	0.18	2.0 MB	2.3%	0.57
Feb 3 02:00:00 - 02:59:59	599	2.0%	0.17	1.8 MB	2.0%	0.50
Feb 3 03:00:00 - 03:59:59	633	2.1%	0.18	2.0 MB	2.2%	0.56
Feb 3 04:00:00 - 04:15:00	170	0.6%	0.19	838.3 KB	0.9%	0.93
TOTAL: 24:00:00	30135	100.0%	0.35	87.5 MB	100.0%	1.04

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	6999	1742	13	5244
2	xanadu-bbs.net	5186	37	0	5149
3	usenet.blueworldhosting.com	2942	1320	1	1621
4	feeder.eternal-september.org	2433	160	2	2271
5	csiph-out.feeder.erje.net	1861	484	22	1355
6	news-out.mixmin.net	1828	2	4	1822
7	newsfeed.fu-berlin.de	654	60	1	593
8	csiph-out.news.weretis.net	423	294	0	129
9	news.freedyn.net	277	2	0	275
10	news.redatomik.org	65	0	0	65
11	news-out.aioe.org	59	47	0	12
12	au2pb.net	57	49	0	8
13	news.swapon.de	31	0	0	31
14	newsfeeds.xmission.com	10	7	1	2
15	news.fcku.it	10	0	0	10
16	nntp.pasdenom.info	5	2	0	3
TOTAL: 16		22840	4206	44	18590

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1084
lada.auto	213
mailing.freebsd.svn-ports-all	148
mailing.freebsd.ports-bugs	143
mailing.freebsd.svn-ports-head	139
ut.test	138
mailing.freebsd.pkg-fallout	137
lada.talk	131
mailing.openssl.dev	124
alt.binaries.multimedia.tatu	78
mailing.freebsd.svn-src-all	71
fa.freebsd.cvs-all	69
mailing.freebsd.svn-src-head	68
mpc.lists.nanog	56
alt.politics.scorched-earth	54
it-alt.sport.calcio.juventus	46
24hoursupport.helpdesk	42
mailing.openssl.cvs	32
spline.eisfair	31
pld.cvs.commit	31
TOTAL: 271	4206

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13122
EMP (phn path)	2593
EMP (phl)	795
PFM bot misc	464
EMP (phn nph)	204
Binary: misplaced rar	192
Too many newsgroups	61
HTML Multipart	38
User-issued cancel	25
Scoring filter (19)	23
Scoring filter (21)	22
Too many newsgroups (meow)	22
Scoring filter (18)	22
Binary Payload	21
Scoring filter (12)	17
Scoring filter (16)	11
Scoring filter (8)	10
Binary Image: misplaced jpg	9
Scoring filter (11)	8
Scoring filter (17)	8
TOTAL: 38	17701

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	52	0	53	53
TOTAL: 1	52	0	53	53

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	35
	usenet.blueworldhosting.com	5
	TOTAL: 2	40
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	usenet.blueworldhosting.com	1
	TOTAL: 2	5
Huge articles
	usenet.blueworldhosting.com	100
	xanadu-bbs.net	90
	csiph-out.news.weretis.net	38
	feeder.eternal-september.org	33
	news-out.mixmin.net	9
	news.redatomik.org	8
	news.freedyn.net	5
	au2pb.net	4
	nntp.pasdenom.info	2
	newsfeeds.xmission.com	1
	TOTAL: 11	291
Including strange strings
	newsfeed.fu-berlin.de	401
	feeder.eternal-september.org	33
	csiph-out.news.weretis.net	32
	news-out.mixmin.net	24
	news.swapon.de	23
	csiph-out.feeder.erje.net	12
	news-out.aioe.org	9
	newsfeed.neva.ru	5
	news.fcku.it	5
	news.redatomik.org	3
	TOTAL: 12	549
No colon-space in header
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 5		886

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	33023	1285	28130	6	1	3%	23:59:53
2	xanadu-bbs	32491	1226	28376	2	13	3%	23:59:53
3	fu-berlin.de	28309	980	27329	0	0	3%	23:59:29
4	news.bbs.geek.nz	55693	974	28050	31	3	1%	23:59:53
5	aioe	28742	648	23983	70	0	2%	23:59:53
6	usenet.pasdenom.info	38317	366	28199	1529	59	0%	23:59:53
7	au2pb	29894	129	28837	7	0	0%	23:59:21
8	news.redatomik.org	35071	110	28219	12	2	0%	23:59:34
9	xmission	35287	65	28443	4	2	0%	23:59:53
10	neva.ru	32335	35	26155	245	2	0%	23:59:49
11	news.fcku.it	4096	17	3548	0	0	0%	23:37:42
12	news.mixmin.net	29904	17	25939	0	1	0%	23:59:49
13	eternal-september	30362	15	24465	0	2	0%	23:59:50
14	weretis.net	25279	12	24626	4	0	0%	23:59:40
15	news.swapon.de	2786	4	2463	1	0	0%	23:55:23
16	erje	29525	3	26244	41	4	0%	23:59:53
17	bwh	23993	1	22466	0	0	0%	23:59:48
TOTAL: 17		495107	5887	405472	1952	89	1%	407:29:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.6 MB	15.6 KB	9.6 MB	0.1 KB/s	8.0 KB	23:59:53
2	news.freedyn.net	9.5 MB	50.3 KB	9.5 MB	0.1 KB/s	7.6 KB	23:59:53
3	fu-berlin.de	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	9.1 KB	23:59:29
4	news.bbs.geek.nz	2.7 MB	89.4 KB	2.8 MB	0.0 KB/s	2.9 KB	23:59:53
5	aioe	1.6 MB	279.0 KB	1.9 MB	0.0 KB/s	2.7 KB	23:59:53
6	usenet.pasdenom.info	1.3 MB	9.8 MB	11.1 MB	0.1 KB/s	6.0 KB	23:59:53
7	au2pb	471.8 KB	100.7 KB	572.5 KB	0.0 KB/s	4.2 KB	23:59:21
8	news.redatomik.org	353.2 KB	68.0 KB	421.1 KB	0.0 KB/s	3.5 KB	23:59:34
9	xmission	246.7 KB	7.6 KB	254.3 KB	0.0 KB/s	3.7 KB	23:59:53
10	neva.ru	109.2 KB	856.7 KB	965.9 KB	0.0 KB/s	3.4 KB	23:59:49
11	news.fcku.it	58.4 KB	0.0 KB	58.4 KB	0.0 KB/s	3.4 KB	23:37:42
12	news.mixmin.net	46.9 KB	0.0 KB	46.9 KB	0.0 KB/s	2.8 KB	23:59:49
13	eternal-september	37.7 KB	0.0 KB	37.7 KB	0.0 KB/s	2.5 KB	23:59:50
14	weretis.net	30.8 KB	99.0 KB	129.8 KB	0.0 KB/s	8.1 KB	23:59:40
15	news.swapon.de	10.4 KB	6.5 KB	16.9 KB	0.0 KB/s	3.4 KB	23:55:23
16	erje	7.2 KB	242.7 KB	249.9 KB	0.0 KB/s	5.7 KB	23:59:53
17	bwh	2.9 KB	0.0 KB	2.9 KB	0.0 KB/s	2.9 KB	23:59:48
TOTAL: 17		34.7 MB	11.6 MB	46.3 MB	0.0 KB/s	6.0 KB	407:29:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	96	238.7 KB	91	04:28:46
2	challenger.nielsenmedia.com	15	18	40.8 KB	4	02:24:01
3	92-108-162-83.dynamic.upc.nl	2	4	6.9 KB	1	00:05:15
4	tempe0.bbox.io	18	1	1.8 KB	1	05:57:20
5	138.108.60.60	2	0	0.0 KB	1	00:03:00
TOTAL: 5		325	119	288.1 KB	98	71:29:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	304	96	238.7 KB	92	50:35:20
2	*.nielsenmedia.com	15	18	40.8 KB	4	02:24:01
3	*.dynamic.upc.nl	2	4	6.9 KB	1	00:05:15
4	*.bbox.io	18	1	1.8 KB	1	05:57:20
5	*.tampfl.dsl-w.verizon.net	4	0	0.0 KB	0	01:29:29
6	*.ip-188-165-239.eu	1	0	0.0 KB	0	00:00:06
7	*.chello.nl	26	0	0.0 KB	0	10:58:21
TOTAL: 7		370	119	288.1 KB	98	71:29:55

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
162.212.88.221	0.838	0.705	0.000	34:50:57
71.94.21.184	0.582	0.588	0.000	11:12:14
dhcp-089-099-203-254.chello.nl	2.135	0.438	0.000	10:58:21
tempe0.bbox.io	1.627	0.303	0.000	05:57:20
202.89.176.220	30.248	6.891	0.725	04:28:46
challenger.nielsenmedia.com	1.490	0.291	0.000	02:24:01
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.347	0.062	0.000	01:29:29
92-108-162-83.dynamic.upc.nl	0.224	0.032	0.000	00:05:15
138.108.60.60	0.116	0.017	0.000	00:03:00
196.52.22.10	0.280	0.438	0.000	00:00:16
ns342002.ip-188-165-239.eu	0.067	0.017	0.000	00:00:06
206.217.197.145	0.195	0.187	0.000	00:00:03
TOTAL: 12	38.149	9.969	0.725	71:29:55

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	26
162.212.88.221	8
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4
71.94.21.184	3
206.217.197.145	2
ns342002.ip-188-165-239.eu	1
196.52.22.10	1
TOTAL: 7	45

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	3
TOTAL: 1	3

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	3
TOTAL: 1	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	20	0
tempe0.bbox.io	9	0
challenger.nielsenmedia.com	4	2
162.212.88.221	4	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
71.94.21.184	1	0
TOTAL: 6	40	2

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	119	99.2%
2	news	1	0.8%
TOTAL: 2		120	100.0%

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

Newsgroup		Count
1	comp.arch	42
2	comp.lang.awk	19
3	comp.arch.embedded	18
4	comp.sys.ibm.pc.games.action	7
5	comp.os.vms	6
6	comp.sys.ibm.ps2.hardware	4
7	comp.os.cpm	4
8	comp.os.msdos.4dos	4
9	comp.ai.neural-nets	3
10	comp.sys.ibm.pc.hardware.storage	3
11	comp.sys.ibm.as400.misc	3
12	comp.os.msdos.djgpp	2
13	news.software.nntp	1
14	comp.sys.ibm.pc.games.war-historical	1
15	comp.os.linux.networking	1
16	comp.sys.ibm.pc.games.strategic	1
17	comp.ai.philosophy	1
TOTAL: 17		120

Daily Usenet report for csiph.com

Feb 2 04:15:00 -- Feb 3 04:15:00