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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	27063	56.3%	5.7 MB	69.9%
innd	11949	24.9%	1.4 MB	17.3%
nnrpd	5934	12.3%	513.6 KB	6.1%
innfeed	2985	6.2%	553.1 KB	6.6%
nocem	117	0.2%	13.0 KB	0.2%
controlchan	2	0.0%	0.3 KB	0.0%
TOTAL: 6	48050	100.0%	8.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	307476	56.3%
Negative hits	212451	38.9%
Do not exist	25989	4.8%
Cache misses	406	0.1%
TOTAL: 4	546322	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.069	0.0%	299	0.000	10.264	137.200
article cleanup	00:00:00.169	0.0%	25986	0.000	0.007	0.023
article logging	00:00:05.815	0.0%	26577	0.000	0.219	4.328
article parse	00:00:03.647	0.0%	69030	0.000	0.053	0.917
article write	00:02:24.513	0.2%	19499	0.945	7.411	52.709
artlog/artcncl	00:00:00.003	0.0%	184	0.000	0.016	1.000
artlog/artparse	00:00:00.502	0.0%	312	0.000	1.609	106.500
data move	00:00:00.164	0.0%	530826	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.469	0.0%	296	0.000	1.584	119.000
hishave/artcncl	00:00:00.016	0.0%	299	0.000	0.054	1.000
hishave/artparse	00:00:00.002	0.0%	305	0.000	0.007	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:07.602	0.1%	552298	0.004	0.122	1.222
history sync	00:00:03.456	0.0%	374	0.000	9.241	202.500
history write	00:02:00.076	0.1%	25986	0.479	4.621	33.512
hiswrite/artcncl	00:00:00.001	0.0%	3	0.000	0.333	1.000
hiswrite/artparse	00:00:02.243	0.0%	305	0.000	7.354	116.667
idle	23:44:56.290	98.9%	519515	90.127	164.569	555.885
nntp read	00:00:02.703	0.0%	537702	0.002	0.005	0.009
overview write	00:07:58.869	0.6%	19499	0.373	24.559	669.654
perl filter	00:00:50.458	0.1%	25819	1.264	1.954	3.290
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.375	0.0%	39108	0.000	0.010	0.035
TOTAL: 24:00:58.110	23:59:40.442	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.045	0.0%	19729	0.000	0.002	0.019
article prepare	00:00:00.001	0.0%	17356	0.000	0.000	0.010
article read	00:00:05.476	0.0%	2356	0.000	2.324	29.000
backlog stats	00:00:00.210	0.0%	365551	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.988	0.0%	279071	0.002	0.007	0.015
data write	00:00:01.506	0.0%	262970	0.001	0.006	0.015
idle	23:43:51.744	100.0%	365551	134.978	233.707	768.103
status file	00:00:00.728	0.0%	268	0.000	2.716	130.000
TOTAL: 23:44:16.309	23:44:01.698	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	28:34:25.941	100.0%	5843	382.299	17604.987	600172.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.081	0.0%	12313	0.000	0.007	0.167
readart	00:00:01.027	0.0%	142	0.000	7.232	65.600
TOTAL: 28:35:02.780	28:34:27.049	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	3
logmode	2
mode	68
pause	3
paused	3
reload	4
reserve	2
TOTAL: 10	93

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	41128	5090	35607	431	12%	49:39:00
2	csiph-out.feeder.erje.net	1153	84135	3209	78307	2619	3%	96:01:27
3	feeder.eternal-september.org	5	22329	2815	18557	957	12%	24:37:29
4	newsfeeds.xmission.com	4	18691	2428	16258	5	12%	23:57:30
5	news-out.aioe.org	3	19095	1424	17663	8	7%	24:09:56
6	newsfeed.neva.ru	149	26339	1299	22882	2158	4%	72:34:21
7	news.redatomik.org	1	23417	1251	22144	22	5%	30:21:48
8	newsfeed.fu-berlin.de	12	16271	1044	14995	232	6%	21:45:06
9	news.fcku.it	1	9063	878	8024	161	9%	23:59:57
10	news-out.mixmin.net	3	20419	183	20066	170	0%	24:08:07
11	news.swapon.de	6	1796	73	1715	8	4%	23:35:47
12	news.freedyn.net	2	20802	46	20740	16	0%	23:31:16
13	nntp.pasdenom.info	2	18405	16	18385	4	0%	23:59:22
14	news.bbs.geek.nz	1	19592	7	19585	0	0%	23:59:41
15	xanadu-bbs.net	14	704	1	698	5	0%	22:31:56
16	localhost	35	1	1	0	0	100%	00:00:06
TOTAL: 16		1396	342187	19765	315626	6796	5%	508:52:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	15.6 MB	3.8 KB	12.8 MB	28.4 MB	54%	5.3 KB
2	csiph-out.feeder.erje.net	14.1 MB	32.7 KB	12.7 MB	26.8 MB	52%	4.7 KB
3	feeder.eternal-september.org	7.8 MB	3.4 KB	2.9 MB	10.7 MB	72%	2.9 KB
4	newsfeeds.xmission.com	6.6 MB	0.0 KB	251.3 KB	6.8 MB	96%	2.9 KB
5	news-out.aioe.org	3.5 MB	0.0 KB	25.7 KB	3.5 MB	99%	2.5 KB
6	newsfeed.neva.ru	3.3 MB	0.0 KB	12.3 MB	15.6 MB	21%	4.6 KB
7	news.redatomik.org	3.1 MB	0.0 KB	605.2 KB	3.7 MB	84%	3.0 KB
8	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	403.3 KB	2.7 MB	85%	2.1 KB
9	news.fcku.it	2.1 MB	0.0 KB	1.1 MB	3.2 MB	66%	3.1 KB
10	news-out.mixmin.net	705.8 KB	0.0 KB	543.9 KB	1.2 MB	56%	3.5 KB
11	news.swapon.de	183.7 KB	0.0 KB	4.4 MB	4.5 MB	3%	57.4 KB
12	news.freedyn.net	146.4 KB	0.0 KB	143.7 KB	290.0 KB	50%	4.7 KB
13	nntp.pasdenom.info	32.4 KB	3.8 KB	10.9 KB	47.1 KB	68%	2.4 KB
14	news.bbs.geek.nz	12.0 KB	0.0 KB	0.0 KB	12.0 KB	100%	1.7 KB
15	xanadu-bbs.net	3.3 KB	0.0 KB	25.9 KB	29.2 KB	11%	4.9 KB
16	localhost	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
TOTAL: 16		59.4 MB	43.8 KB	48.0 MB	107.5 MB	55%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 13 04:15:00 - 04:59:59	265	1.4%	0.10	933.0 KB	1.6%	0.35
Mar 13 05:00:00 - 05:59:59	486	2.5%	0.14	1.9 MB	3.3%	0.55
Mar 13 06:00:00 - 06:59:59	595	3.1%	0.17	2.3 MB	4.0%	0.66
Mar 13 07:00:00 - 07:59:59	573	2.9%	0.16	1.7 MB	2.8%	0.47
Mar 13 08:00:00 - 08:59:59	847	4.3%	0.24	2.3 MB	3.9%	0.65
Mar 13 09:00:00 - 09:59:59	823	4.2%	0.23	2.4 MB	4.1%	0.68
Mar 13 10:00:00 - 10:59:59	868	4.5%	0.24	2.4 MB	4.0%	0.67
Mar 13 11:00:00 - 11:59:59	817	4.2%	0.23	2.2 MB	3.7%	0.61
Mar 13 12:00:00 - 12:59:59	858	4.4%	0.24	2.2 MB	3.7%	0.61
Mar 13 13:00:00 - 13:59:59	965	4.9%	0.27	2.9 MB	5.0%	0.84
Mar 13 14:00:00 - 14:59:59	1006	5.2%	0.28	2.5 MB	4.3%	0.71
Mar 13 15:00:00 - 15:59:59	1142	5.9%	0.32	3.6 MB	6.1%	1.02
Mar 13 16:00:00 - 16:59:59	1290	6.6%	0.36	4.4 MB	7.5%	1.24
Mar 13 17:00:00 - 17:59:59	1116	5.7%	0.31	3.1 MB	5.2%	0.87
Mar 13 18:00:00 - 18:59:59	1159	5.9%	0.32	3.3 MB	5.7%	0.95
Mar 13 19:00:00 - 19:59:59	1150	5.9%	0.32	4.0 MB	6.8%	1.14
Mar 13 20:00:00 - 20:59:59	1084	5.6%	0.30	3.2 MB	5.5%	0.91
Mar 13 21:00:00 - 21:59:59	981	5.0%	0.27	2.7 MB	4.7%	0.78
Mar 13 22:00:00 - 22:59:59	807	4.1%	0.22	2.4 MB	4.0%	0.68
Mar 13 23:00:00 - 23:59:59	729	3.7%	0.20	2.2 MB	3.7%	0.62
Mar 14 00:00:00 - 00:59:59	511	2.6%	0.14	1.6 MB	2.7%	0.44
Mar 14 01:00:00 - 01:59:59	454	2.3%	0.13	1.2 MB	2.1%	0.35
Mar 14 02:00:00 - 02:59:59	452	2.3%	0.13	1.5 MB	2.6%	0.44
Mar 14 03:00:00 - 03:59:59	406	2.1%	0.11	1.5 MB	2.5%	0.41
Mar 14 04:00:00 - 04:15:00	113	0.6%	0.13	406.6 KB	0.7%	0.45
TOTAL: 24:00:00	19497	100.0%	0.23	58.6 MB	100.0%	0.69

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	csiph-out.feeder.erje.net	2654	265	4	0	2385
2	newsfeed.neva.ru	2159	215	0	0	1944
3	feeder.eternal-september.org	949	61	1	0	887
4	newsfeed.fu-berlin.de	734	20	0	0	714
5	csiph-out.news.weretis.net	443	201	1	0	241
6	news-out.mixmin.net	171	1	0	0	170
7	news.fcku.it	161	1	0	0	160
8	news.redatomik.org	43	0	0	0	43
9	news.swapon.de	27	0	0	0	27
10	news.freedyn.net	17	3	0	0	14
11	news-out.aioe.org	10	7	0	0	3
12	newsfeeds.xmission.com	5	1	0	0	4
13	xanadu-bbs.net	5	0	0	0	5
14	nntp.pasdenom.info	4	1	1	1	1
TOTAL: 14		7382	776	7	1	6598

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	86
it.annunci.varie	46
it.annunci.immobiliari	46
alt.windows7.general	45
it.annunci.commerciali	45
mpc.lists.nanog	44
gmane.linux.alsa.devel	43
lada.auto	38
mozilla.support.thunderbird	25
mozilla.dev.security.policy	22
mozilla.support.seamonkey	17
mozilla.dev.platform	17
lada.kitchen	17
alt.bbs.synchronet	16
cakewalk.audio	14
free.it.religioni.scientology	11
spline.eisfair	11
gmane.linux.kernel	11
alt.politics.usa	11
gmane.comp.gis.openstreetmap	8
TOTAL: 114	776

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4652
EMP (phn path)	360
Binary: misplaced rar	207
EMP (phn nph)	126
User-issued cancel	39
Too many newsgroups	33
HTML Multipart	27
EMP (phl)	17
Scoring filter (9)	17
Scoring filter (8)	12
Scoring filter (10)	11
Binary Payload	11
Scoring filter (12)	6
Binary: misplaced par2	4
Too many newsgroups (low_xpost)	2
HTML post	2
HTML Image Tags	1
Binary: misplaced com	1
Binary: misplaced 260	1
Poison newsgroup	1
TOTAL: 23	5533

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	158
	TOTAL: 1	158
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	csiph-out.feeder.erje.net	149
	csiph-out.news.weretis.net	122
	news.swapon.de	8
	news.fcku.it	7
	feeder.eternal-september.org	6
	news-out.mixmin.net	5
	newsfeeds.xmission.com	3
	news.freedyn.net	2
	newsfeed.fu-berlin.de	2
	news.redatomik.org	1
	TOTAL: 10	305
