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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53175	45.3%	9.9 MB	61.7%
nnrpd	33090	28.2%	2.8 MB	17.3%
innd	27728	23.6%	2.8 MB	17.6%
innfeed	3185	2.7%	546.3 KB	3.3%
nocem	96	0.1%	9.7 KB	0.1%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	117275	100.0%	16.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	590966	56.0%
Negative hits	412044	39.0%
Do not exist	52272	4.9%
Cache misses	745	0.1%
TOTAL: 4	1056027	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.364	0.0%	263	0.000	1.384	20.000
article cleanup	00:00:00.287	0.0%	52070	0.000	0.006	0.025
article logging	00:00:00.702	0.0%	52625	0.000	0.013	0.043
article parse	00:00:03.044	0.0%	223301	0.002	0.014	0.134
article write	00:01:15.239	0.1%	28818	0.187	2.611	14.202
artlog/artcncl	00:00:00.003	0.0%	197	0.000	0.015	0.167
artlog/artparse	00:00:00.006	0.0%	320	0.000	0.019	0.500
data move	00:00:00.322	0.0%	1036351	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.056	0.0%	259	0.000	0.216	11.000
hishave/artcncl	00:00:00.003	0.0%	263	0.000	0.011	0.167
hishave/artparse	00:00:00.001	0.0%	315	0.000	0.003	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:25.582	0.0%	1058412	0.002	0.024	0.249
history sync	00:00:04.279	0.0%	351	0.000	12.191	61.500
history write	00:02:41.895	0.2%	52023	0.049	3.112	17.734
hiswrite/artcncl	00:00:00.004	0.0%	4	0.000	1.000	4.000
hiswrite/artparse	00:00:00.796	0.0%	315	0.000	2.527	53.000
idle	23:50:42.231	99.3%	1046194	46.737	82.052	228.453
nntp read	00:00:03.395	0.0%	1060350	0.002	0.003	0.005
overview write	00:02:06.006	0.1%	28818	1.347	4.372	45.490
perl filter	00:02:23.924	0.2%	52018	1.514	2.767	4.598
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.518	0.0%	57724	0.000	0.009	0.041
TOTAL: 24:00:52.928	23:59:48.657	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.080	0.0%	28754	0.000	0.003	0.032
article prepare	00:00:00.007	0.0%	34288	0.000	0.000	0.010
article read	00:00:00.679	0.0%	3877	0.000	0.175	7.125
backlog stats	00:00:00.312	0.0%	685101	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.496	0.0%	495933	0.002	0.005	0.011
data write	00:00:03.356	0.0%	476938	0.004	0.007	0.012
idle	23:52:36.828	100.0%	685101	64.907	125.466	398.820
status file	00:00:00.200	0.0%	290	0.000	0.690	1.111
TOTAL: 23:52:57.608	23:52:43.958	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:07.834	0.0%	26676	0.282	0.294	0.301
idle	78:30:07.929	100.0%	59338	398.513	4762.680	600292.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.744	0.0%	119528	0.000	0.006	0.167
readart	00:00:00.226	0.0%	75	0.000	3.013	20.714
TOTAL: 78:31:26.848	78:30:16.733	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	34232	6402	24810	3020	18%	23:45:01
2	feeder.eternal-september.org	1	46800	3849	39296	3655	8%	23:25:36
3	news-out.mixmin.net	4	32459	3609	26870	1980	11%	24:17:57
4	csiph-out.feeder.erje.net	1152	108194	3329	103575	1290	3%	94:58:38
5	news-out.aioe.org	4	27725	2654	25040	31	9%	24:18:45
6	csiph-out.news.weretis.net	4	63107	2425	60197	485	3%	49:24:19
7	newsfeed.neva.ru	143	63746	2272	54010	7464	3%	71:57:28
8	newsfeed.fu-berlin.de	44	33701	1406	32015	280	4%	33:27:06
9	news.redatomik.org	1	27733	764	26963	6	2%	24:13:47
10	newsfeeds.xmission.com	1	29305	637	28650	18	2%	25:12:18
11	xanadu-bbs.net	1	51042	510	45477	5055	0%	24:13:08
12	news.fcku.it	1	9478	408	9068	2	4%	24:23:24
13	news.freedyn.net	4	32970	406	32339	225	1%	24:09:49
14	news.swapon.de	2	2250	137	2112	1	6%	24:00:24
15	au2pb.net	1	31284	103	31147	34	0%	24:24:16
16	nntp.pasdenom.info	3	28567	17	28546	4	0%	24:09:33
17	news.bbs.geek.nz	2	29836	2	29834	0	0%	23:59:43
18	localhost	36	1	1	0	0	100%	00:00:00
TOTAL: 18		1692	652430	28931	599949	23550	4%	564:21:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	18.8 MB	0.0 KB	32.0 MB	50.8 MB	37%	5.5 KB
2	csiph-out.feeder.erje.net	15.1 MB	207.1 KB	4.9 MB	20.2 MB	74%	4.5 KB
3	news-out.mixmin.net	11.8 MB	0.0 KB	3.7 MB	15.5 MB	75%	2.8 KB
4	feeder.eternal-september.org	9.1 MB	8.5 KB	40.4 MB	49.5 MB	18%	6.8 KB
5	csiph-out.news.weretis.net	7.9 MB	0.0 KB	8.0 MB	16.0 MB	49%	5.6 KB
6	newsfeed.neva.ru	6.4 MB	168.1 KB	61.1 MB	67.7 MB	9%	7.1 KB
7	news-out.aioe.org	6.2 MB	0.0 KB	48.8 KB	6.2 MB	99%	2.4 KB
8	newsfeed.fu-berlin.de	3.1 MB	0.0 KB	640.2 KB	3.7 MB	83%	2.2 KB
9	news.freedyn.net	1.9 MB	6.1 KB	1.4 MB	3.3 MB	58%	5.4 KB
10	newsfeeds.xmission.com	1.8 MB	0.0 KB	169.9 KB	1.9 MB	91%	3.0 KB
11	news.redatomik.org	1.5 MB	0.0 KB	180.7 KB	1.7 MB	89%	2.2 KB
12	xanadu-bbs.net	1.5 MB	0.0 KB	45.6 MB	47.1 MB	3%	8.7 KB
13	news.fcku.it	713.5 KB	0.0 KB	47.8 KB	761.3 KB	93%	1.9 KB
14	au2pb.net	379.3 KB	0.0 KB	290.9 KB	670.3 KB	56%	4.9 KB
15	news.swapon.de	311.0 KB	0.0 KB	89.0 KB	400.0 KB	77%	2.9 KB
16	nntp.pasdenom.info	38.4 KB	0.0 KB	14.8 KB	53.1 KB	72%	2.5 KB
17	localhost	3.3 KB	0.0 KB	0.0 KB	3.3 KB	100%	3.3 KB
18	news.bbs.geek.nz	2.7 KB	0.0 KB	0.0 KB	2.7 KB	100%	1.3 KB
TOTAL: 18		86.5 MB	389.7 KB	198.6 MB	285.5 MB	30%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 29 04:15:00 - 04:59:59	381	1.3%	0.14	1.3 MB	1.5%	0.49
Jan 29 05:00:00 - 05:59:59	592	2.1%	0.16	2.1 MB	2.5%	0.61
Jan 29 06:00:00 - 06:59:59	618	2.1%	0.17	2.3 MB	2.6%	0.64
Jan 29 07:00:00 - 07:59:59	808	2.8%	0.22	1.9 MB	2.3%	0.55
Jan 29 08:00:00 - 08:59:59	1263	4.4%	0.35	4.1 MB	4.8%	1.17
Jan 29 09:00:00 - 09:59:59	1501	5.2%	0.42	5.2 MB	6.0%	1.47
Jan 29 10:00:00 - 10:59:59	1499	5.2%	0.42	4.4 MB	5.1%	1.25
Jan 29 11:00:00 - 11:59:59	1451	5.0%	0.40	3.7 MB	4.3%	1.06
Jan 29 12:00:00 - 12:59:59	1344	4.7%	0.37	3.7 MB	4.3%	1.04
Jan 29 13:00:00 - 13:59:59	1379	4.8%	0.38	3.4 MB	3.9%	0.96
Jan 29 14:00:00 - 14:59:59	1627	5.6%	0.45	4.2 MB	4.9%	1.20
Jan 29 15:00:00 - 15:59:59	1792	6.2%	0.50	5.1 MB	5.9%	1.45
Jan 29 16:00:00 - 16:59:59	1659	5.8%	0.46	5.3 MB	6.1%	1.50
Jan 29 17:00:00 - 17:59:59	1608	5.6%	0.45	5.2 MB	6.0%	1.47
Jan 29 18:00:00 - 18:59:59	1846	6.4%	0.51	5.4 MB	6.3%	1.55
Jan 29 19:00:00 - 19:59:59	1583	5.5%	0.44	4.5 MB	5.3%	1.29
Jan 29 20:00:00 - 20:59:59	1437	5.0%	0.40	4.6 MB	5.4%	1.31
Jan 29 21:00:00 - 21:59:59	1454	5.0%	0.40	3.9 MB	4.6%	1.12
Jan 29 22:00:00 - 22:59:59	1207	4.2%	0.34	3.4 MB	4.0%	0.97
Jan 29 23:00:00 - 23:59:59	1033	3.6%	0.29	3.1 MB	3.6%	0.88
Jan 30 00:00:00 - 00:59:59	838	2.9%	0.23	2.9 MB	3.4%	0.83
Jan 30 01:00:00 - 01:59:59	676	2.3%	0.19	2.3 MB	2.6%	0.64
Jan 30 02:00:00 - 02:59:59	572	2.0%	0.16	1.6 MB	1.9%	0.46
Jan 30 03:00:00 - 03:59:59	491	1.7%	0.14	1.5 MB	1.7%	0.42
Jan 30 04:00:00 - 04:15:00	150	0.5%	0.17	708.6 KB	0.8%	0.79
TOTAL: 24:00:00	28809	100.0%	0.33	85.9 MB	100.0%	1.02

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	7523	1219	19	6285
2	xanadu-bbs.net	5050	30	0	5020
3	feeder.eternal-september.org	3750	169	2	3579
4	usenet.blueworldhosting.com	3029	1123	0	1906
5	news-out.mixmin.net	2035	5	0	2030
6	csiph-out.feeder.erje.net	1311	337	30	944
7	newsfeed.fu-berlin.de	661	37	0	624
8	csiph-out.news.weretis.net	447	274	0	173
9	news.freedyn.net	225	4	1	220
10	news-out.aioe.org	47	28	0	19
11	news.swapon.de	30	0	0	30
12	au2pb.net	24	22	0	2
13	newsfeeds.xmission.com	18	16	0	2
14	news.redatomik.org	11	0	0	11
15	news.fcku.it	4	0	0	4
16	nntp.pasdenom.info	4	4	0	0
TOTAL: 16		24169	3268	52	20849

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	623
lada.talk	208
mailing.freebsd.pkg-fallout	154
ut.test	131
lada.auto	98
24hoursupport.helpdesk	97
mailing.freebsd.ports-bugs	84
mailing.freebsd.svn-ports-all	72
mailing.freebsd.svn-ports-head	69
mailing.openssl.cvs	63
mailing.openbsd.source-changes	55
alt.binaries.multimedia.tatu	55
it-alt.sport.calcio.juventus	54
mailing.gcc.gcc-cvs	49
pld.cvs.commit	45
mozilla.dev.tree-alerts	45
zixia.goabroad.country.united_kingdom	45
mailing.openbsd.ports-changes	42
mailing.freebsd.svn-src-all	33
cakewalk.audio	31
TOTAL: 265	3268

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15179
EMP (phn path)	2844
EMP (phl)	985
User-issued cancel	247
PFM bot misc	221
EMP (phn nph)	125
Too many newsgroups	62
Too many newsgroups (low_xpost)	31
HTML Multipart	28
Scoring filter (19)	24
Scoring filter (18)	24
Scoring filter (9)	23
Binary Payload	20
Scoring filter (17)	18
Scoring filter (14)	17
Scoring filter (25)	17
Scoring filter (20)	16
Scoring filter (16)	16
Scoring filter (21)	15
HTML Image Tags	9
TOTAL: 36	19976

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	88	0	90	90
TOTAL: 1	88	0	90	90

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	15
	usenet.blueworldhosting.com	5
	TOTAL: 2	20
