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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	25941	56.8%	5.7 MB	71.0%
innd	10292	22.5%	1.3 MB	15.8%
nnrpd	6533	14.3%	560.1 KB	6.9%
innfeed	2820	6.2%	510.6 KB	6.3%
nocem	74	0.2%	8.2 KB	0.1%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	45661	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	313807	57.7%
Negative hits	204187	37.6%
Do not exist	25040	4.6%
Cache misses	411	0.1%
TOTAL: 4	543445	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.387	0.0%	218	0.000	6.362	126.000
article cleanup	00:00:00.147	0.0%	24788	0.000	0.006	0.027
article logging	00:00:00.779	0.0%	25392	0.000	0.031	1.767
article parse	00:00:01.919	0.0%	65787	0.000	0.029	0.549
article write	00:00:51.646	0.1%	20045	0.228	2.577	74.642
artlog/artcncl	00:00:00.014	0.0%	174	0.000	0.080	1.333
artlog/artparse	00:00:00.005	0.0%	387	0.000	0.013	0.250
data move	00:00:00.166	0.0%	523868	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.079	0.0%	217	0.000	0.364	25.000
hishave/artcncl	00:00:00.008	0.0%	218	0.000	0.037	3.000
hishave/artparse	00:00:00.000	0.0%	383	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:17.748	0.0%	545352	0.004	0.033	0.195
history sync	00:00:01.644	0.0%	372	0.000	4.419	74.500
history write	00:00:39.856	0.0%	24782	0.255	1.608	15.861
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.074	0.0%	383	0.000	2.804	17.538
idle	23:56:04.964	99.7%	524363	86.791	164.323	519.224
nntp read	00:00:02.514	0.0%	528668	0.002	0.005	0.010
overview write	00:01:19.975	0.1%	20045	0.297	3.990	96.670
perl filter	00:00:46.758	0.1%	24575	1.429	1.903	3.155
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.364	0.0%	40158	0.000	0.009	0.024
TOTAL: 24:00:58.817	24:00:11.047	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.051	0.0%	20012	0.000	0.003	0.029
article prepare	00:00:00.002	0.0%	20232	0.000	0.000	0.011
article read	00:00:00.718	0.0%	2583	0.000	0.278	38.667
backlog stats	00:00:00.236	0.0%	380836	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.919	0.0%	288521	0.002	0.007	0.015
data write	00:00:01.629	0.0%	272572	0.001	0.006	0.012
idle	23:53:50.499	100.0%	380836	108.408	225.899	641.105
status file	00:00:00.994	0.0%	249	0.000	3.992	71.000
TOTAL: 23:54:05.324	23:53:56.048	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	35:08:14.183	100.0%	6420	331.083	19703.144	450866.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.079	0.0%	13539	0.000	0.006	0.167
readart	00:00:00.572	0.0%	171	0.000	3.345	152.000
TOTAL: 35:08:50.393	35:08:14.834	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	40361	5345	34608	408	13%	47:10:20
2	csiph-out.feeder.erje.net	1154	83787	2634	79427	1726	3%	95:00:36
3	feeder.eternal-september.org	4	22054	2504	19166	384	11%	24:26:02
4	newsfeeds.xmission.com	3	19369	2149	17209	11	11%	24:20:40
5	news.redatomik.org	1	23136	1562	21534	40	6%	30:18:08
6	newsfeed.neva.ru	144	27045	1351	23822	1872	4%	71:29:00
7	newsfeed.fu-berlin.de	17	18830	1209	17354	267	6%	26:59:37
8	news-out.aioe.org	2	19430	1185	18225	20	6%	24:09:02
9	news-out.mixmin.net	3	20447	985	19209	253	4%	24:08:47
10	news.fcku.it	1	9083	963	7958	162	10%	23:59:58
11	news.swapon.de	5	1806	62	1739	5	3%	24:16:57
12	nntp.pasdenom.info	2	18499	29	18470	0	0%	23:59:15
13	news.freedyn.net	1	21074	20	21041	13	0%	23:41:45
14	localhost	41	8	8	0	0	100%	00:00:00
15	xanadu-bbs.net	13	688	1	686	1	0%	22:53:41
16	news.bbs.geek.nz	1	19578	0	19578	0	0%	23:59:44
TOTAL: 16		1397	345195	20007	320026	5162	5%	510:53:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	15.7 MB	0.0 KB	12.4 MB	28.1 MB	55%	5.0 KB
2	csiph-out.feeder.erje.net	10.9 MB	21.3 KB	10.7 MB	21.6 MB	50%	5.1 KB
3	feeder.eternal-september.org	6.5 MB	0.0 KB	2.2 MB	8.6 MB	75%	3.1 KB
4	newsfeeds.xmission.com	5.8 MB	0.0 KB	358.1 KB	6.2 MB	94%	2.9 KB
5	news.redatomik.org	3.9 MB	0.0 KB	152.6 KB	4.0 MB	96%	2.6 KB
6	newsfeed.neva.ru	3.8 MB	7.4 KB	6.5 MB	10.3 MB	36%	3.3 KB
7	news-out.mixmin.net	3.1 MB	3.1 KB	716.8 KB	3.8 MB	81%	3.1 KB
8	news-out.aioe.org	3.1 MB	0.0 KB	27.8 KB	3.1 MB	99%	2.6 KB
9	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	526.3 KB	3.2 MB	84%	2.2 KB
10	news.fcku.it	2.2 MB	0.0 KB	5.1 MB	7.2 MB	29%	6.6 KB
11	news.swapon.de	141.4 KB	0.0 KB	2.5 MB	2.6 MB	5%	40.3 KB
12	nntp.pasdenom.info	65.5 KB	0.0 KB	0.0 KB	65.5 KB	100%	2.3 KB
13	news.freedyn.net	55.7 KB	0.0 KB	120.3 KB	176.0 KB	31%	5.3 KB
14	localhost	14.1 KB	0.0 KB	0.0 KB	14.1 KB	100%	1.8 KB
15	xanadu-bbs.net	1.6 KB	0.0 KB	13.2 KB	14.8 KB	11%	7.4 KB
16	news.bbs.geek.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		57.9 MB	31.7 KB	41.2 MB	99.1 MB	58%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 16 04:15:00 - 04:59:59	274	1.4%	0.10	884.4 KB	1.5%	0.33
Mar 16 05:00:00 - 05:59:59	564	2.8%	0.16	2.4 MB	4.2%	0.69
Mar 16 06:00:00 - 06:59:59	491	2.5%	0.14	1.7 MB	2.9%	0.49
Mar 16 07:00:00 - 07:59:59	587	2.9%	0.16	1.6 MB	2.7%	0.44
Mar 16 08:00:00 - 08:59:59	861	4.3%	0.24	2.6 MB	4.5%	0.74
Mar 16 09:00:00 - 09:59:59	1040	5.2%	0.29	2.7 MB	4.6%	0.76
Mar 16 10:00:00 - 10:59:59	934	4.7%	0.26	2.2 MB	3.7%	0.62
Mar 16 11:00:00 - 11:59:59	1201	6.0%	0.33	2.8 MB	4.7%	0.78
Mar 16 12:00:00 - 12:59:59	1106	5.5%	0.31	3.1 MB	5.3%	0.88
Mar 16 13:00:00 - 13:59:59	1025	5.1%	0.28	2.7 MB	4.7%	0.77
Mar 16 14:00:00 - 14:59:59	1125	5.6%	0.31	3.0 MB	5.1%	0.84
Mar 16 15:00:00 - 15:59:59	1265	6.3%	0.35	3.7 MB	6.3%	1.04
Mar 16 16:00:00 - 16:59:59	986	4.9%	0.27	2.6 MB	4.5%	0.73
Mar 16 17:00:00 - 17:59:59	973	4.9%	0.27	2.5 MB	4.4%	0.72
Mar 16 18:00:00 - 18:59:59	1071	5.3%	0.30	3.0 MB	5.2%	0.86
Mar 16 19:00:00 - 19:59:59	1007	5.0%	0.28	3.8 MB	6.6%	1.08
Mar 16 20:00:00 - 20:59:59	1070	5.3%	0.30	3.1 MB	5.3%	0.88
Mar 16 21:00:00 - 21:59:59	904	4.5%	0.25	2.7 MB	4.6%	0.76
Mar 16 22:00:00 - 22:59:59	766	3.8%	0.21	2.4 MB	4.2%	0.69
Mar 16 23:00:00 - 23:59:59	730	3.6%	0.20	2.3 MB	3.9%	0.64
Mar 17 00:00:00 - 00:59:59	579	2.9%	0.16	1.7 MB	2.9%	0.48
Mar 17 01:00:00 - 01:59:59	484	2.4%	0.13	1.5 MB	2.6%	0.43
Mar 17 02:00:00 - 02:59:59	458	2.3%	0.13	1.4 MB	2.4%	0.39
Mar 17 03:00:00 - 03:59:59	420	2.1%	0.12	1.6 MB	2.7%	0.45
Mar 17 04:00:00 - 04:15:00	111	0.6%	0.12	337.3 KB	0.6%	0.37
TOTAL: 24:00:00	20032	100.0%	0.23	58.0 MB	100.0%	0.69

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	1848	185	2	1661
2	csiph-out.feeder.erje.net	1753	182	7	1564
3	newsfeed.fu-berlin.de	770	57	0	713
4	csiph-out.news.weretis.net	420	232	0	188
5	feeder.eternal-september.org	384	72	0	312
6	news-out.mixmin.net	265	1	1	263
7	news.fcku.it	157	20	0	137
8	news.redatomik.org	70	0	0	70
9	news-out.aioe.org	24	18	0	6
10	news.swapon.de	18	0	0	18
11	news.freedyn.net	14	0	0	14
12	newsfeeds.xmission.com	11	6	0	5
13	xanadu-bbs.net	1	0	0	1
TOTAL: 13		5735	773	10	4952

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	93
it-alt.sport.calcio.juventus	78
spline.eisfair	49
lada.kitchen	37
it.annunci.commerciali	37
it.annunci.immobiliari	37
it.annunci.varie	36
gmane.linux.alsa.devel	32
lada.auto	29
mpc.lists.freebsd.gnome	24
de.alt.rec.getraenke	22
alt.windows7.general	21
oesterreich.tratsch	16
mozilla.support.thunderbird	15
alt.bbs.synchronet	12
gmane.comp.gis.openstreetmap	11
alt.politics	11
alt.survival	10
gmane.comp.gis.openstreetmap.tagging	10
cakewalk.audio	9
TOTAL: 107	773

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3335
EMP (phn path)	202
EMP (phn nph)	64
User-issued cancel	51
Too many newsgroups	25
Binary Payload	20
HTML Multipart	19
Scoring filter (10)	9
Scoring filter (11)	8
Scoring filter (13)	7
Binary: misplaced rar	5
Scoring filter (9)	3
Scoring filter (12)	3
HTML post	2
Too many newsgroups (meow)	1
Binary: misplaced arj	1
Scoring filter (8)	1
Scoring filter (14)	1
Binary Image: misplaced png	1
TOTAL: 19	3758

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	49	0	60	60
TOTAL: 1	49	19	60	60

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	203
	TOTAL: 1	203
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	csiph-out.feeder.erje.net	159
	csiph-out.news.weretis.net	133
	news.fcku.it	57
	feeder.eternal-september.org	16
	news-out.mixmin.net	7
	news.swapon.de	5
	newsfeeds.xmission.com	3
	news.freedyn.net	2
	news.redatomik.org	1
	TOTAL: 9	383
