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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	23462	60.7%	5.1 MB	71.4%
innd	11285	29.2%	1.4 MB	20.1%
innfeed	2912	7.5%	502.5 KB	6.9%
nnrpd	971	2.5%	111.7 KB	1.5%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	38631	100.0%	7.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	314315	53.1%
Negative hits	254457	43.0%
Do not exist	22848	3.9%
Cache misses	371	0.1%
TOTAL: 4	591991	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.511	0.0%	21	1.000	24.333	131.000
article cleanup	00:00:00.128	0.0%	22387	0.000	0.006	0.037
article logging	00:00:01.357	0.0%	22913	0.000	0.059	1.754
article parse	00:00:06.322	0.0%	71139	0.000	0.089	1.340
article write	00:04:26.890	0.3%	18467	0.557	14.452	104.827
artlog/artcncl	00:00:00.000	0.0%	21	0.000	0.000	0.000
artlog/artparse	00:00:00.048	0.0%	536	0.000	0.090	1.636
data move	00:00:00.122	0.0%	563827	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.012	0.0%	20	0.000	0.600	8.000
hishave/artcncl	00:00:00.004	0.0%	21	0.000	0.190	1.000
hishave/artparse	00:00:00.001	0.0%	536	0.000	0.002	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:04:23.245	0.3%	593330	0.001	0.444	1.290
history sync	00:00:02.247	0.0%	359	0.000	6.259	85.500
history write	00:04:07.863	0.3%	22378	0.213	11.076	29.453
hiswrite/artcncl	00:00:00.015	0.0%	1	15.000	15.000	15.000
hiswrite/artparse	00:00:05.650	0.0%	536	0.000	10.541	139.800
idle	23:34:44.951	98.2%	524403	90.477	161.870	530.881
nntp read	00:00:03.038	0.0%	569095	0.001	0.005	0.065
overview write	00:11:28.895	0.8%	18467	5.955	37.304	130.415
perl filter	00:00:41.771	0.0%	22374	1.408	1.867	4.132
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.336	0.0%	36934	0.000	0.009	0.044
TOTAL: 24:00:51.882	24:00:13.406	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.063	0.0%	18636	0.000	0.003	0.031
article prepare	00:00:00.001	0.0%	13908	0.000	0.000	0.010
article read	00:00:04.174	0.0%	1481	0.000	2.818	711.000
backlog stats	00:00:00.190	0.0%	327439	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.654	0.0%	258069	0.001	0.006	0.082
data write	00:00:01.622	0.0%	242303	0.003	0.007	0.016
idle	23:54:46.241	100.0%	327439	140.470	262.908	764.065
status file	00:00:01.436	0.0%	270	0.000	5.319	195.000
TOTAL: 23:55:02.512	23:54:55.381	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	39:12:01.383	100.0%	840	349.667	168001.646	600138.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.012	0.0%	1892	0.000	0.006	0.167
readart	00:00:01.446	0.0%	115	0.000	12.574	237.000
TOTAL: 39:12:46.158	39:12:02.841	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	6	57061	4735	51848	478	8%	73:26:19
2	news-out.mixmin.net	2	19148	2477	15938	733	12%	24:08:44
3	feeder.eternal-september.org	4	20575	2141	18069	365	10%	24:23:53
4	news.redatomik.org	3	17818	2071	15651	96	11%	24:08:45
5	csiph-out.feeder.erje.net	1439	94234	2046	91448	740	2%	118:37:28
6	news-out.aioe.org	4	17968	1709	16211	48	9%	23:54:31
7	newsfeed.neva.ru	145	17725	1104	15049	1572	6%	71:46:43
8	newsfeed.fu-berlin.de	7	17046	1046	15727	273	6%	24:46:38
9	news.freedyn.net	2	19672	435	19059	178	2%	24:00:36
10	news.fcku.it	13	2570	422	2139	9	16%	22:44:03
11	newsfeeds.xmission.com	1	18590	287	18297	6	1%	26:34:25
12	nntp.pasdenom.info	2	16793	79	16686	28	0%	23:59:37
13	news.swapon.de	5	1500	45	1454	1	3%	24:06:58
14	localhost	51	25	25	0	0	100%	00:00:02
15	news.bbs.nz	1	17758	0	17758	0	0%	23:59:50
16	xanadu-bbs.net	25	507	0	506	1	0%	22:20:27
TOTAL: 16		1710	338990	18622	315840	4528	5%	552:58:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	15.5 MB	5.9 KB	10.5 MB	26.1 MB	59%	5.1 KB
2	csiph-out.feeder.erje.net	10.9 MB	3.7 KB	10.3 MB	21.2 MB	51%	7.8 KB
3	news-out.mixmin.net	8.1 MB	0.0 KB	2.1 MB	10.2 MB	79%	3.3 KB
4	news.redatomik.org	6.8 MB	2.7 KB	1.1 MB	7.9 MB	86%	3.8 KB
5	feeder.eternal-september.org	5.8 MB	8.9 KB	11.8 MB	17.6 MB	32%	7.2 KB
6	news-out.aioe.org	4.1 MB	9.5 KB	201.4 KB	4.3 MB	95%	2.5 KB
7	newsfeed.neva.ru	3.5 MB	0.0 KB	6.1 MB	9.6 MB	36%	3.7 KB
8	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	487.0 KB	2.8 MB	82%	2.2 KB
9	news.freedyn.net	1.8 MB	0.0 KB	1.3 MB	3.1 MB	58%	5.1 KB
10	newsfeeds.xmission.com	1.4 MB	0.0 KB	357.5 KB	1.7 MB	79%	6.0 KB
11	news.fcku.it	744.0 KB	0.0 KB	15.3 KB	759.3 KB	97%	1.8 KB
12	nntp.pasdenom.info	187.1 KB	0.0 KB	51.8 KB	238.9 KB	78%	2.2 KB
13	news.swapon.de	104.8 KB	0.0 KB	6.1 KB	110.9 KB	94%	2.4 KB
14	localhost	47.0 KB	0.0 KB	0.0 KB	47.0 KB	100%	1.9 KB
15	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
16	xanadu-bbs.net	0.0 KB	2.7 KB	2.7 KB	5.4 KB	0%	5.4 KB
TOTAL: 16		61.3 MB	33.3 KB	44.3 MB	105.6 MB	58%	4.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 29 04:15:00 - 04:59:59	245	1.3%	0.09	1.1 MB	1.9%	0.43
Jan 29 05:00:00 - 05:59:59	351	1.9%	0.10	2.0 MB	3.2%	0.56
Jan 29 06:00:00 - 06:59:59	434	2.3%	0.12	2.1 MB	3.4%	0.59
Jan 29 07:00:00 - 07:59:59	445	2.4%	0.12	1.7 MB	2.8%	0.48
Jan 29 08:00:00 - 08:59:59	573	3.1%	0.16	2.1 MB	3.4%	0.59
Jan 29 09:00:00 - 09:59:59	751	4.1%	0.21	2.0 MB	3.3%	0.57
Jan 29 10:00:00 - 10:59:59	904	4.9%	0.25	2.6 MB	4.2%	0.73
Jan 29 11:00:00 - 11:59:59	852	4.6%	0.24	2.6 MB	4.3%	0.74
Jan 29 12:00:00 - 12:59:59	889	4.8%	0.25	2.6 MB	4.3%	0.74
Jan 29 13:00:00 - 13:59:59	974	5.3%	0.27	2.8 MB	4.6%	0.80
Jan 29 14:00:00 - 14:59:59	930	5.0%	0.26	2.8 MB	4.7%	0.80
Jan 29 15:00:00 - 15:59:59	1031	5.6%	0.29	3.2 MB	5.3%	0.91
Jan 29 16:00:00 - 16:59:59	1067	5.8%	0.30	4.0 MB	6.6%	1.14
Jan 29 17:00:00 - 17:59:59	1090	5.9%	0.30	3.0 MB	5.0%	0.86
Jan 29 18:00:00 - 18:59:59	1133	6.1%	0.31	3.5 MB	5.7%	0.99
Jan 29 19:00:00 - 19:59:59	1125	6.1%	0.31	3.8 MB	6.2%	1.07
Jan 29 20:00:00 - 20:59:59	1131	6.1%	0.31	3.3 MB	5.4%	0.93
Jan 29 21:00:00 - 21:59:59	1078	5.8%	0.30	3.2 MB	5.3%	0.91
Jan 29 22:00:00 - 22:59:59	844	4.6%	0.23	2.5 MB	4.1%	0.71
Jan 29 23:00:00 - 23:59:59	625	3.4%	0.17	2.3 MB	3.8%	0.65
Jan 30 00:00:00 - 00:59:59	603	3.3%	0.17	2.3 MB	3.7%	0.64
Jan 30 01:00:00 - 01:59:59	447	2.4%	0.12	1.4 MB	2.3%	0.39
Jan 30 02:00:00 - 02:59:59	446	2.4%	0.12	1.6 MB	2.7%	0.46
Jan 30 03:00:00 - 03:59:59	396	2.1%	0.11	1.8 MB	3.0%	0.52
Jan 30 04:00:00 - 04:15:00	107	0.6%	0.12	347.9 KB	0.6%	0.39
TOTAL: 24:00:00	18471	100.0%	0.21	60.5 MB	100.0%	0.72

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	1580	94	0	1486
2	csiph-out.feeder.erje.net	755	70	2	683
3	news-out.mixmin.net	744	1	0	743
4	newsfeed.fu-berlin.de	654	33	0	621
5	csiph-out.news.weretis.net	480	172	1	307
6	feeder.eternal-september.org	366	16	2	348
7	news.freedyn.net	176	4	0	172
8	news.redatomik.org	106	0	1	105
9	news-out.aioe.org	54	31	2	21
10	nntp.pasdenom.info	28	0	0	28
11	news.swapon.de	10	0	0	10
12	news.fcku.it	9	5	0	4
13	newsfeeds.xmission.com	7	0	0	7
14	xanadu-bbs.net	1	0	1	0
TOTAL: 14		4970	426	9	4535

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	67
gmane.linux.alsa.devel	52
lada.auto	23
alt.music.pink-floyd.publius	22
it-alt.sport.calcio.juventus	19
alt.bbs.synchronet	18
alt.windows7.general	17
spline.eisfair	16
spline.fli4l	13
mozilla.support.thunderbird	13
mozilla.dev.platform	13
alt.survival	10
oesterreich.tratsch	8
mozilla.support.firefox	7
alt.eunuchs	7
gmane.linux.kernel	7
alt.aviation	5
alt.slack.goathead	5
gmane.comp.gis.openstreetmap.tagging	4
rocksolid.shared.test	4
TOTAL: 90	426

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2715
EMP (phn path)	291
User-issued cancel	88
Too many newsgroups	72
Binary: misplaced par2	59
EMP (phl)	57
Binary: misplaced nfo	46
EMP (phn nph)	44
HTML Multipart	24
User-issued spam cancel	22
Scoring filter (10)	11
Binary Payload	8
Scoring filter (12)	6
Binary: misplaced rar	5
HTML Image Tags	5
Scoring filter (8)	4
Scoring filter (9)	3
Scoring filter (11)	2
Too many newsgroups (low_xpost)	1
bofh violation - invalid distribution	1
TOTAL: 24	3468

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	feeder.eternal-september.org	169
	csiph-out.feeder.erje.net	159
	csiph-out.news.weretis.net	159
	news.redatomik.org	17
	news-out.mixmin.net	14
	news.freedyn.net	13
	newsfeeds.xmission.com	5
	TOTAL: 7	536
