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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	44730	42.2%	8.3 MB	58.7%
nnrpd	33337	31.5%	2.8 MB	19.6%
innd	24492	23.1%	2.5 MB	17.9%
innfeed	3193	3.0%	543.8 KB	3.7%
nocem	120	0.1%	12.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	105873	100.0%	14.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	488515	55.6%
Negative hits	345569	39.3%
Do not exist	44050	5.0%
Cache misses	514	0.1%
TOTAL: 4	878648	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.414	0.0%	221	0.000	1.873	17.429
article cleanup	00:00:00.255	0.0%	43453	0.000	0.006	0.029
article logging	00:00:00.615	0.0%	43930	0.000	0.014	0.185
article parse	00:00:02.187	0.0%	187326	0.001	0.012	0.114
article write	00:00:59.714	0.1%	24210	0.189	2.467	30.896
artlog/artcncl	00:00:00.004	0.0%	120	0.000	0.033	0.500
artlog/artparse	00:00:00.006	0.0%	712	0.000	0.008	0.500
data move	00:00:00.295	0.0%	865826	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.086	0.0%	221	0.000	0.389	12.000
hishave/artcncl	00:00:00.005	0.0%	221	0.000	0.023	0.625
hishave/artparse	00:00:00.000	0.0%	212	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:32.295	0.0%	880210	0.002	0.037	0.291
history sync	00:00:04.001	0.0%	363	0.000	11.022	123.000
history write	00:02:18.622	0.2%	43920	0.075	3.156	15.214
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.576	0.0%	212	0.000	2.717	108.000
idle	23:51:36.675	99.4%	886027	57.718	96.946	263.919
nntp read	00:00:02.921	0.0%	897600	0.001	0.003	0.005
overview write	00:02:12.635	0.2%	24210	1.400	5.479	59.746
perl filter	00:02:04.132	0.1%	43420	1.593	2.859	4.882
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.414	0.0%	48530	0.000	0.009	0.030
TOTAL: 24:00:52.619	23:59:55.852	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.084	0.0%	24264	0.000	0.003	0.025
article prepare	00:00:00.005	0.0%	28516	0.000	0.000	0.014
article read	00:00:00.491	0.0%	3051	0.000	0.161	12.500
backlog stats	00:00:00.285	0.0%	574770	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.080	0.0%	415773	0.002	0.005	0.010
data write	00:00:02.856	0.0%	397247	0.003	0.007	0.013
idle	23:53:33.936	100.0%	574770	85.283	149.649	404.879
status file	00:00:00.228	0.0%	292	0.000	0.781	1.205
TOTAL: 23:53:53.047	23:53:39.965	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.283	0.0%	13125	0.000	0.174	1.615
idle	37:09:59.182	99.9%	46032	39.571	2906.656	600164.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.063	0.0%	94574	0.000	0.022	4.097
readart	00:01:06.319	0.0%	76711	0.000	0.865	53.000
TOTAL: 37:12:20.318	37:11:09.847	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	usenet.blueworldhosting.com	288	28825	5347	20934	2544	18%	23:44:53
2	csiph-out.feeder.erje.net	1152	90676	4374	84310	1992	4%	94:58:11
3	news-out.mixmin.net	3	28361	3189	23279	1893	11%	24:08:50
4	feeder.eternal-september.org	7	39661	2947	34397	2317	7%	25:49:20
5	news-out.aioe.org	3	22783	2192	20566	25	9%	24:09:02
6	newsfeed.neva.ru	141	52720	1779	44080	6861	3%	72:19:25
7	csiph-out.news.weretis.net	4	45366	1255	43877	234	2%	43:52:02
8	newsfeed.fu-berlin.de	29	24994	1226	23578	190	4%	27:22:47
9	news.redatomik.org	1	19842	514	19325	3	2%	21:42:07
10	news.fcku.it	1	7431	310	7121	0	4%	24:23:25
11	news.freedyn.net	3	28294	271	27840	183	0%	24:10:03
12	newsfeeds.xmission.com	0	16529	195	16326	8	1%	16:05:16
13	news.swapon.de	3	1899	123	1774	2	6%	22:06:09
14	xanadu-bbs.net	2	40559	117	36864	3578	0%	22:27:15
15	au2pb.net	1	30332	64	30243	25	0%	24:24:16
16	nntp.pasdenom.info	2	22673	22	22640	11	0%	23:59:30
17	news.bbs.geek.nz	1	27572	4	27568	0	0%	23:59:44
18	localhost	37	0	0	0	0	0%	00:00:00
TOTAL: 18		1678	528517	23929	484722	19866	4%	539:42:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	16.6 MB	105.0 KB	7.4 MB	24.1 MB	69%	3.9 KB
2	usenet.blueworldhosting.com	15.4 MB	0.0 KB	24.7 MB	40.1 MB	38%	5.2 KB
3	news-out.mixmin.net	9.8 MB	2.4 KB	3.3 MB	13.1 MB	74%	2.6 KB
4	feeder.eternal-september.org	7.0 MB	6.8 KB	26.0 MB	33.0 MB	21%	6.4 KB
5	news-out.aioe.org	5.3 MB	0.0 KB	69.0 KB	5.3 MB	98%	2.5 KB
6	newsfeed.neva.ru	5.1 MB	114.8 KB	54.4 MB	59.6 MB	8%	7.1 KB
7	csiph-out.news.weretis.net	4.2 MB	0.0 KB	4.0 MB	8.3 MB	51%	5.7 KB
8	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	413.1 KB	3.1 MB	87%	2.3 KB
9	news.redatomik.org	1.1 MB	0.0 KB	69.4 KB	1.2 MB	94%	2.3 KB
10	news.freedyn.net	1.1 MB	0.0 KB	1004.7 KB	2.1 MB	52%	4.7 KB
11	newsfeeds.xmission.com	676.8 KB	0.0 KB	261.4 KB	938.2 KB	72%	4.6 KB
12	news.fcku.it	660.7 KB	0.0 KB	0.0 KB	660.7 KB	100%	2.1 KB
13	xanadu-bbs.net	314.5 KB	0.0 KB	40.3 MB	40.6 MB	0%	11.3 KB
14	news.swapon.de	302.8 KB	0.0 KB	82.5 KB	385.4 KB	78%	3.1 KB
15	au2pb.net	155.9 KB	0.0 KB	141.9 KB	297.8 KB	52%	3.3 KB
16	nntp.pasdenom.info	57.2 KB	0.0 KB	134.4 KB	191.6 KB	29%	5.8 KB
17	news.bbs.geek.nz	10.8 KB	0.0 KB	0.0 KB	10.8 KB	100%	2.7 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		70.4 MB	228.9 KB	162.2 MB	232.8 MB	30%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 30 04:15:00 - 04:59:59	377	1.6%	0.14	1.1 MB	1.5%	0.41
Jan 30 05:00:00 - 05:59:59	422	1.7%	0.12	1.4 MB	2.0%	0.40
Jan 30 06:00:00 - 06:59:59	611	2.5%	0.17	2.8 MB	4.0%	0.80
Jan 30 07:00:00 - 07:59:59	645	2.7%	0.18	2.2 MB	3.1%	0.62
Jan 30 08:00:00 - 08:59:59	876	3.6%	0.24	2.7 MB	3.7%	0.75
Jan 30 09:00:00 - 09:59:59	992	4.1%	0.28	2.8 MB	4.0%	0.81
Jan 30 10:00:00 - 10:59:59	1206	5.0%	0.34	3.6 MB	5.1%	1.03
Jan 30 11:00:00 - 11:59:59	1255	5.2%	0.35	3.8 MB	5.3%	1.07
Jan 30 12:00:00 - 12:59:59	1078	4.5%	0.30	2.7 MB	3.8%	0.78
Jan 30 13:00:00 - 13:59:59	1255	5.2%	0.35	4.0 MB	5.7%	1.15
Jan 30 14:00:00 - 14:59:59	1150	4.8%	0.32	3.2 MB	4.5%	0.91
Jan 30 15:00:00 - 15:59:59	1200	5.0%	0.33	3.1 MB	4.4%	0.89
Jan 30 16:00:00 - 16:59:59	1273	5.3%	0.35	3.7 MB	5.2%	1.06
Jan 30 17:00:00 - 17:59:59	1474	6.1%	0.41	4.4 MB	6.1%	1.24
Jan 30 18:00:00 - 18:59:59	1542	6.4%	0.43	4.3 MB	6.0%	1.21
Jan 30 19:00:00 - 19:59:59	1324	5.5%	0.37	3.4 MB	4.8%	0.97
Jan 30 20:00:00 - 20:59:59	1389	5.7%	0.39	3.6 MB	5.1%	1.03
Jan 30 21:00:00 - 21:59:59	1330	5.5%	0.37	3.9 MB	5.5%	1.11
Jan 30 22:00:00 - 22:59:59	1141	4.7%	0.32	3.3 MB	4.6%	0.93
Jan 30 23:00:00 - 23:59:59	872	3.6%	0.24	2.5 MB	3.5%	0.70
Jan 31 00:00:00 - 00:59:59	771	3.2%	0.21	2.4 MB	3.3%	0.67
Jan 31 01:00:00 - 01:59:59	663	2.7%	0.18	2.3 MB	3.2%	0.65
Jan 31 02:00:00 - 02:59:59	657	2.7%	0.18	1.9 MB	2.6%	0.53
Jan 31 03:00:00 - 03:59:59	565	2.3%	0.16	1.8 MB	2.5%	0.50
Jan 31 04:00:00 - 04:15:00	126	0.5%	0.14	393.6 KB	0.5%	0.44
TOTAL: 24:00:00	24194	100.0%	0.28	71.1 MB	100.0%	0.84

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	6805	787	14	6004
2	xanadu-bbs.net	3737	24	0	3713
3	usenet.blueworldhosting.com	2546	763	0	1783
4	feeder.eternal-september.org	2298	109	1	2188
5	csiph-out.feeder.erje.net	2001	306	17	1678
6	news-out.mixmin.net	1894	2	1	1891
7	newsfeed.fu-berlin.de	572	33	0	539
8	csiph-out.news.weretis.net	240	147	0	93
9	news.freedyn.net	185	3	0	182
10	news-out.aioe.org	43	25	0	18
11	au2pb.net	38	37	0	1
12	news.swapon.de	20	0	0	20
13	newsfeeds.xmission.com	11	7	0	4
14	nntp.pasdenom.info	11	8	0	3
15	news.redatomik.org	9	0	0	9
16	news.fcku.it	6	0	0	6
TOTAL: 16		20416	2251	33	18132

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	273
lada.talk	163
alt.binaries.multimedia.tatu	121
ut.test	119
lada.auto	69
mailing.freebsd.svn-ports-all	68
mailing.freebsd.svn-ports-head	59
24hoursupport.helpdesk	57
it-alt.sport.calcio.juventus	55
mailing.openssl.cvs	48
mailing.freebsd.pkg-fallout	44
mailing.freebsd.ports-bugs	39
mozilla.dev.tree-alerts	37
mailing.openssl.dev	36
mailing.freebsd.bugs	33
fa.freebsd.bugs	32
mailing.freebsd.svn-src-all	31
fa.freebsd.cvs-all	30
alt.survival	27
mailing.freebsd.current	27
TOTAL: 221	2251

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12561
EMP (phn path)	2639
EMP (phl)	1033
PFM bot misc	247
EMP (phn nph)	116
Too many newsgroups	106
User-issued cancel	30
Binary Payload	29
HTML Multipart	28
Scoring filter (19)	26
Scoring filter (14)	21
Too many newsgroups (low_xpost)	21
Scoring filter (22)	16
Scoring filter (18)	10
Scoring filter (16)	10
Scoring filter (10)	8
Scoring filter (11)	7
Scoring filter (12)	5
Scoring filter (17)	5
Scoring filter (21)	4
TOTAL: 32	16943

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	110	0	119	119
TOTAL: 1	110	0	119	119

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	73
	usenet.blueworldhosting.com	60
	feeder.eternal-september.org	30
	csiph-out.news.weretis.net	26
	news-out.mixmin.net	10
	news.freedyn.net	5
	newsfeeds.xmission.com	4
	news.redatomik.org	1
	news.swapon.de	1
	newsfeed.fu-berlin.de	1
	TOTAL: 11	212
