Daily Usenet report for csiph.com: Jun 6 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	47016	58.4%	9.0 MB	70.2%
innd	23632	29.3%	2.6 MB	20.0%
nnrpd	6604	8.2%	709.9 KB	5.4%
innfeed	3175	3.9%	562.0 KB	4.3%
nocem	108	0.1%	11.0 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	80536	100.0%	12.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	547970	59.3%
Negative hits	329235	35.6%
Do not exist	45867	5.0%
Cache misses	730	0.1%
TOTAL: 4	923802	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.197	0.0%	291	0.000	4.113	42.000
article cleanup	00:00:00.293	0.0%	45343	0.000	0.006	0.038
article logging	00:00:00.608	0.0%	45968	0.000	0.013	0.114
article parse	00:00:01.702	0.0%	166913	0.002	0.010	0.077
article write	00:05:23.450	0.4%	26088	0.444	12.398	22.427
artlog/artcncl	00:00:00.001	0.0%	237	0.000	0.004	0.062
artlog/artparse	00:00:00.012	0.0%	830	0.000	0.014	1.000
data move	00:00:00.268	0.0%	866155	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.076	0.0%	284	0.000	0.268	20.000
hishave/artcncl	00:00:00.001	0.0%	291	0.000	0.003	1.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:04.355	0.0%	928917	0.001	0.005	0.033
history sync	00:00:01.177	0.0%	346	0.000	3.402	25.667
history write	00:00:27.803	0.0%	45948	0.096	0.605	3.320
hiswrite/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hiswrite/artparse	00:00:00.196	0.0%	200	0.000	0.980	14.500
idle	23:50:50.756	99.3%	893270	61.476	96.108	268.854
nntp read	00:00:02.875	0.0%	910118	0.002	0.003	0.005
overview write	00:01:01.438	0.1%	26088	0.340	2.355	72.357
perl filter	00:01:53.019	0.1%	45142	1.479	2.504	3.806
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.454	0.0%	52276	0.000	0.009	0.024
TOTAL: 24:00:44.238	23:59:49.681	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.080	0.0%	26183	0.000	0.003	0.025
article prepare	00:00:00.002	0.0%	29348	0.000	0.000	0.005
article read	00:00:02.852	0.0%	3470	0.000	0.822	6.562
backlog stats	00:00:00.290	0.0%	622269	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.312	0.0%	449426	0.001	0.005	0.009
data write	00:00:02.969	0.0%	432991	0.004	0.007	0.012
idle	23:53:38.225	100.0%	622269	76.386	138.233	351.284
status file	00:00:00.366	0.0%	271	0.000	1.351	3.000
TOTAL: 23:54:00.221	23:53:47.096	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.452	0.0%	42821	0.000	0.034	1.061
idle	86:04:24.774	97.8%	2051848	84.228	151.017	601246.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:41.724	0.0%	5870069	0.000	0.007	0.167
readart	01:50:55.145	2.1%	1897224	0.000	3.508	92.000
TOTAL: 87:59:20.341	87:56:03.095	99.9%	-	-	-	-

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	csiph-out.feeder.erje.net	1152	145583	6370	129354	9859	4%	95:02:25
2	usenet.blueworldhosting.com	288	30002	6334	21944	1724	21%	23:44:54
3	feeder.eternal-september.org	5	39223	3529	34719	975	8%	24:37:44
4	news-out.mixmin.net	2	29073	2336	26246	491	8%	24:09:01
5	news-out.aioe.org	3	25213	1774	23403	36	7%	24:09:50
6	csiph-out.news.weretis.net	4	67177	1407	65084	686	2%	54:18:10
7	newsfeed.neva.ru	151	67048	1342	59699	6007	2%	72:31:24
8	newsfeed.fu-berlin.de	70	28825	1270	27329	226	4%	35:22:43
9	newsfeeds.xmission.com	1	29458	803	28637	18	2%	26:58:24
10	news.fcku.it	1	15379	458	14839	82	2%	35:52:42
11	news.freedyn.net	4	28846	354	28438	54	1%	24:09:32
12	au2pb.net	1	28169	188	27951	30	0%	24:12:48
13	news.swapon.de	1	2301	98	2201	2	4%	24:04:12
14	news.redatomik.org	1	24033	82	23943	8	0%	22:26:07
15	nntp.pasdenom.info	3	27151	66	27071	14	0%	24:08:22
16	xanadu-bbs.net	6	879	17	850	12	1%	25:38:54
17	localhost	46	11	11	0	0	100%	00:00:00
18	news.bbs.geek.nz	2	24152	3	24149	0	0%	23:59:41
TOTAL: 18		1741	612523	26442	565857	20224	4%	585:26:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	24.0 MB	137.7 KB	42.2 MB	66.4 MB	36%	4.2 KB
2	usenet.blueworldhosting.com	18.0 MB	0.0 KB	20.9 MB	38.8 MB	46%	4.9 KB
3	feeder.eternal-september.org	8.9 MB	40.4 KB	15.3 MB	24.3 MB	36%	5.5 KB
4	news-out.mixmin.net	8.7 MB	4.7 KB	1.8 MB	10.5 MB	82%	3.8 KB
5	csiph-out.news.weretis.net	4.3 MB	6.3 KB	10.2 MB	14.5 MB	29%	7.1 KB
6	news-out.aioe.org	4.0 MB	6.4 KB	101.5 KB	4.1 MB	97%	2.3 KB
7	newsfeed.neva.ru	3.3 MB	103.4 KB	56.8 MB	60.2 MB	5%	8.4 KB
8	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	640.7 KB	3.2 MB	80%	2.2 KB
9	newsfeeds.xmission.com	2.1 MB	0.0 KB	450.3 KB	2.6 MB	82%	3.2 KB
10	news.freedyn.net	1.4 MB	4.7 KB	699.4 KB	2.1 MB	66%	5.2 KB
11	news.fcku.it	883.9 KB	76.2 KB	1.6 MB	2.5 MB	34%	4.8 KB
12	au2pb.net	564.9 KB	4.7 KB	156.5 KB	726.1 KB	77%	3.3 KB
13	news.swapon.de	236.9 KB	0.0 KB	439.2 KB	676.0 KB	35%	6.8 KB
14	news.redatomik.org	222.4 KB	4.7 KB	434.0 KB	661.1 KB	33%	7.3 KB
15	nntp.pasdenom.info	137.2 KB	0.0 KB	39.5 KB	176.7 KB	77%	2.2 KB
16	xanadu-bbs.net	47.0 KB	0.0 KB	136.0 KB	182.9 KB	25%	6.3 KB
17	localhost	14.6 KB	0.0 KB	0.0 KB	14.6 KB	100%	1.3 KB
18	news.bbs.geek.nz	13.7 KB	0.0 KB	0.0 KB	13.7 KB	100%	4.6 KB
TOTAL: 18		79.3 MB	389.2 KB	151.8 MB	231.5 MB	34%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 6 04:15:00 - 04:59:59	494	1.9%	0.18	1.9 MB	2.5%	0.73
Jun 6 05:00:00 - 05:59:59	686	2.6%	0.19	2.7 MB	3.5%	0.78
Jun 6 06:00:00 - 06:59:59	866	3.3%	0.24	2.4 MB	3.0%	0.67
Jun 6 07:00:00 - 07:59:59	1140	4.4%	0.32	3.3 MB	4.3%	0.95
Jun 6 08:00:00 - 08:59:59	1109	4.3%	0.31	3.0 MB	3.8%	0.84
Jun 6 09:00:00 - 09:59:59	1088	4.2%	0.30	2.8 MB	3.6%	0.80
Jun 6 10:00:00 - 10:59:59	1162	4.5%	0.32	3.5 MB	4.5%	1.00
Jun 6 11:00:00 - 11:59:59	1179	4.5%	0.33	3.2 MB	4.1%	0.92
Jun 6 12:00:00 - 12:59:59	1270	4.9%	0.35	3.3 MB	4.3%	0.95
Jun 6 13:00:00 - 13:59:59	1328	5.1%	0.37	3.4 MB	4.3%	0.96
Jun 6 14:00:00 - 14:59:59	1638	6.3%	0.46	5.1 MB	6.6%	1.46
Jun 6 15:00:00 - 15:59:59	1478	5.7%	0.41	4.5 MB	5.8%	1.29
Jun 6 16:00:00 - 16:59:59	1513	5.8%	0.42	4.7 MB	6.1%	1.35
Jun 6 17:00:00 - 17:59:59	1450	5.6%	0.40	4.8 MB	6.1%	1.37
Jun 6 18:00:00 - 18:59:59	1465	5.6%	0.41	4.6 MB	5.8%	1.30
Jun 6 19:00:00 - 19:59:59	1549	5.9%	0.43	4.7 MB	6.1%	1.35
Jun 6 20:00:00 - 20:59:59	1224	4.7%	0.34	3.8 MB	4.8%	1.08
Jun 6 21:00:00 - 21:59:59	1381	5.3%	0.38	4.1 MB	5.2%	1.16
Jun 6 22:00:00 - 22:59:59	1043	4.0%	0.29	3.1 MB	4.0%	0.89
Jun 6 23:00:00 - 23:59:59	766	2.9%	0.21	2.1 MB	2.7%	0.59
Jun 7 00:00:00 - 00:59:59	605	2.3%	0.17	1.8 MB	2.3%	0.52
Jun 7 01:00:00 - 01:59:59	497	1.9%	0.14	1.4 MB	1.8%	0.40
Jun 7 02:00:00 - 02:59:59	459	1.8%	0.13	1.5 MB	1.9%	0.43
Jun 7 03:00:00 - 03:59:59	541	2.1%	0.15	1.8 MB	2.3%	0.51
Jun 7 04:00:00 - 04:15:00	143	0.5%	0.16	497.0 KB	0.6%	0.55
TOTAL: 24:00:00	26074	100.0%	0.30	78.3 MB	100.0%	0.93

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	csiph-out.feeder.erje.net	9921	1186	12	0	0	8723
2	newsfeed.neva.ru	5996	1087	9	0	0	4900
3	usenet.blueworldhosting.com	1722	503	0	1	0	1218
4	feeder.eternal-september.org	999	118	1	0	0	880
5	newsfeed.fu-berlin.de	682	17	0	0	0	665
6	csiph-out.news.weretis.net	632	166	1	0	0	465
7	news-out.mixmin.net	529	3	0	0	0	526
8	news.freedyn.net	53	0	0	0	0	53
9	news-out.aioe.org	45	30	0	0	0	15
10	news.fcku.it	32	0	2	0	0	30
11	au2pb.net	27	23	0	0	0	4
12	news.swapon.de	20	0	0	0	0	20
13	nntp.pasdenom.info	15	8	0	0	4	3
14	newsfeeds.xmission.com	14	12	0	0	0	2
15	xanadu-bbs.net	12	0	0	0	0	12
16	news.redatomik.org	7	0	0	0	0	7
TOTAL: 16		20706	3153	25	1	4	17523

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	990
mailing.freebsd.pkg-fallout	227
ut.test	110
lada.talk	88
mailing.freebsd.ports-bugs	72
mailing.freebsd.svn-src-all	71
fa.freebsd.cvs-all	63
mailing.freebsd.svn-src-head	63
mailing.freebsd.svn-ports-all	56
it-alt.sport.calcio.juventus	52
mailing.freebsd.svn-ports-head	52
mailing.openbsd.source-changes	51
mailing.gcc.gcc-cvs	48
fa.openbsd.source-changes	42
mailing.openssl.cvs	41
mailing.openbsd.misc	40
alt.windows7.general	40
mailing.postfix.users	35
lada.auto	34
alt.politics.scorched-earth	33
TOTAL: 235	3153

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