Including strange strings
	newsfeed.fu-berlin.de	515
	news.redatomik.org	30
	csiph-out.feeder.erje.net	17
	news-out.mixmin.net	14
	news.swapon.de	13
	csiph-out.news.weretis.net	6
	news-out.aioe.org	5
	feeder.eternal-september.org	3
	newsfeeds.xmission.com	1
	TOTAL: 9	604
No colon-space in header
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
TOTAL: 5		1198

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	36801	1110	18219	19	1	3%	23:59:51
2	news.freedyn.net	21278	807	19187	7	0	3%	23:59:51
3	xanadu-bbs	22487	750	19281	0	0	3%	23:59:51
4	fu-berlin.de	18639	656	17983	0	0	3%	23:59:53
5	usenet.pasdenom.info	22715	166	18572	1257	7	0%	23:59:53
6	aioe	17666	54	16761	0	0	0%	23:59:33
7	weretis.net	14248	32	13046	11	0	0%	23:59:53
8	erje	18484	17	17261	6	5	0%	23:59:53
9	xmission	19472	14	17092	0	0	0%	23:59:53
10	eternal-september	17756	9	16674	0	0	0%	23:59:53
11	news.mixmin.net	18420	9	17939	0	0	0%	23:59:40
12	news.redatomik.org	18665	9	17885	0	0	0%	23:59:23
13	neva.ru	18227	9	17628	109	0	0%	23:59:53
14	news.swapon.de	1907	8	1878	0	0	0%	23:50:34
15	news.fcku.it	1945	0	1934	0	0	0%	22:30:04
TOTAL: 15		268710	3650	231340	1409	13	1%	358:17:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	5.9 MB	58.2 KB	6.0 MB	0.1 KB/s	7.5 KB	23:59:51
2	xanadu-bbs	5.7 MB	0.0 KB	5.7 MB	0.1 KB/s	7.8 KB	23:59:51
3	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	8.6 KB	23:59:53
4	news.bbs.geek.nz	3.1 MB	78.5 KB	3.1 MB	0.0 KB/s	2.8 KB	23:59:51
5	usenet.pasdenom.info	428.1 KB	6.4 MB	6.9 MB	0.1 KB/s	4.9 KB	23:59:53
6	aioe	121.3 KB	0.0 KB	121.3 KB	0.0 KB/s	2.2 KB	23:59:33
7	weretis.net	72.9 KB	64.6 KB	137.5 KB	0.0 KB/s	3.2 KB	23:59:53
8	erje	34.1 KB	9.9 KB	44.0 KB	0.0 KB/s	1.9 KB	23:59:53
9	xmission	30.9 KB	0.0 KB	30.9 KB	0.0 KB/s	2.2 KB	23:59:53
10	eternal-september	16.0 KB	0.0 KB	16.0 KB	0.0 KB/s	1.8 KB	23:59:53
11	news.mixmin.net	16.0 KB	0.0 KB	16.0 KB	0.0 KB/s	1.8 KB	23:59:40
12	news.redatomik.org	16.0 KB	0.0 KB	16.0 KB	0.0 KB/s	1.8 KB	23:59:23
13	neva.ru	15.9 KB	228.0 KB	243.9 KB	0.0 KB/s	2.1 KB	23:59:53
14	news.swapon.de	14.2 KB	0.0 KB	14.2 KB	0.0 KB/s	1.8 KB	23:50:34
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:30:04
TOTAL: 15		20.9 MB	6.9 MB	27.8 MB	0.0 KB/s	5.6 KB	358:17:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	49	149	490.6 KB	97	5	0	00:43:23
2	96-42-18-226.dhcp.mdsn.wi.charter.com	14	12	21.9 KB	7	3	3	03:34:39
3	a131033.upc-a.chello.nl	85	9	15.3 KB	5	0	0	26:11:34
4	challenger.nielsenmedia.com	1	1	1.9 KB	1	0	0	00:30:04
TOTAL: 4		149	171	529.6 KB	110	8	3	35:08:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	49	149	490.6 KB	97	5	0	00:43:23
2	*.dhcp.mdsn.wi.charter.com	14	12	21.9 KB	7	3	3	03:34:39
3	*.upc-a.chello.nl	85	9	15.3 KB	5	0	0	26:11:34
4	*.nielsenmedia.com	1	1	1.9 KB	1	0	0	00:30:04
5	*.ph.ph.cox.net	11	0	0.0 KB	0	0	0	03:39:02
TOTAL: 5		160	171	529.6 KB	110	8	3	35:08:48