Including strange strings
	newsfeed.fu-berlin.de	375
	news-out.mixmin.net	22
	news.swapon.de	18
	news-out.aioe.org	16
	feeder.eternal-september.org	12
	csiph-out.news.weretis.net	10
	newsfeed.neva.ru	8
	csiph-out.feeder.erje.net	8
	news.redatomik.org	6
	news.fcku.it	1
	TOTAL: 11	477
No colon-space in header
	csiph-out.feeder.erje.net	494
	usenet.blueworldhosting.com	6
	TOTAL: 2	500
TOTAL: 3		1189

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	27235	1348	22571	3	0	4%	23:59:44
2	xanadu-bbs	26090	1202	22866	4	91	4%	23:59:51
3	fu-berlin.de	22595	934	21661	0	0	4%	23:59:26
4	news.bbs.geek.nz	44084	520	22574	15	0	1%	23:59:50
5	aioe	22452	267	19579	20	0	1%	23:59:38
6	usenet.pasdenom.info	31211	201	22394	1579	9	0%	23:59:51
7	news.redatomik.org	27849	117	22363	8	0	0%	23:59:45
8	au2pb	23378	91	22882	10	0	0%	23:59:48
9	xmission	28999	51	22668	2	0	0%	23:59:41
10	erje	22072	31	19202	493	0	0%	23:59:47
11	news.mixmin.net	23481	24	19656	0	0	0%	23:59:47
12	neva.ru	25561	18	20900	150	0	0%	23:59:51
13	eternal-september	24198	14	19625	0	0	0%	23:59:51
14	weretis.net	21338	13	20992	7	0	0%	23:59:50
15	news.fcku.it	2244	10	2009	0	0	0%	22:31:34
16	news.swapon.de	2287	1	2060	1	0	0%	23:56:01
17	bwh	18518	0	17608	0	0	0%	23:59:35
TOTAL: 17		393592	4842	321610	2292	100	1%	406:23:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.2 MB	43.7 KB	9.2 MB	0.1 KB/s	7.0 KB	23:59:44
2	xanadu-bbs	8.8 MB	8.7 KB	8.8 MB	0.1 KB/s	7.5 KB	23:59:51
3	fu-berlin.de	8.0 MB	0.0 KB	8.0 MB	0.1 KB/s	8.8 KB	23:59:26
4	news.bbs.geek.nz	1.3 MB	55.7 KB	1.3 MB	0.0 KB/s	2.6 KB	23:59:50
5	usenet.pasdenom.info	853.2 KB	9.2 MB	10.1 MB	0.1 KB/s	5.8 KB	23:59:51
6	aioe	753.8 KB	81.9 KB	835.7 KB	0.0 KB/s	2.9 KB	23:59:38
7	news.redatomik.org	387.6 KB	30.2 KB	417.8 KB	0.0 KB/s	3.3 KB	23:59:45
8	au2pb	369.6 KB	70.5 KB	440.2 KB	0.0 KB/s	4.4 KB	23:59:48
9	xmission	169.7 KB	3.7 KB	173.4 KB	0.0 KB/s	3.3 KB	23:59:41
10	news.mixmin.net	82.8 KB	0.0 KB	82.8 KB	0.0 KB/s	3.5 KB	23:59:47
11	erje	65.8 KB	1.7 MB	1.8 MB	0.0 KB/s	3.4 KB	23:59:47
12	neva.ru	52.9 KB	566.7 KB	619.5 KB	0.0 KB/s	3.7 KB	23:59:51
13	weretis.net	38.4 KB	87.2 KB	125.6 KB	0.0 KB/s	6.3 KB	23:59:50
14	eternal-september	33.4 KB	0.0 KB	33.4 KB	0.0 KB/s	2.4 KB	23:59:51
15	news.fcku.it	25.1 KB	0.0 KB	25.1 KB	0.0 KB/s	2.5 KB	22:31:34
16	news.swapon.de	1.6 KB	2.1 KB	3.7 KB	0.0 KB/s	1.8 KB	23:56:01
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:35
TOTAL: 17		30.1 MB	11.9 MB	41.9 MB	0.0 KB/s	6.0 KB	406:23:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	65	168.7 KB	60	04:25:16
2	71.94.21.184	11	10	16.6 KB	10	00:01:12
3	pool-71-98-160-84.tampfl.dsl-w.verizon.net	5	2	3.7 KB	1	01:38:53
4	challenger.nielsenmedia.com	2	2	3.7 KB	1	01:00:15
TOTAL: 4		306	79	192.7 KB	72	37:12:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	316	75	185.2 KB	70	24:14:49
2	*.tampfl.dsl-w.verizon.net	5	2	3.7 KB	1	01:38:53
3	*.nielsenmedia.com	2	2	3.7 KB	1	01:00:15
4	*.bbox.io	8	0	0.0 KB	0	04:06:49
5	*.chello.nl	13	0	0.0 KB	0	06:09:57
6	*.kortex.jyu.fi	1	0	0.0 KB	0	00:01:31
TOTAL: 6		345	79	192.7 KB	72	37:12:16

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.221	1.559	0.915	0.000	19:48:09
dhcp-089-099-203-254.chello.nl	0.955	0.258	0.000	06:09:57
202.89.176.220	28.576	6.820	0.735	04:25:16
tempe0.bbox.io	0.918	0.179	0.000	04:06:49
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.507	0.126	0.000	01:38:53
challenger.nielsenmedia.com	0.259	0.071	0.000	01:00:15
a00005499.kortex.jyu.fi	0.123	0.015	0.000	00:01:31
71.94.21.184	3.117	2.988	0.000	00:01:12
104.233.77.230	0.052	0.030	0.000	00:00:10
TOTAL: 9	36.066	11.402	0.735	37:12:16

