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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	36617	58.9%	7.0 MB	70.2%
innd	20883	33.6%	2.3 MB	22.8%
innfeed	3183	5.1%	549.8 KB	5.4%
nnrpd	1427	2.3%	158.8 KB	1.6%
nocem	52	0.1%	5.4 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	62163	100.0%	9.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	376823	55.6%
Negative hits	264800	39.1%
Do not exist	35699	5.3%
Cache misses	502	0.1%
TOTAL: 4	677824	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.683	0.0%	175	0.000	3.903	60.000
article cleanup	00:00:00.227	0.0%	35698	0.000	0.006	0.023
article logging	00:00:04.577	0.0%	36260	0.000	0.126	0.679
article parse	00:00:02.645	0.0%	109193	0.003	0.024	0.342
article write	00:02:38.950	0.2%	20435	0.107	7.778	16.950
artlog/artcncl	00:00:00.011	0.0%	136	0.000	0.081	1.000
artlog/artparse	00:00:00.030	0.0%	207	0.000	0.145	1.545
data move	00:00:00.214	0.0%	626069	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.254	0.0%	175	0.000	1.451	20.000
hishave/artcncl	00:00:00.007	0.0%	175	0.000	0.040	1.000
hishave/artparse	00:00:00.002	0.0%	202	0.000	0.010	0.091
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:05.751	0.1%	680681	0.001	0.185	0.441
history sync	00:00:05.934	0.0%	366	0.000	16.213	79.000
history write	00:03:52.677	0.3%	35685	0.336	6.520	12.857
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.319	0.0%	202	0.000	6.530	180.000
idle	23:44:51.661	98.9%	612278	87.115	139.629	391.425
nntp read	00:00:02.900	0.0%	639574	0.002	0.005	0.009
overview write	00:04:26.847	0.3%	20435	1.330	13.058	54.429
perl filter	00:01:24.628	0.1%	35458	1.531	2.387	3.953
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.383	0.0%	40915	0.000	0.009	0.028
TOTAL: 24:00:58.511	23:59:39.700	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.067	0.0%	20418	0.000	0.003	0.024
article prepare	00:00:00.008	0.0%	39572	0.000	0.000	0.008
article read	00:00:03.267	0.0%	7468	0.000	0.437	2.174
backlog stats	00:00:00.219	0.0%	375380	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.069	0.0%	290299	0.002	0.007	0.015
data write	00:00:01.793	0.0%	273773	0.002	0.007	0.014
idle	23:54:18.578	100.0%	375380	142.540	229.257	652.807
status file	00:00:00.254	0.0%	281	0.000	0.904	6.000
TOTAL: 23:54:37.826	23:54:26.255	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:10.649	0.0%	7002	0.000	1.521	23.000
idle	89:23:57.689	99.8%	7756	200.276	41495.318	900234.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.183	0.0%	61460	0.000	0.036	0.500
readart	00:00:05.473	0.0%	133	0.000	41.150	61.000
TOTAL: 89:33:56.553	89:24:15.994	99.8%	-	-	-	-

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	113713	7123	97661	8929	6%	94:57:42
2	csiph-out.news.weretis.net	5	50376	4493	45008	875	8%	56:26:21
3	feeder.eternal-september.org	4	31587	2787	25831	2969	8%	25:32:56
4	news-out.aioe.org	3	19496	1737	17750	9	8%	24:09:43
5	newsfeed.fu-berlin.de	35	21698	1151	20270	277	5%	30:41:54
6	news-out.mixmin.net	2	22357	881	21188	288	3%	24:08:40
7	newsfeed.neva.ru	148	45070	848	42234	1988	1%	71:28:20
8	newsfeeds.xmission.com	3	19014	642	18368	4	3%	23:29:07
9	news.redatomik.org	1	19076	523	18545	8	2%	23:19:58
10	news.freedyn.net	3	25736	103	25491	142	0%	25:12:54
11	news.swapon.de	1	2268	82	2183	3	3%	24:28:22
12	news.fcku.it	2	8622	74	8487	61	0%	23:59:37
13	nntp.pasdenom.info	3	28888	67	28812	9	0%	24:09:01
14	xanadu-bbs.net	11	984	55	925	4	5%	22:09:24
15	localhost	38	3	3	0	0	100%	00:00:00
16	news.bbs.geek.nz	1	16372	1	16371	0	0%	23:59:31
TOTAL: 16		1412	425260	20570	389124	15566	4%	518:13:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	22.8 MB	180.8 KB	47.8 MB	70.9 MB	32%	4.5 KB
2	csiph-out.news.weretis.net	14.3 MB	12.0 KB	10.5 MB	24.8 MB	57%	4.7 KB
3	feeder.eternal-september.org	7.6 MB	12.0 KB	38.1 MB	45.7 MB	16%	8.1 KB
4	news-out.mixmin.net	4.9 MB	0.0 KB	1.2 MB	6.1 MB	79%	5.4 KB
5	news-out.aioe.org	4.4 MB	0.0 KB	17.1 KB	4.5 MB	99%	2.6 KB
6	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	654.9 KB	3.1 MB	79%	2.2 KB
7	newsfeed.neva.ru	2.3 MB	12.0 KB	14.0 MB	16.3 MB	13%	5.9 KB
8	newsfeeds.xmission.com	2.0 MB	0.0 KB	264.8 KB	2.3 MB	88%	3.6 KB
9	news.redatomik.org	1.5 MB	0.0 KB	1.3 MB	2.8 MB	54%	5.4 KB
10	news.freedyn.net	374.1 KB	0.0 KB	1.3 MB	1.7 MB	21%	7.1 KB
11	news.swapon.de	236.2 KB	0.0 KB	54.1 KB	290.3 KB	81%	3.4 KB
12	xanadu-bbs.net	171.1 KB	0.0 KB	21.9 KB	192.9 KB	88%	3.3 KB
13	nntp.pasdenom.info	138.4 KB	0.0 KB	18.2 KB	156.6 KB	88%	2.1 KB
14	news.fcku.it	130.9 KB	0.0 KB	1.1 MB	1.2 MB	10%	9.4 KB
15	localhost	3.6 KB	0.0 KB	0.0 KB	3.6 KB	100%	1.2 KB
16	news.bbs.geek.nz	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
TOTAL: 16		63.4 MB	216.9 KB	116.4 MB	179.9 MB	35%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 14 04:15:00 - 04:59:59	342	1.7%	0.13	1.0 MB	1.6%	0.38
Jan 14 05:00:00 - 05:59:59	346	1.7%	0.10	1.0 MB	1.6%	0.29
Jan 14 06:00:00 - 06:59:59	408	2.0%	0.11	1.3 MB	2.0%	0.36
Jan 14 07:00:00 - 07:59:59	650	3.2%	0.18	2.1 MB	3.3%	0.60
Jan 14 08:00:00 - 08:59:59	708	3.5%	0.20	2.0 MB	3.2%	0.58
Jan 14 09:00:00 - 09:59:59	957	4.7%	0.27	3.2 MB	5.1%	0.92
Jan 14 10:00:00 - 10:59:59	1009	4.9%	0.28	3.5 MB	5.6%	1.00
Jan 14 11:00:00 - 11:59:59	980	4.8%	0.27	2.8 MB	4.5%	0.81
Jan 14 12:00:00 - 12:59:59	1048	5.1%	0.29	4.3 MB	6.8%	1.22
Jan 14 13:00:00 - 13:59:59	1070	5.2%	0.30	2.9 MB	4.6%	0.82
Jan 14 14:00:00 - 14:59:59	974	4.8%	0.27	2.8 MB	4.5%	0.81
Jan 14 15:00:00 - 15:59:59	1071	5.2%	0.30	3.0 MB	4.8%	0.87
Jan 14 16:00:00 - 16:59:59	1244	6.1%	0.35	3.3 MB	5.3%	0.94
Jan 14 17:00:00 - 17:59:59	1031	5.0%	0.29	3.0 MB	4.8%	0.86
Jan 14 18:00:00 - 18:59:59	1245	6.1%	0.35	3.8 MB	6.1%	1.09
Jan 14 19:00:00 - 19:59:59	1035	5.1%	0.29	3.0 MB	4.8%	0.86
Jan 14 20:00:00 - 20:59:59	1139	5.6%	0.32	2.9 MB	4.5%	0.81
Jan 14 21:00:00 - 21:59:59	1123	5.5%	0.31	3.3 MB	5.2%	0.93
Jan 14 22:00:00 - 22:59:59	976	4.8%	0.27	2.9 MB	4.6%	0.83
Jan 14 23:00:00 - 23:59:59	827	4.0%	0.23	2.6 MB	4.1%	0.73
Jan 15 00:00:00 - 00:59:59	662	3.2%	0.18	2.0 MB	3.1%	0.56
Jan 15 01:00:00 - 01:59:59	569	2.8%	0.16	1.5 MB	2.4%	0.44
Jan 15 02:00:00 - 02:59:59	474	2.3%	0.13	2.4 MB	3.7%	0.67
Jan 15 03:00:00 - 03:59:59	437	2.1%	0.12	1.9 MB	3.0%	0.55
Jan 15 04:00:00 - 04:15:00	101	0.5%	0.11	396.6 KB	0.6%	0.44
TOTAL: 24:00:00	20426	100.0%	0.24	63.1 MB	100.0%	0.75

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	8957	197	15	8745
2	feeder.eternal-september.org	2973	58	1	2914
3	newsfeed.neva.ru	2027	191	1	1835
4	csiph-out.news.weretis.net	838	99	1	738
5	newsfeed.fu-berlin.de	714	53	0	661
6	news-out.mixmin.net	289	0	0	289
7	news.freedyn.net	139	5	0	134
8	news.fcku.it	59	0	0	59
9	news-out.aioe.org	26	9	0	17
10	nntp.pasdenom.info	10	0	0	10
11	news.redatomik.org	9	0	0	9
12	news.swapon.de	7	0	0	7
13	newsfeeds.xmission.com	5	0	0	5
14	xanadu-bbs.net	4	0	0	4
TOTAL: 14		16057	612	18	15427

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	85
ut.test	64
it-alt.sport.calcio.juventus	49
lada.auto	42
spline.eisfair	41
alt.survival	26
lada.commerce	23
gmane.linux.alsa.devel	20
mozilla.support.firefox	19
mpc.lists.freebsd.gnome	14
alt.computer.workshop	14
cakewalk.audio	14
spline.eisfair.dev	10
it-alt.comp.software.openoffice	8
mpc.lists.nanog	7
mozilla.test	7
abg.test	7
oesterreich.tratsch	7
alt.binaries.vladmodels	6
gmane.comp.gis.openstreetmap.imports	6
TOTAL: 92	612

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11775
EMP (phn path)	1657
EMP (phl)	678
EMP (phn nph)	166
Too many newsgroups	107
Binary Payload	10
User-issued cancel	9
HTML Multipart	8
Scoring filter (8)	7
Binary Image: misplaced jpg	6
Scoring filter (10)	5
Scoring filter (14)	3
Scoring filter (11)	3
Scoring filter (13)	2
HTML post	2
Scoring filter (12)	1
Too many newsgroups (low_xpost)	1
TOTAL: 17	14440

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	43	0	47	47
TOTAL: 1	43	1	47	47

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	222
	TOTAL: 1	222