NNRP auth users [Top 20]:

User	Conn
moussa	49
ohlandl	3
TOTAL: 2	52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	6.215	1.395	0.000	26:11:34
ip72-222-200-59.ph.ph.cox.net	0.877	0.149	0.000	03:39:02
96-42-18-226.dhcp.mdsn.wi.charter.com	1.407	0.247	0.011	03:34:39
141.168.117.108	5.724	1.096	0.167	00:43:23
challenger.nielsenmedia.com	0.116	0.025	0.000	00:30:04
c-67-160-62-214.hsd1.wa.comcast.net	0.085	0.023	0.000	00:30:04
TOTAL: 6	14.424	2.935	0.178	35:08:48

Curious NNRP server explorers [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-59.ph.ph.cox.net	5	0
96-42-18-226.dhcp.mdsn.wi.charter.com	4	0
challenger.nielsenmedia.com	1	0
c-67-160-62-214.hsd1.wa.comcast.net	1	0
TOTAL: 4	11	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	141	82.5%
2	alt	30	17.5%
TOTAL: 2		171	100.0%

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

Newsgroup		Count
1	comp.os.vms	41
2	comp.ai.philosophy	35
3	alt.comp.freeware	30
4	comp.sys.ibm.ps2.hardware	28
5	comp.arch	14
6	comp.arch.fpga	14
7	comp.sys.ibm.pc.games.action	5
8	comp.lang.awk	1
9	comp.sys.ibm.pc.hardware.storage	1
10	comp.archives.ms-windows.discuss	1
11	comp.os.cpm	1
TOTAL: 11		171

Daily Usenet report for csiph.com

Mar 16 04:15:00 -- Mar 17 04:15:00