Daily Usenet report for csiph.com: Aug 22 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	32070	52.0%	6.8 MB	66.7%
innd	13833	22.4%	1.6 MB	16.2%
nnrpd	12417	20.1%	1.2 MB	11.8%
innfeed	3201	5.2%	542.9 KB	5.2%
nocem	134	0.2%	14.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	61656	100.0%	10.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	335671	54.5%
Negative hits	248680	40.4%
Do not exist	30756	5.0%
Cache misses	427	0.1%
TOTAL: 4	615534	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.858	0.0%	1383	0.000	3.513	252.667
article cleanup	00:00:00.224	0.0%	30458	0.000	0.007	0.040
article logging	00:00:04.963	0.0%	31003	0.000	0.160	1.490
article parse	00:00:04.282	0.0%	88239	0.005	0.049	0.691
article write	00:03:34.704	0.2%	21487	0.200	9.992	60.304
artlog/artcncl	00:00:00.103	0.0%	681	0.000	0.151	5.750
artlog/artparse	00:00:00.051	0.0%	413	0.000	0.123	2.833
data move	00:00:00.399	0.0%	587325	0.000	0.001	0.069
hisgrep/artcncl	00:00:01.259	0.0%	923	0.000	1.364	110.167
hishave/artcncl	00:00:00.489	0.0%	1383	0.000	0.354	35.500
hishave/artparse	00:00:00.000	0.0%	399	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:46.512	0.2%	618139	0.003	0.269	1.676
history sync	00:00:02.573	0.0%	345	0.000	7.458	112.500
history write	00:03:30.798	0.2%	30005	1.364	7.025	47.584
hiswrite/artcncl	00:00:01.365	0.0%	460	0.500	2.967	12.000
hiswrite/artparse	00:00:03.069	0.0%	395	0.000	7.770	167.000
idle	23:41:01.777	98.6%	569890	40.578	149.611	481.189
nntp read	00:00:03.053	0.0%	598103	0.002	0.005	0.039
overview write	00:06:58.412	0.5%	21487	1.400	19.473	339.637
perl filter	00:01:07.665	0.1%	29990	1.019	2.256	14.244
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.379	0.0%	43102	0.000	0.009	0.030
TOTAL: 24:00:50.720	23:59:26.935	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.071	0.0%	21473	0.000	0.003	0.024
article prepare	00:00:00.001	0.0%	18788	0.000	0.000	0.014
article read	00:00:01.403	0.0%	2651	0.000	0.529	21.500
backlog stats	00:00:00.192	0.0%	368237	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.998	0.0%	296668	0.001	0.007	0.011
data write	00:00:01.732	0.0%	282611	0.000	0.006	0.012
idle	23:53:36.537	100.0%	368237	73.396	233.590	840.015
status file	00:00:00.468	0.0%	288	0.000	1.625	32.000
TOTAL: 23:53:51.010	23:53:42.402	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.399	0.0%	183	0.000	2.180	24.000
idle	107:50:00.354	99.9%	17326	61.000	22405.654	1182364.208
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.208	0.0%	35085	0.000	0.006	0.167
readart	00:01:47.143	0.0%	5182	0.000	20.676	161.000
TOTAL: 107:53:43.865	107:51:48.104	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	42424	5443	36262	719	12%	45:42:19
2	csiph-out.feeder.erje.net	1152	91744	4284	83760	3700	4%	94:58:41
3	feeder.eternal-september.org	2	25065	2712	21274	1079	10%	24:13:32
4	news-out.mixmin.net	2	22137	2452	19013	672	11%	24:08:55
5	news.redatomik.org	1	19624	1457	18151	16	7%	23:09:58
6	newsfeeds.xmission.com	3	19343	1327	18008	8	6%	20:49:51
7	newsfeed.neva.ru	151	27440	1182	23390	2868	4%	71:55:18
8	newsfeed.fu-berlin.de	51	22698	1121	21316	261	4%	35:00:38
9	news-out.aioe.org	4	21154	1070	20057	27	5%	24:09:39
10	news.fcku.it	2	8461	123	8327	11	1%	23:59:44
11	news.freedyn.net	2	23630	93	23497	40	0%	23:59:43
12	news.swapon.de	4	2038	56	1982	0	2%	26:38:07
13	nntp.pasdenom.info	3	20284	24	20256	4	0%	24:08:16
14	localhost	45	12	12	0	0	100%	00:00:08
15	news.bbs.geek.nz	1	20876	5	20871	0	0%	23:59:46
16	xanadu-bbs.net	13	785	4	781	0	0%	25:51:17
TOTAL: 16		1439	367715	21365	336945	9405	5%	512:45:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	16.2 MB	1.9 KB	10.6 MB	26.8 MB	60%	4.5 KB
2	csiph-out.feeder.erje.net	16.1 MB	6.2 KB	20.2 MB	36.4 MB	44%	4.7 KB
3	news-out.mixmin.net	8.4 MB	1.4 KB	2.0 MB	10.4 MB	80%	3.4 KB
4	feeder.eternal-september.org	7.9 MB	6.4 KB	8.5 MB	16.5 MB	48%	4.4 KB
5	newsfeeds.xmission.com	3.6 MB	0.0 KB	304.3 KB	3.9 MB	92%	3.0 KB
6	news-out.aioe.org	3.6 MB	4.9 KB	46.2 KB	3.6 MB	98%	3.4 KB
7	news.redatomik.org	3.3 MB	24.7 KB	179.6 KB	3.5 MB	94%	2.5 KB
8	newsfeed.neva.ru	3.0 MB	10.6 MB	28.6 MB	42.2 MB	7%	10.7 KB
9	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	593.6 KB	3.1 MB	81%	2.3 KB
10	news.freedyn.net	410.2 KB	0.0 KB	455.8 KB	866.0 KB	47%	6.5 KB
11	news.fcku.it	207.4 KB	0.0 KB	343.3 KB	550.7 KB	37%	4.1 KB
12	news.swapon.de	178.8 KB	0.0 KB	0.0 KB	178.8 KB	100%	3.2 KB
13	nntp.pasdenom.info	65.6 KB	0.0 KB	2.9 KB	68.4 KB	95%	2.4 KB
14	localhost	25.2 KB	0.0 KB	0.0 KB	25.2 KB	100%	2.1 KB
15	news.bbs.geek.nz	10.1 KB	0.0 KB	0.0 KB	10.1 KB	100%	2.0 KB
16	xanadu-bbs.net	7.6 KB	0.0 KB	0.0 KB	7.6 KB	100%	1.9 KB
TOTAL: 16		65.5 MB	10.6 MB	71.9 MB	148.0 MB	44%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 22 04:15:00 - 04:59:59	364	1.7%	0.13	1.8 MB	2.8%	0.69
Aug 22 05:00:00 - 05:59:59	512	2.4%	0.14	2.0 MB	3.1%	0.58
Aug 22 06:00:00 - 06:59:59	572	2.7%	0.16	2.0 MB	3.1%	0.58
Aug 22 07:00:00 - 07:59:59	802	3.7%	0.22	2.4 MB	3.7%	0.69
Aug 22 08:00:00 - 08:59:59	1041	4.8%	0.29	3.4 MB	5.1%	0.96
Aug 22 09:00:00 - 09:59:59	916	4.3%	0.25	2.2 MB	3.4%	0.64
Aug 22 10:00:00 - 10:59:59	957	4.5%	0.27	2.8 MB	4.3%	0.80
Aug 22 11:00:00 - 11:59:59	966	4.5%	0.27	2.6 MB	4.0%	0.75
Aug 22 12:00:00 - 12:59:59	908	4.2%	0.25	2.8 MB	4.2%	0.79
Aug 22 13:00:00 - 13:59:59	1126	5.2%	0.31	3.6 MB	5.5%	1.03
Aug 22 14:00:00 - 14:59:59	1093	5.1%	0.30	3.4 MB	5.2%	0.97
Aug 22 15:00:00 - 15:59:59	1203	5.6%	0.33	3.3 MB	5.0%	0.94
Aug 22 16:00:00 - 16:59:59	1667	7.8%	0.46	4.6 MB	7.0%	1.31
Aug 22 17:00:00 - 17:59:59	1222	5.7%	0.34	3.5 MB	5.3%	0.99
Aug 22 18:00:00 - 18:59:59	1136	5.3%	0.32	3.9 MB	5.9%	1.11
Aug 22 19:00:00 - 19:59:59	1373	6.4%	0.38	4.0 MB	6.0%	1.13
Aug 22 20:00:00 - 20:59:59	1130	5.3%	0.31	3.0 MB	4.6%	0.87
Aug 22 21:00:00 - 21:59:59	922	4.3%	0.26	2.6 MB	4.0%	0.74
Aug 22 22:00:00 - 22:59:59	846	3.9%	0.23	2.8 MB	4.3%	0.81
Aug 22 23:00:00 - 23:59:59	678	3.2%	0.19	2.0 MB	3.1%	0.57
Aug 23 00:00:00 - 00:59:59	554	2.6%	0.15	1.8 MB	2.7%	0.51
Aug 23 01:00:00 - 01:59:59	493	2.3%	0.14	1.7 MB	2.5%	0.47
Aug 23 02:00:00 - 02:59:59	434	2.0%	0.12	1.6 MB	2.4%	0.46
Aug 23 03:00:00 - 03:59:59	438	2.0%	0.12	1.4 MB	2.2%	0.40
Aug 23 04:00:00 - 04:15:00	123	0.6%	0.14	369.2 KB	0.5%	0.41
TOTAL: 24:00:00	21476	100.0%	0.25	65.8 MB	100.0%	0.78

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	3745	744	3	2998
2	newsfeed.neva.ru	2852	249	442	2161
3	feeder.eternal-september.org	1081	58	4	1019
4	csiph-out.news.weretis.net	762	255	1	506
5	news-out.mixmin.net	682	2	1	679
6	newsfeed.fu-berlin.de	658	33	0	625
7	news.freedyn.net	40	8	0	32
8	news-out.aioe.org	28	17	3	8
9	news.redatomik.org	25	0	11	14
10	newsfeeds.xmission.com	15	2	0	13
11	news.fcku.it	14	2	1	11
12	news.swapon.de	8	0	0	8
13	nntp.pasdenom.info	4	0	1	3
TOTAL: 13		9914	1370	467	8077

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.binaries.homeporn	361
ut.test	108
gmane.linux.alsa.devel	99
it.annunci.commerciali	60
it.annunci.immobiliari	58
lada.talk	56
it.annunci.varie	55
alt.survival	41
gmane.comp.gis.openstreetmap	36
it-alt.sport.calcio.juventus	36
mozilla.governance	33
gmane.linux.kernel	29
alt.computer.workshop	27
lada.photo	27
lada.auto	23
spline.eisfair	15
mozilla.support.seamonkey	14
lada.commerce	12
lada.kitchen	11
mpc.lists.nanog	10
TOTAL: 119	1370

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5627
EMP (phn path)	600
Binary Image: misplaced jpg	527
EMP (phn nph)	154
Too many newsgroups	56
Binary Payload	30
HTML Multipart	23
Too many newsgroups (low_xpost)	21
Scoring filter (9)	19
User-issued cancel	16
Scoring filter (10)	8
Scoring filter (8)	8
Scoring filter (14)	5
Scoring filter (11)	5
Scoring filter (13)	4
Scoring filter (16)	3
Cancel for rejected article	3
Scoring filter (12)	3
EMP (phl)	2
Scoring filter (20)	1
TOTAL: 21	7116

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	127	0	708	708
TOTAL: 1	127	1	708	708

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.feeder.erje.net	177
	csiph-out.news.weretis.net	152
	feeder.eternal-september.org	38
	news-out.mixmin.net	14
	newsfeeds.xmission.com	6
	news.freedyn.net	4
	news.fcku.it	3
	newsfeed.fu-berlin.de	3
	news.redatomik.org	2
	TOTAL: 9	399