Bad Message-ID's offered
	csiph-out.feeder.erje.net	8
	TOTAL: 1	8
Huge articles
	feeder.eternal-september.org	128
	csiph-out.news.weretis.net	32
	news.fcku.it	24
	news-out.mixmin.net	12
	newsfeeds.xmission.com	4
	news.freedyn.net	1
	news.redatomik.org	1
	TOTAL: 7	202
Including strange strings
	newsfeed.fu-berlin.de	455
	csiph-out.feeder.erje.net	37
	news-out.aioe.org	17
	feeder.eternal-september.org	12
	csiph-out.news.weretis.net	10
	newsfeed.neva.ru	8
	news-out.mixmin.net	7
	news.swapon.de	7
	news.redatomik.org	4
	newsfeeds.xmission.com	1
	TOTAL: 10	558
No colon-space in header
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
TOTAL: 5		994

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	33392	5375	14184	74	0	16%	23:50:04
2	news.freedyn.net	20974	850	19458	3	25	4%	23:57:02
3	xanadu-bbs	22533	757	19597	1	0	3%	23:59:52
4	fu-berlin.de	19082	622	18460	0	0	3%	23:59:43
5	usenet.pasdenom.info	22734	101	18288	1968	0	0%	23:59:52
6	xmission	21797	17	19020	1	0	0%	23:59:52
7	aioe	17514	16	16817	0	0	0%	23:50:04
8	eternal-september	18023	10	16749	2	0	0%	23:59:52
9	news.redatomik.org	19645	8	19059	4	0	0%	23:50:04
10	weretis.net	14379	6	14106	2	0	0%	23:55:21
11	news.mixmin.net	18580	3	18011	0	0	0%	23:50:40
12	news.swapon.de	2257	3	2173	0	0	0%	23:48:12
13	neva.ru	19629	1	18683	12	0	0%	23:59:12
14	au2pb	0	0	0	0	19638	0%	23:50:06
15	bwh	0	0	0	0	19625	0%	23:50:04
16	news.fcku.it	1596	0	1560	0	0	0%	22:26:20
17	erje	13306	0	13181	43	0	0%	23:58:42
TOTAL: 17		265441	7769	229346	2110	39288	2%	405:05:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	15.3 MB	347.3 KB	15.6 MB	0.2 KB/s	2.9 KB	23:50:04
2	news.freedyn.net	5.0 MB	5.7 KB	5.0 MB	0.1 KB/s	6.0 KB	23:57:02
3	xanadu-bbs	4.8 MB	3.0 KB	4.8 MB	0.1 KB/s	6.4 KB	23:59:52
4	fu-berlin.de	4.4 MB	0.0 KB	4.4 MB	0.1 KB/s	7.2 KB	23:59:43
5	usenet.pasdenom.info	287.7 KB	6.8 MB	7.1 MB	0.1 KB/s	3.5 KB	23:59:52
6	xmission	47.7 KB	3.0 KB	50.7 KB	0.0 KB/s	2.8 KB	23:59:52
7	aioe	36.7 KB	0.0 KB	36.7 KB	0.0 KB/s	2.3 KB	23:50:04
8	news.redatomik.org	28.2 KB	7.6 KB	35.8 KB	0.0 KB/s	3.0 KB	23:50:04
9	weretis.net	22.7 KB	2.3 KB	25.0 KB	0.0 KB/s	3.1 KB	23:55:21
10	eternal-september	20.6 KB	20.0 KB	40.6 KB	0.0 KB/s	3.4 KB	23:59:52
11	news.mixmin.net	3.6 KB	0.0 KB	3.6 KB	0.0 KB/s	1.2 KB	23:50:40
12	news.swapon.de	3.6 KB	0.0 KB	3.6 KB	0.0 KB/s	1.2 KB	23:48:12
13	neva.ru	1.2 KB	53.0 KB	54.2 KB	0.0 KB/s	4.2 KB	23:59:12
14	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:06
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:26:20
17	erje	0.0 KB	256.2 KB	256.2 KB	0.0 KB/s	6.0 KB	23:58:42
TOTAL: 17		29.9 MB	7.5 MB	37.3 MB	0.0 KB/s	3.9 KB	405:05:02

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	240.132.151.153.ap.dti.ne.jp	1	108	120.0 KB	6	0	0	00:00:37
2	tempe0.bbox.io	31	15	61.7 KB	2	0	0	06:28:47
3	194-73-239-109.cust.centrio.cz	12	4	11.7 KB	4	0	0	03:11:17
4	dhcp-089-099-203-254.chello.nl	65	3	3.9 KB	3	0	0	17:57:38
5	68-185-181-64.dhcp.mdsn.wi.charter.com	16	3	3.6 KB	3	3	3	04:42:53
TOTAL: 5		125	133	200.8 KB	18	3	3	89:33:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.132.151.153.ap.dti.ne.jp	1	108	120.0 KB	6	0	0	00:00:37
2	*.bbox.io	31	15	61.7 KB	2	0	0	06:28:47
3	*.cust.centrio.cz	12	4	11.7 KB	4	0	0	03:11:17
4	*.dhcp.mdsn.wi.charter.com	16	3	3.6 KB	3	3	3	04:42:53
5	*.chello.nl	65	3	3.9 KB	3	0	0	17:57:38
6	*.136.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	0	0	07:32:03
7	unresolved	102	0	0.0 KB	0	0	0	49:40:35
TOTAL: 7		229	133	200.8 KB	18	3	3	89:33:54