Huge articles
	xanadu-bbs.net	125
	usenet.blueworldhosting.com	85
	csiph-out.news.weretis.net	52
	feeder.eternal-september.org	28
	news-out.mixmin.net	10
	news.freedyn.net	7
	newsfeed.fu-berlin.de	2
	newsfeeds.xmission.com	2
	news.redatomik.org	2
	au2pb.net	1
	TOTAL: 11	315
Including strange strings
	newsfeed.fu-berlin.de	411
	news-out.mixmin.net	31
	news.swapon.de	29
	feeder.eternal-september.org	21
	csiph-out.feeder.erje.net	19
	csiph-out.news.weretis.net	15
	news-out.aioe.org	15
	news.redatomik.org	7
	news.fcku.it	3
	xanadu-bbs.net	1
	TOTAL: 11	553
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		889

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	32165	1417	26978	4	2	4%	23:59:34
2	xanadu-bbs	30860	1275	27012	1	0	4%	23:59:52
3	news.bbs.geek.nz	53078	1036	26606	11	4	1%	23:59:41
4	fu-berlin.de	27115	1018	26096	0	1	3%	23:59:41
5	aioe	27710	592	23016	59	1	2%	23:59:50
6	usenet.pasdenom.info	36701	315	26830	1644	8	0%	23:59:51
7	news.redatomik.org	33405	190	26674	11	2	0%	23:59:51
8	au2pb	28625	118	27461	13	0	0%	23:59:52
9	xmission	33126	37	27075	3	2	0%	23:59:52
10	neva.ru	30652	21	24795	185	2	0%	23:59:48
11	eternal-september	29035	16	23225	0	2	0%	23:59:51
12	weretis.net	24433	12	23880	5	0	0%	23:59:34
13	news.mixmin.net	28206	12	23784	2	1	0%	23:59:50
14	news.fcku.it	4433	11	3704	0	0	0%	23:23:39
15	erje	28760	6	24863	580	2	0%	23:59:51
16	news.swapon.de	2574	2	2262	2	0	0%	23:59:45
17	bwh	22281	1	21170	0	0	0%	23:59:52
TOTAL: 17		473159	6079	385431	2520	27	1%	407:20:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.9 MB	53.1 KB	11.0 MB	0.1 KB/s	7.9 KB	23:59:34
2	xanadu-bbs	10.6 MB	2.0 KB	10.6 MB	0.1 KB/s	8.5 KB	23:59:52
3	fu-berlin.de	9.7 MB	0.0 KB	9.7 MB	0.1 KB/s	9.8 KB	23:59:41
4	news.bbs.geek.nz	2.8 MB	104.2 KB	2.9 MB	0.0 KB/s	2.9 KB	23:59:41
5	aioe	1.6 MB	227.4 KB	1.8 MB	0.0 KB/s	2.9 KB	23:59:50
6	usenet.pasdenom.info	1.1 MB	10.9 MB	12.0 MB	0.1 KB/s	6.3 KB	23:59:51
7	news.redatomik.org	572.7 KB	64.8 KB	637.4 KB	0.0 KB/s	3.2 KB	23:59:51
8	au2pb	536.1 KB	94.1 KB	630.3 KB	0.0 KB/s	4.8 KB	23:59:52
9	xmission	116.0 KB	3.9 KB	119.9 KB	0.0 KB/s	3.0 KB	23:59:52
10	weretis.net	72.8 KB	21.8 KB	94.6 KB	0.0 KB/s	5.6 KB	23:59:34
11	eternal-september	65.4 KB	0.0 KB	65.4 KB	0.0 KB/s	4.1 KB	23:59:51
12	neva.ru	61.2 KB	719.7 KB	780.9 KB	0.0 KB/s	3.8 KB	23:59:48
13	news.mixmin.net	33.7 KB	9.9 KB	43.6 KB	0.0 KB/s	3.1 KB	23:59:50
14	news.fcku.it	21.8 KB	0.0 KB	21.8 KB	0.0 KB/s	2.0 KB	23:23:39
15	erje	13.7 KB	2.1 MB	2.1 MB	0.0 KB/s	3.7 KB	23:59:51
16	news.swapon.de	4.6 KB	1.6 KB	6.2 KB	0.0 KB/s	1.5 KB	23:59:45
17	bwh	3.3 KB	0.0 KB	3.3 KB	0.0 KB/s	3.3 KB	23:59:52
TOTAL: 17		38.3 MB	14.2 MB	52.5 MB	0.0 KB/s	6.2 KB	407:20:14

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	286	57	184.3 KB	54	0	0	04:28:56
2	tempe0.bbox.io	14	12	23.4 KB	4	1	1	04:13:33
3	challenger.nielsenmedia.com	13	6	13.2 KB	4	0	0	03:33:08
TOTAL: 3		313	75	220.9 KB	62	1	1	78:31:23

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	290	57	184.3 KB	54	0	0	60:05:39
2	*.bbox.io	14	12	23.4 KB	4	1	1	04:13:33
3	*.nielsenmedia.com	13	6	13.2 KB	4	0	0	03:33:08
4	*.chello.nl	27	0	0.0 KB	0	0	0	10:39:02
TOTAL: 4		344	75	220.9 KB	62	1	1	78:31:23