Including strange strings
	newsfeed.fu-berlin.de	446
	csiph-out.news.weretis.net	15
	csiph-out.feeder.erje.net	15
	news-out.mixmin.net	13
	news.redatomik.org	10
	newsfeed.neva.ru	10
	news.swapon.de	9
	news-out.aioe.org	6
	feeder.eternal-september.org	2
	newsfeeds.xmission.com	1
	TOTAL: 10	527
TOTAL: 3		1108

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	18160	595	17421	1	0	3%	23:59:31
2	xanadu-bbs	19636	587	17884	0	0	2%	23:59:55
3	fu-berlin.de	17226	537	16689	0	0	3%	23:59:42
4	news.bbs.nz	33001	144	17700	9	0	0%	23:50:01
5	usenet.pasdenom.info	18770	61	17133	1196	10	0%	23:59:55
6	aioe	15332	34	15047	0	176	0%	23:52:35
7	weretis.net	13103	26	13016	0	0	0%	23:50:01
8	xmission	20040	25	17545	0	0	0%	23:59:53
9	news.swapon.de	1577	25	1541	0	0	1%	23:32:58
10	eternal-september	15816	24	15574	0	0	0%	23:59:55
11	news.mixmin.net	15331	24	15147	0	0	0%	23:59:55
12	news.redatomik.org	16010	23	15803	1	6	0%	23:59:55
13	neva.ru	16653	20	16468	90	0	0%	23:59:55
14	erje	16273	7	16149	48	4	0%	23:59:55
15	news.fcku.it	2052	0	2052	0	0	0%	22:40:40
TOTAL: 15		238980	2132	215169	1345	196	0%	357:44:46

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	11.3 KB	23:59:55
2	news.freedyn.net	6.4 MB	2.5 KB	6.4 MB	0.1 KB/s	11.1 KB	23:59:31
3	fu-berlin.de	6.3 MB	0.0 KB	6.3 MB	0.1 KB/s	12.1 KB	23:59:42
4	news.bbs.nz	365.6 KB	32.1 KB	397.8 KB	0.0 KB/s	2.6 KB	23:50:01
5	usenet.pasdenom.info	114.5 KB	7.5 MB	7.6 MB	0.1 KB/s	6.2 KB	23:59:55
6	aioe	71.0 KB	0.0 KB	71.0 KB	0.0 KB/s	2.1 KB	23:52:35
7	weretis.net	49.8 KB	0.0 KB	49.8 KB	0.0 KB/s	1.9 KB	23:50:01
8	xmission	47.1 KB	0.0 KB	47.1 KB	0.0 KB/s	1.9 KB	23:59:53
9	news.swapon.de	47.1 KB	0.0 KB	47.1 KB	0.0 KB/s	1.9 KB	23:32:58
10	eternal-september	45.1 KB	0.0 KB	45.1 KB	0.0 KB/s	1.9 KB	23:59:55
11	news.mixmin.net	43.9 KB	0.0 KB	43.9 KB	0.0 KB/s	1.8 KB	23:59:55
12	news.redatomik.org	43.0 KB	7.0 KB	50.0 KB	0.0 KB/s	2.1 KB	23:59:55
13	neva.ru	40.5 KB	243.7 KB	284.2 KB	0.0 KB/s	2.6 KB	23:59:55
14	erje	11.6 KB	264.6 KB	276.3 KB	0.0 KB/s	5.0 KB	23:59:55
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:40:40
TOTAL: 15		20.1 MB	8.0 MB	28.1 MB	0.0 KB/s	8.3 KB	357:44:46

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	10	47	92.0 KB	8	2	1	02:58:08
2	71-82-226-171.dhcp.stpt.wi.charter.com	41	30	56.7 KB	21	23	16	11:34:41
3	a131033.upc-a.chello.nl	69	27	50.7 KB	10	0	0	19:43:25
4	cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	2	6	9.8 KB	1	0	0	01:02:41
5	208-71-239-109.cust.centrio.cz	11	3	4.9 KB	3	0	0	02:20:34
6	challenger.nielsenmedia.com	2	2	4.4 KB	1	0	0	01:02:56
TOTAL: 6		135	115	218.5 KB	44	25	17	39:12:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	10	47	92.0 KB	8	2	1	02:58:08
2	*.dhcp.stpt.wi.charter.com	41	30	56.7 KB	21	23	16	11:34:41
3	*.upc-a.chello.nl	69	27	50.7 KB	10	0	0	19:43:25
4	*.19-1.cable.virginm.net	2	6	9.8 KB	1	0	0	01:02:41
5	*.cust.centrio.cz	11	3	4.9 KB	3	0	0	02:20:34
6	*.nielsenmedia.com	2	2	4.4 KB	1	0	0	01:02:56
7	unresolved	3	0	0.0 KB	0	0	0	00:00:13
8	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
TOTAL: 8		139	115	218.5 KB	44	25	17	39:12:42

