Daily Usenet report for csiph.com: Oct 15 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	31988	60.4%	6.5 MB	71.6%
innd	16492	31.1%	1.9 MB	21.1%
innfeed	3258	6.2%	541.6 KB	5.8%
nnrpd	1177	2.2%	131.3 KB	1.4%
nocem	43	0.1%	4.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	52959	100.0%	9.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	370060	58.0%
Negative hits	236252	37.0%
Do not exist	31194	4.9%
Cache misses	444	0.1%
TOTAL: 4	637950	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.679	0.0%	173	0.000	3.925	31.000
article cleanup	00:00:00.222	0.0%	30978	0.000	0.007	0.037
article logging	00:00:00.445	0.0%	31457	0.000	0.014	0.134
article parse	00:00:01.052	0.0%	83697	0.000	0.013	0.054
article write	00:06:50.309	0.5%	20263	0.246	20.249	97.474
artlog/artcncl	00:00:00.001	0.0%	159	0.000	0.006	0.250
artlog/artparse	00:00:00.008	0.0%	323	0.000	0.025	0.500
data move	00:00:00.165	0.0%	613611	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.015	0.0%	173	0.000	0.087	6.000
hishave/artcncl	00:00:00.015	0.0%	173	0.000	0.087	11.000
hishave/artparse	00:00:00.000	0.0%	323	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:07.081	0.0%	640005	0.001	0.011	0.157
history sync	00:00:01.100	0.0%	357	0.000	3.081	30.333
history write	00:00:20.401	0.0%	30977	0.101	0.659	11.605
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.154	0.0%	323	0.000	0.477	3.000
idle	23:49:30.845	99.2%	609521	71.346	140.718	419.227
nntp read	00:00:02.137	0.0%	626585	0.001	0.003	0.007
overview write	00:02:10.400	0.2%	20263	0.151	6.435	251.592
perl filter	00:00:58.847	0.1%	30976	1.308	1.900	3.956
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.379	0.0%	40561	0.000	0.009	0.033
TOTAL: 24:00:50.599	24:00:04.255	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.074	0.0%	20238	0.000	0.004	0.021
article prepare	00:00:00.008	0.0%	29408	0.000	0.000	0.006
article read	00:00:04.380	0.0%	4835	0.000	0.906	45.000
backlog stats	00:00:00.192	0.0%	373498	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.635	0.0%	287670	0.002	0.006	0.011
data write	00:00:02.034	0.0%	271016	0.002	0.008	0.017
idle	23:53:39.353	100.0%	373498	107.452	230.307	729.969
status file	00:00:00.421	0.0%	291	0.000	1.447	61.000
TOTAL: 23:53:55.139	23:53:48.097	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.848	0.0%	8989	0.000	0.206	2.000
idle	61:41:13.421	99.8%	9495	46.000	23388.459	900123.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.120	0.0%	19060	0.000	0.006	0.250
readart	00:00:00.108	0.0%	7	0.000	15.429	49.000
TOTAL: 61:48:56.231	61:41:15.497	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	102829	5060	90052	7717	4%	95:12:06
2	csiph-out.news.weretis.net	3	41108	4497	36357	254	10%	45:10:15
3	feeder.eternal-september.org	1	27050	3036	23680	334	11%	23:59:47
4	news-out.mixmin.net	4	25535	1893	22710	932	7%	24:11:41
5	news-out.aioe.org	3	20258	1401	18806	51	6%	24:08:37
6	newsfeed.neva.ru	145	40584	1106	38294	1184	2%	71:42:21
7	newsfeed.fu-berlin.de	22	24744	1078	23383	283	4%	29:12:31
8	news.redatomik.org	1	18912	976	17912	24	5%	23:34:57
9	newsfeeds.xmission.com	0	16526	600	15916	10	3%	18:50:36
10	news.fcku.it	2	8072	381	7569	122	4%	23:59:44
11	xanadu-bbs.net	22	663	156	499	8	23%	26:29:03
12	news.swapon.de	5	1723	40	1682	1	2%	23:14:55
13	news.freedyn.net	3	24522	37	24455	30	0%	24:01:29
14	nntp.pasdenom.info	2	28730	15	28684	31	0%	24:09:00
15	news.bbs.geek.nz	1	18780	7	18773	0	0%	23:59:49
16	localhost	33	0	0	0	0	0%	00:00:01
TOTAL: 16		1399	400036	20283	368772	10981	5%	501:56:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	16.0 MB	0.0 KB	15.4 MB	31.3 MB	50%	2.5 KB
2	csiph-out.news.weretis.net	12.5 MB	0.0 KB	9.5 MB	22.0 MB	56%	4.8 KB
3	feeder.eternal-september.org	7.9 MB	2.3 KB	4.4 MB	12.3 MB	64%	3.7 KB
4	news-out.mixmin.net	6.1 MB	0.0 KB	2.0 MB	8.0 MB	75%	2.9 KB
5	news-out.aioe.org	3.5 MB	0.0 KB	74.8 KB	3.6 MB	97%	2.5 KB
6	newsfeed.neva.ru	3.2 MB	0.0 KB	2.6 MB	5.8 MB	55%	2.6 KB
7	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	607.1 KB	2.9 MB	79%	2.2 KB
8	news.redatomik.org	2.3 MB	0.0 KB	853.9 KB	3.1 MB	73%	3.2 KB
9	newsfeeds.xmission.com	1.8 MB	0.0 KB	779.8 KB	2.6 MB	70%	4.3 KB
10	news.fcku.it	863.6 KB	2.2 KB	4.2 MB	5.0 MB	16%	10.2 KB
11	xanadu-bbs.net	392.8 KB	0.0 KB	16.7 KB	409.5 KB	95%	2.5 KB
12	news.freedyn.net	178.6 KB	0.0 KB	137.9 KB	316.5 KB	56%	4.7 KB
13	news.swapon.de	98.2 KB	0.0 KB	80.3 KB	178.5 KB	54%	4.4 KB
14	nntp.pasdenom.info	31.1 KB	0.0 KB	284.3 KB	315.3 KB	9%	6.9 KB
15	news.bbs.geek.nz	12.1 KB	0.0 KB	0.0 KB	12.1 KB	100%	1.7 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		57.0 MB	4.6 KB	40.8 MB	97.9 MB	58%	3.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 15 04:15:00 - 04:59:59	289	1.4%	0.11	834.3 KB	1.4%	0.31
Oct 15 05:00:00 - 05:59:59	512	2.5%	0.14	1.3 MB	2.4%	0.38
Oct 15 06:00:00 - 06:59:59	587	2.9%	0.16	1.8 MB	3.1%	0.51
Oct 15 07:00:00 - 07:59:59	1009	5.0%	0.28	3.1 MB	5.5%	0.90
Oct 15 08:00:00 - 08:59:59	875	4.3%	0.24	2.6 MB	4.6%	0.74
Oct 15 09:00:00 - 09:59:59	954	4.7%	0.27	2.5 MB	4.3%	0.70
Oct 15 10:00:00 - 10:59:59	1081	5.3%	0.30	3.1 MB	5.4%	0.88
Oct 15 11:00:00 - 11:59:59	963	4.7%	0.27	2.8 MB	4.8%	0.79
Oct 15 12:00:00 - 12:59:59	1068	5.3%	0.30	3.1 MB	5.5%	0.89
Oct 15 13:00:00 - 13:59:59	1079	5.3%	0.30	2.7 MB	4.7%	0.76
Oct 15 14:00:00 - 14:59:59	1271	6.3%	0.35	3.7 MB	6.5%	1.06
Oct 15 15:00:00 - 15:59:59	1116	5.5%	0.31	3.3 MB	5.8%	0.94
Oct 15 16:00:00 - 16:59:59	1050	5.2%	0.29	2.8 MB	4.8%	0.79
Oct 15 17:00:00 - 17:59:59	1173	5.8%	0.33	2.8 MB	4.9%	0.80
Oct 15 18:00:00 - 18:59:59	1016	5.0%	0.28	2.8 MB	4.8%	0.79
Oct 15 19:00:00 - 19:59:59	1040	5.1%	0.29	3.0 MB	5.3%	0.86
Oct 15 20:00:00 - 20:59:59	1028	5.1%	0.29	2.7 MB	4.8%	0.78
Oct 15 21:00:00 - 21:59:59	878	4.3%	0.24	2.2 MB	3.9%	0.63
Oct 15 22:00:00 - 22:59:59	818	4.0%	0.23	2.5 MB	4.3%	0.70
Oct 15 23:00:00 - 23:59:59	520	2.6%	0.14	1.6 MB	2.8%	0.45
Oct 16 00:00:00 - 00:59:59	496	2.4%	0.14	1.5 MB	2.6%	0.42
Oct 16 01:00:00 - 01:59:59	534	2.6%	0.15	1.7 MB	3.1%	0.50
Oct 16 02:00:00 - 02:59:59	425	2.1%	0.12	1.3 MB	2.3%	0.37
Oct 16 03:00:00 - 03:59:59	359	1.8%	0.10	1.0 MB	1.8%	0.29
Oct 16 04:00:00 - 04:15:00	135	0.7%	0.15	354.4 KB	0.6%	0.39
TOTAL: 24:00:00	20276	100.0%	0.23	57.1 MB	100.0%	0.68

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	7759	234	0	7525
2	newsfeed.neva.ru	1185	254	0	931
3	news-out.mixmin.net	953	4	0	949
4	newsfeed.fu-berlin.de	596	38	0	558
5	csiph-out.news.weretis.net	383	97	1	285
6	feeder.eternal-september.org	352	54	0	298
7	news.fcku.it	135	0	0	135
8	news-out.aioe.org	55	43	0	12
9	news.redatomik.org	39	0	0	39
10	nntp.pasdenom.info	35	1	0	34
11	news.freedyn.net	30	0	0	30
12	newsfeeds.xmission.com	15	0	0	15
13	xanadu-bbs.net	10	0	0	10
14	news.swapon.de	3	0	0	3
TOTAL: 14		11550	725	1	10824

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	126
ut.test	114
it-alt.sport.calcio.juventus	75
lada.auto	59
alt.survival	38
spline.eisfair	25
gmane.linux.alsa.devel	17
alt.windows7.general	17
lada.commerce	15
mozilla.support.seamonkey	11
gmane.comp.gis.openstreetmap	11
cakewalk.audio	11
alt.politics	11
lada.photo	10
mozilla.support.thunderbird	9
alt.philosophy	8
mpc.lists.freebsd.gnome	7
spline.eisfair.dev	7
mozilla.dev.security.policy	7
lada.movies	7
TOTAL: 105	725

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5966
EMP (phn path)	2991
EMP (phl)	779
EMP (phn nph)	125
Too many newsgroups	68
User-issued cancel	27
Binary Payload	14
HTML Multipart	9
Scoring filter (8)	8
Scoring filter (12)	5
Too many newsgroups (low_xpost)	4
Scoring filter (9)	4
Scoring filter (11)	3
Binary Image: misplaced jpg	3
Scoring filter (10)	3
Scoring filter (13)	2
Scoring filter (14)	2
EMP (fsl)	2
Scoring filter (15)	1
Scoring filter (21)	1
TOTAL: 22	10019

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	37	0	39	39
TOTAL: 1	37	0	39	39

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.news.weretis.net	131
	news.fcku.it	77
	feeder.eternal-september.org	75
	newsfeeds.xmission.com	14
	news-out.mixmin.net	10
	nntp.pasdenom.info	8
	news.redatomik.org	7
	news.swapon.de	1
	TOTAL: 8	323