Groups	Count
nz.politics.announce	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12247
EMP (phn path)	1942
PFM bot misc	719
EMP (phl)	649
EMP (phn nph)	90
Too many newsgroups	47
HTML Multipart	41
Scoring filter (17)	17
Scoring filter (21)	17
Binary Payload	17
Scoring filter (9)	16
User-issued cancel	12
Scoring filter (16)	11
Scoring filter (10)	10
Binary: misplaced par2	10
Binary: misplaced sfv	6
Scoring filter (15)	5
Scoring filter (14)	4
Scoring filter (8)	4
Too many newsgroups (meow)	4
TOTAL: 36	15895

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	169
	usenet.blueworldhosting.com	3
	TOTAL: 2	172
Huge articles
	usenet.blueworldhosting.com	93
	csiph-out.news.weretis.net	48
	feeder.eternal-september.org	20
	news-out.mixmin.net	19
	news.redatomik.org	6
	news.freedyn.net	5
	newsfeeds.xmission.com	2
	newsfeed.fu-berlin.de	2
	news.swapon.de	2
	news.fcku.it	1
	TOTAL: 11	199
Including strange strings
	newsfeed.fu-berlin.de	453
	csiph-out.feeder.erje.net	59
	news-out.mixmin.net	32
	csiph-out.news.weretis.net	30
	feeder.eternal-september.org	19
	news.swapon.de	18
	news-out.aioe.org	10
	newsfeed.neva.ru	6
	TOTAL: 8	627