Including strange strings
	newsfeed.fu-berlin.de	417
	csiph-out.feeder.erje.net	46
	news-out.mixmin.net	28
	csiph-out.news.weretis.net	26
	news.redatomik.org	8
	news.swapon.de	8
	newsfeeds.xmission.com	7
	feeder.eternal-september.org	3
	news.fcku.it	2
	newsfeed.neva.ru	1
	TOTAL: 11	547
No colon-space in header
	csiph-out.feeder.erje.net	14
	TOTAL: 1	14
TOTAL: 3		960

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	38132	974	19842	84	0	2%	23:53:01
2	news.freedyn.net	22385	708	20593	6	0	3%	23:50:05
3	xanadu-bbs	23828	683	20772	17	35	2%	23:59:55
4	fu-berlin.de	20161	485	19675	0	0	2%	23:55:26
5	usenet.pasdenom.info	21777	120	19964	1369	9	0%	23:59:55
6	aioe	18576	47	17724	2	0	0%	23:53:43
7	erje	17504	24	16915	5	0	0%	23:50:05
8	neva.ru	19694	16	19445	76	0	0%	23:59:48
9	news.redatomik.org	20017	15	19360	0	0	0%	23:50:05
10	weretis.net	15390	14	14884	1	0	0%	23:50:05
11	eternal-september	18529	13	18041	2	0	0%	23:59:51
12	xmission	20967	13	19490	0	0	0%	23:59:55
13	news.mixmin.net	18441	12	18151	0	0	0%	23:50:05
14	news.swapon.de	1950	11	1922	0	0	0%	23:52:55
15	bwh	0	0	0	0	20881	0%	23:50:09
16	news.fcku.it	2028	0	2000	0	0	0%	23:10:24
17	au2pb	0	0	0	0	20890	0%	23:50:09
TOTAL: 17		279379	3135	248778	1562	41815	1%	405:35:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.3 MB	21.3 KB	6.3 MB	0.1 KB/s	9.0 KB	23:50:05
2	xanadu-bbs	6.1 MB	72.2 KB	6.2 MB	0.1 KB/s	9.1 KB	23:59:55
3	fu-berlin.de	5.6 MB	0.0 KB	5.6 MB	0.1 KB/s	11.9 KB	23:55:26
4	news.bbs.geek.nz	2.7 MB	276.1 KB	2.9 MB	0.0 KB/s	2.8 KB	23:53:01
5	usenet.pasdenom.info	355.5 KB	7.2 MB	7.6 MB	0.1 KB/s	5.2 KB	23:59:55
6	aioe	96.5 KB	4.1 KB	100.7 KB	0.0 KB/s	2.1 KB	23:53:43
7	erje	47.7 KB	9.7 KB	57.4 KB	0.0 KB/s	2.0 KB	23:50:05
8	weretis.net	32.2 KB	2.1 KB	34.4 KB	0.0 KB/s	2.3 KB	23:50:05
9	news.redatomik.org	28.9 KB	0.0 KB	28.9 KB	0.0 KB/s	1.9 KB	23:50:05
10	eternal-september	27.1 KB	4.2 KB	31.3 KB	0.0 KB/s	2.1 KB	23:59:51
11	xmission	26.1 KB	0.0 KB	26.1 KB	0.0 KB/s	2.0 KB	23:59:55
12	news.mixmin.net	25.2 KB	0.0 KB	25.2 KB	0.0 KB/s	2.1 KB	23:50:05
13	neva.ru	24.9 KB	171.5 KB	196.4 KB	0.0 KB/s	2.1 KB	23:59:48
14	news.swapon.de	24.0 KB	0.0 KB	24.0 KB	0.0 KB/s	2.2 KB	23:52:55
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:10:24
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
TOTAL: 17		21.4 MB	7.8 MB	29.1 MB	0.0 KB/s	6.3 KB	405:35:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	144.138.90.254	48	52	129.9 KB	37	0	0	00:37:02
2	bas2-montrealak-70-49-69-223.dsl.bell.ca	450	50	133.4 KB	33	0	0	00:38:10
3	71-90-73-201.dhcp.ftbg.wi.charter.com	35	12	25.2 KB	12	12	8	08:42:32
4	194-73-239-109.cust.centrio.cz	10	6	16.2 KB	6	0	0	04:28:09
5	a131033.upc-a.chello.nl	86	1	1.7 KB	1	0	0	28:10:30
TOTAL: 5		629	121	306.5 KB	89	12	8	107:53:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	91	52	129.9 KB	37	0	0	63:09:40
2	*.dsl.bell.ca	450	50	133.4 KB	33	0	0	00:38:10
3	*.dhcp.ftbg.wi.charter.com	35	12	25.2 KB	12	12	8	08:42:32
4	*.cust.centrio.cz	10	6	16.2 KB	6	0	0	04:28:09
5	*.upc-a.chello.nl	86	1	1.7 KB	1	0	0	28:10:30
6	*.bbox.io	4	0	0.0 KB	0	0	0	02:00:09
7	*.nielsenmedia.com	3	0	0.0 KB	0	0	0	00:44:24
8	*.208-100-26.static.steadfastdns.net	2	0	0.0 KB	0	0	0	00:00:01
TOTAL: 8		681	121	306.5 KB	89	12	8	107:53:38