Including strange strings
	newsfeed.fu-berlin.de	477
	csiph-out.feeder.erje.net	38
	news.redatomik.org	27
	csiph-out.news.weretis.net	23
	news.swapon.de	19
	newsfeed.neva.ru	6
	feeder.eternal-september.org	2
	news-out.aioe.org	2
	TOTAL: 8	594
No colon-space in header
	csiph-out.feeder.erje.net	6
	TOTAL: 1	6
TOTAL: 5		1067

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	34987	1064	17901	12	1	3%	23:59:48
2	news.freedyn.net	20713	649	18791	9	0	3%	23:59:48
3	xanadu-bbs	22409	604	18890	0	0	2%	23:59:43
4	fu-berlin.de	18148	498	17650	0	0	2%	23:59:48
5	usenet.pasdenom.info	20890	102	18237	1140	2	0%	23:59:48
6	aioe	17127	70	16375	0	0	0%	23:59:48
7	weretis.net	14745	23	13336	7	0	0%	23:59:48
8	neva.ru	17823	10	17296	135	0	0%	23:59:48
9	eternal-september	16711	7	15995	1	0	0%	23:59:48
10	erje	17329	6	16194	1	0	0%	23:59:48
11	xmission	19122	5	16471	0	335	0%	23:59:48
12	news.redatomik.org	18539	5	17859	0	0	0%	23:59:48
13	news.mixmin.net	18445	3	17988	0	0	0%	23:59:48
14	news.swapon.de	1832	2	1813	0	0	0%	23:46:18
15	news.fcku.it	1891	0	1883	0	0	0%	22:05:26
TOTAL: 15		260711	3048	226679	1305	338	1%	357:49:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.9 MB	51.8 KB	7.9 MB	0.1 KB/s	12.4 KB	23:59:48
2	xanadu-bbs	7.7 MB	0.0 KB	7.7 MB	0.1 KB/s	13.1 KB	23:59:43
3	fu-berlin.de	7.5 MB	0.0 KB	7.5 MB	0.1 KB/s	15.4 KB	23:59:48
4	news.bbs.geek.nz	2.9 MB	36.6 KB	2.9 MB	0.0 KB/s	2.8 KB	23:59:48
5	usenet.pasdenom.info	373.2 KB	8.6 MB	8.9 MB	0.1 KB/s	7.4 KB	23:59:48
6	aioe	142.6 KB	0.0 KB	142.6 KB	0.0 KB/s	2.0 KB	23:59:48
7	weretis.net	59.8 KB	21.9 KB	81.7 KB	0.0 KB/s	2.7 KB	23:59:48
8	erje	41.8 KB	1.4 KB	43.2 KB	0.0 KB/s	6.2 KB	23:59:48
9	neva.ru	23.3 KB	274.7 KB	298.0 KB	0.0 KB/s	2.1 KB	23:59:48
10	eternal-september	19.1 KB	2.9 KB	22.0 KB	0.0 KB/s	2.8 KB	23:59:48
11	xmission	10.0 KB	0.0 KB	10.0 KB	0.0 KB/s	2.0 KB	23:59:48
12	news.redatomik.org	9.7 KB	0.0 KB	9.7 KB	0.0 KB/s	1.9 KB	23:59:48
13	news.mixmin.net	5.0 KB	0.0 KB	5.0 KB	0.0 KB/s	1.7 KB	23:59:48
14	news.swapon.de	3.2 KB	0.0 KB	3.2 KB	0.0 KB/s	1.6 KB	23:46:18
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:05:26
TOTAL: 15		26.6 MB	8.9 MB	35.6 MB	0.0 KB/s	8.4 KB	357:49:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	45	131	361.6 KB	77	0	0	00:45:43
2	194-73-239-109.cust.centrio.cz	2	6	16.6 KB	3	0	0	00:03:42
3	a131033.upc-a.chello.nl	58	3	4.3 KB	3	0	0	14:47:40
4	96-42-18-226.dhcp.mdsn.wi.charter.com	26	1	1.6 KB	1	1	1	08:48:04
5	challenger.nielsenmedia.com	3	1	3.0 KB	1	0	0	01:09:31
TOTAL: 5		134	142	387.0 KB	85	1	1	28:35:01

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	45	131	361.6 KB	77	0	0	00:45:43
2	*.cust.centrio.cz	2	6	16.6 KB	3	0	0	00:03:42
3	*.upc-a.chello.nl	58	3	4.3 KB	3	0	0	14:47:40
4	*.nielsenmedia.com	3	1	3.0 KB	1	0	0	01:09:31
5	*.dhcp.mdsn.wi.charter.com	26	1	1.6 KB	1	1	1	08:48:04
6	*.ph.ph.cox.net	6	0	0.0 KB	0	0	0	03:00:19
TOTAL: 6		140	142	387.0 KB	85	1	1	28:35:01