Curious NNRP server explorers [Top 20]:

System	Conn
162.212.88.221	16
dhcp-089-099-203-254.chello.nl	13
tempe0.bbox.io	8
a00005499.kortex.jyu.fi	1
104.233.77.230	1
TOTAL: 5	39

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	12	0
tempe0.bbox.io	8	0
71.94.21.184	4	4
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
challenger.nielsenmedia.com	2	0
TOTAL: 5	28	4

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	69	87.3%
2	alt	10	12.7%
TOTAL: 2		79	100.0%

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

Newsgroup		Count
1	comp.arch	31
2	comp.sys.ibm.pc.hardware.storage	9
3	comp.sys.ibm.pc.games.action	7
4	comp.arch.embedded	4
5	comp.lang.awk	4
6	comp.ai.neural-nets	4
7	comp.os.vms	3
8	comp.archives.ms-windows.discuss	3
9	comp.os.qnx	2
10	alt.binaries.mp3	2
11	alt.free.nntp	2
12	alt.test	2
13	alt.binaries.test	2
14	alt.binaries.teevee	2
15	comp.sys.ibm.pc.games.rpg	1
16	comp.os.msdos.4dos	1
TOTAL: 16		79

Daily Usenet report for csiph.com

Jan 30 04:15:00 -- Jan 31 04:15:00