No colon-space in header
	csiph-out.feeder.erje.net	626
	usenet.blueworldhosting.com	3
	TOTAL: 2	629
TOTAL: 4		1627

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	47820	1681	23428	22	154	3%	24:00:00
2	news.freedyn.net	29852	1269	24440	4	0	4%	23:59:45
3	xanadu-bbs	29782	1055	24974	12	0	3%	23:59:48
4	fu-berlin.de	24479	890	23589	0	0	3%	23:59:51
5	xmission	29725	210	24213	6	0	0%	23:59:52
6	usenet.pasdenom.info	34156	186	24465	1354	6	0%	23:59:52
7	weretis.net	24615	160	22604	28	0	0%	23:59:52
8	aioe	25832	122	22044	7	0	0%	23:59:48
9	news.redatomik.org	28719	121	24835	2	0	0%	23:59:52
10	au2pb	27623	76	24880	1	0	0%	23:59:52
11	news.mixmin.net	26151	25	22404	1	0	0%	23:59:52
12	eternal-september	26430	22	21221	0	0	0%	23:59:52
13	bwh	21383	18	18432	0	0	0%	23:59:47
14	news.swapon.de	2489	10	2169	1	0	0%	23:59:29
15	neva.ru	26768	8	23431	23	0	0%	23:59:52
16	erje	21107	4	19526	48	0	0%	23:59:52
17	news.fcku.it	3809	0	3208	0	0	0%	22:47:43
TOTAL: 17		430740	5857	349863	1509	160	1%	406:44:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.3 MB	49.2 KB	10.4 MB	0.1 KB/s	8.4 KB	23:59:45
2	xanadu-bbs	9.8 MB	22.4 KB	9.8 MB	0.1 KB/s	9.4 KB	23:59:48
3	fu-berlin.de	9.3 MB	0.0 KB	9.3 MB	0.1 KB/s	10.7 KB	23:59:51
4	news.bbs.geek.nz	4.3 MB	91.8 KB	4.4 MB	0.1 KB/s	2.7 KB	24:00:00
5	xmission	696.8 KB	10.7 KB	707.5 KB	0.0 KB/s	3.3 KB	23:59:52
6	weretis.net	578.9 KB	151.7 KB	730.6 KB	0.0 KB/s	3.9 KB	23:59:52
7	usenet.pasdenom.info	462.7 KB	10.3 MB	10.8 MB	0.1 KB/s	7.2 KB	23:59:52
8	news.redatomik.org	311.3 KB	3.2 KB	314.5 KB	0.0 KB/s	2.6 KB	23:59:52
9	aioe	292.3 KB	11.7 KB	304.0 KB	0.0 KB/s	2.4 KB	23:59:48
10	au2pb	220.5 KB	13.4 KB	234.0 KB	0.0 KB/s	3.0 KB	23:59:52
11	news.mixmin.net	73.5 KB	9.5 KB	83.1 KB	0.0 KB/s	3.2 KB	23:59:52
12	eternal-september	68.3 KB	0.0 KB	68.3 KB	0.0 KB/s	3.1 KB	23:59:52
13	bwh	34.0 KB	0.0 KB	34.0 KB	0.0 KB/s	1.9 KB	23:59:47
14	news.swapon.de	13.5 KB	1.2 KB	14.6 KB	0.0 KB/s	1.3 KB	23:59:29
15	neva.ru	11.6 KB	104.0 KB	115.6 KB	0.0 KB/s	3.7 KB	23:59:52
16	erje	5.0 KB	297.0 KB	302.0 KB	0.0 KB/s	5.8 KB	23:59:52
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:47:43
TOTAL: 17		36.5 MB	11.1 MB	47.5 MB	0.0 KB/s	6.6 KB	406:44:59

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	50	883154	2.9 GB	581	0	0	52:26:42
2	68-185-181-64.dhcp.mdsn.wi.charter.com	16	12	16.6 KB	9	11	6	04:34:00
3	dhcp-089-099-203-254.chello.nl	15	7	35.3 KB	2	0	0	05:59:35
4	challenger.nielsenmedia.com	7	6	16.6 KB	4	0	0	01:38:52
5	92-108-162-83.dynamic.upc.nl	2	3	10.7 KB	1	0	0	00:04:47
TOTAL: 5		90	883182	2.9 GB	597	11	6	87:59:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	50	883154	2.9 GB	581	0	0	52:26:42
2	*.dhcp.mdsn.wi.charter.com	16	12	16.6 KB	9	11	6	04:34:00
3	*.chello.nl	15	7	35.3 KB	2	0	0	05:59:35
4	*.nielsenmedia.com	7	6	16.6 KB	4	0	0	01:38:52
5	*.dynamic.upc.nl	2	3	10.7 KB	1	0	0	00:04:47
6	unresolved	21	0	0.0 KB	0	0	0	16:52:24
7	*.bbox.io	31	0	0.0 KB	0	0	0	04:48:28
8	*.net-89-2-152.rev.numericable.fr	43	0	0.0 KB	0	0	0	01:34:25
TOTAL: 8		185	883182	2.9 GB	597	11	6	87:59:18