Including strange strings
	newsfeed.fu-berlin.de	351
	csiph-out.feeder.erje.net	45
	news-out.mixmin.net	24
	feeder.eternal-september.org	18
	csiph-out.news.weretis.net	17
	news.redatomik.org	15
	news-out.aioe.org	4
	newsfeed.neva.ru	4
	news.swapon.de	2
	newsfeeds.xmission.com	1
	TOTAL: 10	481
TOTAL: 2		804

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	34790	3783	15804	71	0	10%	24:09:27
2	news.freedyn.net	20825	960	19334	10	0	4%	24:09:27
3	xanadu-bbs	21859	700	19418	2	19	3%	23:59:56
4	fu-berlin.de	18962	578	18384	0	0	3%	24:00:14
5	usenet.pasdenom.info	22074	158	19117	985	0	0%	23:59:32
6	xmission	22896	36	18781	6	0	0%	23:59:55
7	news.redatomik.org	19144	30	18621	7	0	0%	24:00:16
8	aioe	17835	10	17226	1	0	0%	24:01:56
9	news.mixmin.net	17968	6	17479	6	0	0%	24:01:51
10	weretis.net	14314	3	13515	2	0	0%	24:09:47
11	bwh	0	0	0	0	19624	0%	24:07:19
12	au2pb	0	0	0	0	19655	0%	24:07:20
13	eternal-september	17388	0	16327	0	0	0%	23:59:31
14	erje	15333	0	15182	17	0	0%	24:05:13
15	neva.ru	18832	0	18130	8	0	0%	23:59:56
16	news.fcku.it	1689	0	1649	0	0	0%	23:04:30
17	news.swapon.de	1856	0	1829	0	0	0%	23:47:24
TOTAL: 17		265765	6264	230796	1115	39298	2%	407:43:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	9.2 MB	196.9 KB	9.4 MB	0.1 KB/s	2.5 KB	24:09:27
2	news.freedyn.net	6.2 MB	40.1 KB	6.2 MB	0.1 KB/s	6.5 KB	24:09:27
3	xanadu-bbs	5.5 MB	4.8 KB	5.5 MB	0.1 KB/s	8.1 KB	23:59:56
4	fu-berlin.de	5.3 MB	0.0 KB	5.3 MB	0.1 KB/s	9.3 KB	24:00:14
5	usenet.pasdenom.info	492.0 KB	6.0 MB	6.4 MB	0.1 KB/s	5.8 KB	23:59:32
6	xmission	93.0 KB	11.9 KB	104.9 KB	0.0 KB/s	2.5 KB	23:59:55
7	news.redatomik.org	59.9 KB	153.0 KB	213.0 KB	0.0 KB/s	5.8 KB	24:00:16
8	aioe	19.1 KB	1.1 KB	20.1 KB	0.0 KB/s	1.8 KB	24:01:56
9	news.mixmin.net	10.1 KB	74.3 KB	84.4 KB	0.0 KB/s	7.0 KB	24:01:51
10	weretis.net	8.8 KB	21.7 KB	30.5 KB	0.0 KB/s	6.1 KB	24:09:47
11	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:07:19
12	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:07:20
13	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:31
14	erje	0.0 KB	31.2 KB	31.2 KB	0.0 KB/s	1.8 KB	24:05:13
15	neva.ru	0.0 KB	42.8 KB	42.8 KB	0.0 KB/s	5.3 KB	23:59:56
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:04:30
17	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:47:24
TOTAL: 17		26.8 MB	6.5 MB	33.3 MB	0.0 KB/s	4.6 KB	407:43:34

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	challenger.nielsenmedia.com	2	2	5.7 KB	1	00:30:12
2	c-73-195-247-24.hsd1.nj.comcast.net	5	2	5.7 KB	1	01:43:51
3	1.127.48.188	2	2	3.2 KB	1	00:01:15
4	tempe0.bbox.io	31	1	8.3 KB	1	06:32:21
TOTAL: 4		40	7	22.8 KB	4	61:48:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	88	2	3.2 KB	1	39:28:19
2	*.hsd1.nj.comcast.net	5	2	5.7 KB	1	01:43:51
3	*.nielsenmedia.com	2	2	5.7 KB	1	00:30:12
4	*.bbox.io	31	1	8.3 KB	1	06:32:21
5	*.chello.nl	73	0	0.0 KB	0	12:02:18
6	*.208-100-26.static.steadfastdns.net	2	0	0.0 KB	0	00:00:01
7	*.133.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	01:01:45
8	*.dhcp.mdsn.wi.charter.com	1	0	0.0 KB	0	00:30:03
TOTAL: 8		203	7	22.8 KB	4	61:48:54