NNRP auth users [Top 20]:

User	Conn
moussa	286
kev009	1
TOTAL: 2	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.221	1.034	1.286	0.000	55:36:42
dhcp-089-099-203-254.chello.nl	2.283	0.526	0.000	10:39:02
202.89.176.220	28.358	6.936	0.754	04:28:56
tempe0.bbox.io	1.593	0.266	0.003	04:13:33
challenger.nielsenmedia.com	1.084	0.288	0.000	03:33:08
TOTAL: 5	34.352	9.302	0.757	78:31:23

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	27
162.212.88.221	4
TOTAL: 2	31

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	19	0
tempe0.bbox.io	7	0
challenger.nielsenmedia.com	5	0
TOTAL: 3	31	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	71	94.7%
2	news	4	5.3%
TOTAL: 2		75	100.0%

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

Newsgroup		Count
1	comp.arch	39
2	comp.unix.misc	7
3	comp.sys.ibm.pc.games.action	6
4	comp.lang.awk	6
5	news.software.nntp	4
6	comp.arch.embedded	2
7	comp.os.cpm	2
8	comp.os.vms	2
9	comp.sys.hp.hpux	1
10	comp.sys.ibm.as400.misc	1
11	comp.os.qnx	1
12	comp.sys.ibm.pc.games.space-sim	1
13	comp.os.msdos.4dos	1
14	comp.ai.nat-lang	1
15	comp.answers	1
TOTAL: 15		75

Daily Usenet report for csiph.com

Jan 29 04:15:00 -- Jan 30 04:15:00