NNRP auth users [Top 20]:

User	Conn
moussa	45
ctomczyk	2
ohlandl	1
TOTAL: 3	48

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	4.226	0.859	0.000	14:47:40
96-42-18-226.dhcp.mdsn.wi.charter.com	1.856	0.372	0.003	08:48:04
ip72-222-200-59.ph.ph.cox.net	0.530	0.142	0.000	03:00:19
challenger.nielsenmedia.com	0.339	0.057	0.000	01:09:31
141.168.117.108	5.337	0.990	0.324	00:45:43
194-73-239-109.cust.centrio.cz	0.238	0.034	0.009	00:03:42
TOTAL: 6	12.526	2.454	0.336	28:35:01

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-59.ph.ph.cox.net	6
TOTAL: 1	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
96-42-18-226.dhcp.mdsn.wi.charter.com	6	0
ip72-222-200-59.ph.ph.cox.net	6	0
challenger.nielsenmedia.com	2	0
a131033.upc-a.chello.nl	1	0
TOTAL: 4	15	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	112	78.9%
2	alt	29	20.4%
3	soc	1	0.7%
TOTAL: 3		142	100.0%

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

Newsgroup		Count
1	comp.os.vms	61
2	alt.comp.freeware	29
3	comp.ai.philosophy	16
4	comp.arch	7
5	comp.lang.javascript	6
6	comp.sys.ibm.ps2.hardware	5
7	comp.arch.fpga	5
8	comp.sys.ibm.pc.hardware.storage	5
9	comp.sys.ibm.pc.games.action	3
10	comp.os.cpm	2
11	soc.culture.lebanon	1
12	comp.sys.hp.hardware	1
13	comp.lang.awk	1
TOTAL: 13		142

Daily Usenet report for csiph.com

Mar 13 04:15:00 -- Mar 14 04:15:00