NNRP auth users [Top 20]:

User	Conn
moussa	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	6.444	1.500	0.000	39:03:41
dhcp-089-099-203-254.chello.nl	5.921	1.586	0.000	12:02:18
tempe0.bbox.io	2.650	0.604	0.000	06:32:21
c-73-195-247-24.hsd1.nj.comcast.net	0.481	0.088	0.000	01:43:51
h132.133.22.98.dynamic.ip.windstream.net	0.062	0.076	0.000	01:01:45
challenger.nielsenmedia.com	0.180	0.023	0.000	00:30:12
68-185-181-64.dhcp.mdsn.wi.charter.com	0.088	0.032	0.000	00:30:03
5.51.176.30	0.786	0.503	0.000	00:23:22
1.127.48.188	0.181	0.032	0.005	00:01:15
ip232.208-100-26.static.steadfastdns.net	0.186	0.053	0.000	00:00:01
TOTAL: 10	16.979	4.497	0.005	61:48:54

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	80
dhcp-089-099-203-254.chello.nl	73
5.51.176.30	6
ip232.208-100-26.static.steadfastdns.net	2
68-185-181-64.dhcp.mdsn.wi.charter.com	1
h132.133.22.98.dynamic.ip.windstream.net	1
TOTAL: 6	163

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	76	0
tempe0.bbox.io	8	0
c-73-195-247-24.hsd1.nj.comcast.net	3	0
h132.133.22.98.dynamic.ip.windstream.net	1	0
challenger.nielsenmedia.com	1	0
68-185-181-64.dhcp.mdsn.wi.charter.com	1	0
TOTAL: 6	90	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	6	85.7%
2	muc	1	14.3%
TOTAL: 2		7	100.0%

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

Newsgroup		Count
1	comp.lang.awk	4
2	comp.sys.ibm.ps2.hardware	2
3	muc.lists.freebsd.stable	1
TOTAL: 3		7

Daily Usenet report for csiph.com

Oct 15 04:15:00 -- Oct 16 04:15:00