NNRP auth users [Top 20]:

User	Conn
ohlandl	16
ctomczyk	11
kev009	1
TOTAL: 3	28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	5.345	0.932	0.000	19:43:25
71-82-226-171.dhcp.stpt.wi.charter.com	3.857	0.515	0.044	11:34:41
ip72-222-200-117.ph.ph.cox.net	1.171	0.109	0.004	02:58:08
208-71-239-109.cust.centrio.cz	0.924	0.146	0.030	02:20:34
challenger.nielsenmedia.com	0.179	0.033	0.000	01:02:56
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	0.192	0.022	0.000	01:02:41
c-67-160-62-214.hsd1.wa.comcast.net	0.055	0.023	0.000	00:30:00
104.131.146.99	0.055	0.023	0.000	00:00:09
5.86.179.6	0.051	0.029	0.000	00:00:02
94.160.212.99	0.064	0.016	0.000	00:00:01
TOTAL: 10	11.893	1.848	0.078	39:12:42

Curious NNRP server explorers [Top 20]:

System	Conn
94.160.212.99	1
104.131.146.99	1
c-67-160-62-214.hsd1.wa.comcast.net	1
5.86.179.6	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-226-171.dhcp.stpt.wi.charter.com	11	0
ip72-222-200-117.ph.ph.cox.net	3	0
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	2	0
challenger.nielsenmedia.com	2	0
c-67-160-62-214.hsd1.wa.comcast.net	1	0
TOTAL: 5	19	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	112	97.4%
2	pl	3	2.6%
TOTAL: 2		115	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	110
2	pl.comp.lang.javascript	3
3	comp.lang.awk	2
TOTAL: 3		115

Daily Usenet report for csiph.com

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