NNRP auth users [Top 20]:

User	Conn
ctomczyk	7
ohlandl	3
TOTAL: 2	10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.703	1.568	0.000	49:40:34
dhcp-089-099-203-254.chello.nl	4.966	1.015	0.000	17:57:38
h60.136.22.98.dynamic.ip.windstream.net	0.330	0.576	0.000	07:32:03
tempe0.bbox.io	2.490	0.505	0.000	06:28:47
68-185-181-64.dhcp.mdsn.wi.charter.com	1.455	0.340	0.011	04:42:53
194-73-239-109.cust.centrio.cz	1.149	0.199	0.026	03:11:17
240.132.151.153.ap.dti.ne.jp	0.225	0.085	0.000	00:00:37
94.102.49.190	0.092	0.008	0.000	00:00:01
TOTAL: 8	18.410	4.296	0.037	89:33:54

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	101
h60.136.22.98.dynamic.ip.windstream.net	2
94.102.49.190	1
TOTAL: 3	104

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	98	0
tempe0.bbox.io	10	0
194-73-239-109.cust.centrio.cz	6	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
h60.136.22.98.dynamic.ip.windstream.net	3	0
TOTAL: 5	121	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	108	81.2%
2	muc	14	10.5%
3	comp	10	7.5%
4	pl	1	0.8%
TOTAL: 4		133	100.0%

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

Newsgroup		Count
1	alt.binaries.pictures.erotica.bestiality	39
2	alt.binaries.pictures.erotica.anime	35
3	alt.binaries.pictures.erotica.areola.big	26
4	muc.lists.freebsd.current	14
5	comp.sys.ibm.ps2.hardware	7
6	alt.binaries.pictures.erotica.asian.male	6
7	comp.lang.javascript	3
8	alt.binaries.pictures.erotica.balls	1
9	alt.binaries.pictures.erotica.anna	1
10	pl.comp.www	1
TOTAL: 10		133

Daily Usenet report for csiph.com

Jan 14 04:15:00 -- Jan 15 04:15:00