NNRP auth users [Top 20]:

User	Conn
ohlandl	6
TOTAL: 1	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	109.501	236.499	0.000	52:26:42
216.239.174.71	1.692	0.704	0.000	16:22:20
dhcp-089-099-203-254.chello.nl	1.408	0.291	0.000	05:59:35
tempe0.bbox.io	2.141	0.529	0.000	04:48:28
68-185-181-64.dhcp.mdsn.wi.charter.com	1.624	0.371	0.017	04:34:00
challenger.nielsenmedia.com	0.781	0.168	0.000	01:38:52
ip-84.net-89-2-152.rev.numericable.fr	4.252	2.296	0.000	01:34:25
71.98.160.36	0.058	0.019	0.000	00:30:04
92-108-162-83.dynamic.upc.nl	0.193	0.077	0.000	00:04:47
TOTAL: 9	121.650	240.954	0.017	87:59:18

Curious NNRP server explorers [Top 20]:

System	Conn
ip-84.net-89-2-152.rev.numericable.fr	43
tempe0.bbox.io	31
216.239.174.71	20
71.98.160.36	1
TOTAL: 4	95

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	10	0
tempe0.bbox.io	9	0
68-185-181-64.dhcp.mdsn.wi.charter.com	5	0
216.239.174.71	4	0
challenger.nielsenmedia.com	3	0
71.98.160.36	1	0
TOTAL: 6	32	0

Newsgroup request counts (by category):

Category		Count	Pct
1	linux	147672	30.0%
2	rec	119226	24.2%
3	alt	87773	17.8%
4	uk	58278	11.8%
5	fr	47153	9.6%
6	can	32854	6.7%
7	comp	29	0.0%
TOTAL: 7		492985	100.0%

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

Newsgroup		Count
1	linux.kernel	147672
2	rec.arts.tv	96031
3	alt.atheism	58497
4	uk.politics.misc	58275
5	fr.soc.politique	47153
6	can.politics	32854
7	rec.food.cooking	23190
8	alt.comp.freeware	22827
9	alt.messianic	6446
10	comp.sys.ibm.ps2.hardware	22
11	comp.lang.awk	6
12	rec.boats	4
13	uk.radio.amateur	2
14	alt.home.repair	2
15	alt.sports.basketball.nba.gs-warriors	1
16	comp.lang.php	1
17	uk.d-i-y	1
18	rec.sport.pro-wrestling	1
TOTAL: 18		492985

Daily Usenet report for csiph.com

Jun 6 04:15:00 -- Jun 7 04:15:00