NNRP auth users [Top 20]:

User	Conn
moussa	48
ctomczyk	10
ohlandl	8
TOTAL: 3	66

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.160	2.779	0.527	0.000	62:32:35
a131033.upc-a.chello.nl	6.633	1.212	0.000	28:10:30
71-90-73-201.dhcp.ftbg.wi.charter.com	3.111	0.460	0.034	08:42:32
194-73-239-109.cust.centrio.cz	1.115	0.199	0.045	04:28:09
tempe0.bbox.io	0.446	0.060	0.000	02:00:09
challenger.nielsenmedia.com	0.222	0.054	0.000	00:44:24
bas2-montrealak-70-49-69-223.dsl.bell.ca	41.324	6.871	0.000	00:38:10
144.138.90.254	5.439	1.114	0.253	00:37:02
94.161.135.102	0.093	0.008	0.000	00:00:01
ip232.208-100-26.static.steadfastdns.net	0.117	0.008	0.000	00:00:01
TOTAL: 10	61.279	10.513	0.332	107:53:38

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.160	42
tempe0.bbox.io	4
challenger.nielsenmedia.com	3
ip232.208-100-26.static.steadfastdns.net	2
94.161.135.102	1
TOTAL: 5	52

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.160	35	0
71-90-73-201.dhcp.ftbg.wi.charter.com	9	0
194-73-239-109.cust.centrio.cz	8	0
tempe0.bbox.io	4	0
challenger.nielsenmedia.com	1	0
TOTAL: 5	57	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	75	62.0%
2	uk	17	14.0%
3	rec	16	13.2%
4	alt	10	8.3%
5	talk	2	1.7%
6	sci	1	0.8%
TOTAL: 6		121	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	25
2	comp.arch	22
3	uk.legal	7
4	comp.lang.javascript	6
5	comp.os.msdos.misc	5
6	uk.media.radio.archers	5
7	rec.crafts.metalworking	5
8	alt.usage.english	4
9	comp.sys.ibm.pc.hardware.storage	3
10	rec.boats	3
11	uk.d-i-y	3
12	rec.arts.tv.uk.coronation-st	3
13	alt.atheism	2
14	rec.sport.tennis	2
15	talk.politics.misc	2
16	rec.food.cooking	2
17	comp.sys.ibm.pc.games.action	2
18	comp.os.vms	2
19	alt.sports.football.pro.sd-chargers	2
20	comp.lang.python	2
21	comp.sys.ibm.pc.hardware.chips	1
22	rec.arts.sf.written	1
23	uk.net.news.moderation	1
24	comp.os.os2.misc	1
25	comp.lang.c	1
26	alt.russian.z1	1
27	comp.lang.clipper.visual-objects	1
28	comp.os.msdos.programmer	1
29	alt.home.repair	1
30	sci.military.naval	1
31	uk.politics.misc	1
32	comp.windows.x.kde	1
33	comp.unix.aix	1
34	comp.arch.embedded	1
TOTAL: 34		121

Daily Usenet report for csiph.com

Aug 22 04:15:00 -- Aug 23 04:15:00