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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	28983	56.8%	6.3 MB	70.9%
innd	12518	24.5%	1.5 MB	16.8%
nnrpd	6145	12.0%	521.2 KB	5.8%
innfeed	3276	6.4%	580.4 KB	6.4%
nocem	87	0.2%	9.2 KB	0.1%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	51011	100.0%	8.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	311629	55.3%
Negative hits	223596	39.7%
Do not exist	27630	4.9%
Cache misses	495	0.1%
TOTAL: 4	563350	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.952	0.0%	267	0.000	7.311	131.000
article cleanup	00:00:00.224	0.0%	27556	0.000	0.008	0.024
article logging	00:00:00.541	0.0%	28363	0.000	0.019	0.057
article parse	00:00:01.204	0.0%	70396	0.000	0.017	0.058
article write	00:02:00.015	0.1%	20588	0.539	5.829	23.397
artlog/artcncl	00:00:00.003	0.0%	213	0.000	0.014	0.333
artlog/artparse	00:00:00.003	0.0%	287	0.000	0.010	0.067
data move	00:00:00.168	0.0%	544244	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.010	0.0%	265	0.000	0.038	1.000
hishave/artcncl	00:00:00.006	0.0%	267	0.000	0.022	0.200
hishave/artparse	00:00:00.000	0.0%	287	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:05.775	0.0%	567520	0.005	0.010	0.019
history sync	00:00:01.232	0.0%	355	0.000	3.470	9.000
history write	00:00:26.278	0.0%	27540	0.305	0.954	19.694
hiswrite/artcncl	00:00:00.003	0.0%	2	1.000	1.500	2.000
hiswrite/artparse	00:00:00.270	0.0%	287	0.000	0.941	9.000
idle	23:39:54.869	99.2%	545683	98.441	156.125	410.452
nntp read	00:00:02.822	0.0%	552262	0.002	0.005	0.009
overview write	00:06:59.082	0.5%	20588	0.305	20.356	5163.415
perl filter	00:00:53.611	0.1%	27345	1.228	1.961	3.115
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.341	0.0%	41257	0.000	0.008	0.038
TOTAL: 23:51:17.844	23:50:28.409	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.066	0.0%	20563	0.000	0.003	0.016
article prepare	00:00:00.001	0.0%	19908	0.000	0.000	0.004
article read	00:00:05.514	0.0%	2745	0.000	2.009	27.500
backlog stats	00:00:00.249	0.0%	375892	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.113	0.0%	290721	0.002	0.007	0.014
data write	00:00:01.735	0.0%	274008	0.001	0.006	0.014
idle	23:43:32.020	100.0%	375892	134.672	227.225	574.953
status file	00:00:00.638	0.0%	296	0.000	2.155	103.000
TOTAL: 23:43:56.869	23:43:42.336	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	16:47:53.494	99.9%	6048	422.667	9998.924	601354.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.090	0.0%	12550	0.000	0.007	0.167
readart	00:00:01.077	0.0%	111	0.000	9.703	97.000
TOTAL: 16:48:26.120	16:47:54.661	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	100
pause	2
paused	2
reload	2
reserve	2
shutdown	3
TOTAL: 11	123

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	6	40356	5079	34932	345	12%	47:39:36
2	csiph-out.feeder.erje.net	1156	87392	3172	81787	2433	3%	94:52:46
3	feeder.eternal-september.org	9	23905	2703	19803	1399	11%	25:25:23
4	news-out.mixmin.net	3	20917	2303	18190	424	11%	24:05:27
5	newsfeeds.xmission.com	3	21028	1434	19590	4	6%	26:19:36
6	news-out.aioe.org	3	19354	1406	17932	16	7%	24:04:01
7	newsfeed.fu-berlin.de	12	18783	1380	17359	44	7%	23:56:20
8	newsfeed.neva.ru	149	26900	1228	23245	2427	4%	71:54:05
9	news.redatomik.org	2	17703	1078	16623	2	6%	23:24:22
10	news.fcku.it	2	7865	520	7271	74	6%	23:00:04
11	nntp.pasdenom.info	3	18742	60	18681	1	0%	23:55:50
12	news.freedyn.net	2	12129	46	12056	27	0%	15:40:18
13	news.swapon.de	6	1733	41	1691	1	2%	24:51:02
14	news.bbs.geek.nz	2	20438	8	20429	1	0%	26:11:58
15	xanadu-bbs.net	20	570	4	566	0	0%	21:52:20
16	localhost	33	0	0	0	0	0%	00:00:01
TOTAL: 16		1411	337815	20462	310155	7198	6%	497:13:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	16.0 MB	2.5 KB	6.4 MB	22.4 MB	71%	4.2 KB
2	csiph-out.feeder.erje.net	16.0 MB	14.3 KB	13.7 MB	29.7 MB	53%	5.4 KB
3	feeder.eternal-september.org	7.0 MB	1.3 KB	6.0 MB	13.0 MB	53%	3.2 KB
4	news-out.mixmin.net	6.6 MB	0.0 KB	1.2 MB	7.9 MB	84%	2.9 KB
5	newsfeeds.xmission.com	4.1 MB	1.3 KB	156.7 KB	4.3 MB	96%	3.0 KB
6	news-out.aioe.org	3.6 MB	1.2 KB	25.6 KB	3.7 MB	99%	2.6 KB
7	newsfeed.neva.ru	3.6 MB	15.1 KB	7.8 MB	11.3 MB	31%	3.2 KB
8	newsfeed.fu-berlin.de	2.9 MB	1.4 KB	100.9 KB	3.0 MB	96%	2.2 KB
9	news.redatomik.org	2.4 MB	0.0 KB	9.6 KB	2.4 MB	99%	2.3 KB
10	news.fcku.it	1.1 MB	0.0 KB	1.3 MB	2.4 MB	46%	4.2 KB
11	news.freedyn.net	202.8 KB	0.0 KB	279.1 KB	482.0 KB	42%	6.6 KB
12	nntp.pasdenom.info	163.9 KB	1.3 KB	1.3 KB	166.4 KB	98%	2.7 KB
13	news.swapon.de	115.7 KB	0.0 KB	62.1 KB	177.8 KB	65%	4.2 KB
14	news.bbs.geek.nz	14.3 KB	1.4 KB	1.4 KB	17.2 KB	83%	1.9 KB
15	xanadu-bbs.net	6.9 KB	0.0 KB	0.0 KB	6.9 KB	100%	1.7 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		63.8 MB	39.8 KB	37.0 MB	100.9 MB	63%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 29 04:15:00 - 04:59:59	355	1.7%	0.13	1.1 MB	1.7%	0.42
Dec 29 05:00:00 - 05:59:59	486	2.4%	0.14	1.6 MB	2.5%	0.45
Dec 29 06:00:00 - 06:59:59	479	2.3%	0.13	1.4 MB	2.1%	0.39
Dec 29 07:00:00 - 07:59:59	714	3.5%	0.20	2.6 MB	4.1%	0.75
Dec 29 08:00:00 - 08:59:59	858	4.2%	0.24	2.4 MB	3.7%	0.68
Dec 29 09:00:00 - 09:59:59	1012	4.9%	0.28	4.4 MB	6.9%	1.25
Dec 29 10:00:00 - 10:59:59	1033	5.0%	0.29	3.2 MB	5.0%	0.92
Dec 29 11:00:00 - 11:59:59	1041	5.0%	0.29	3.0 MB	4.7%	0.87
Dec 29 12:00:00 - 12:59:59	848	4.1%	0.24	2.1 MB	3.3%	0.60
Dec 29 13:00:00 - 13:59:59	1002	4.8%	0.28	2.6 MB	4.1%	0.75
Dec 29 14:00:00 - 14:59:59	1101	5.3%	0.31	3.6 MB	5.7%	1.04
Dec 29 15:00:00 - 15:59:59	1185	5.7%	0.33	3.4 MB	5.3%	0.98
Dec 29 16:00:00 - 16:59:59	1162	5.6%	0.32	3.8 MB	5.9%	1.08
Dec 29 17:00:00 - 17:59:59	1150	5.6%	0.32	3.4 MB	5.3%	0.96
Dec 29 18:00:00 - 18:59:59	1198	5.8%	0.33	3.8 MB	5.9%	1.08
Dec 29 19:00:00 - 19:59:59	1159	5.6%	0.32	3.8 MB	5.9%	1.08
Dec 29 20:00:00 - 20:59:59	956	4.6%	0.27	2.9 MB	4.5%	0.83
Dec 29 21:00:00 - 21:59:59	1084	5.2%	0.30	3.0 MB	4.7%	0.86
Dec 29 22:00:00 - 22:59:59	921	4.5%	0.26	2.7 MB	4.1%	0.76
Dec 29 23:00:00 - 23:59:59	775	3.7%	0.22	2.6 MB	4.0%	0.73
Dec 30 00:00:00 - 00:59:59	602	2.9%	0.17	1.8 MB	2.8%	0.51
Dec 30 01:00:00 - 01:59:59	515	2.5%	0.14	1.5 MB	2.4%	0.43
Dec 30 02:00:00 - 02:59:59	503	2.4%	0.14	1.6 MB	2.4%	0.45
Dec 30 03:00:00 - 03:59:59	426	2.1%	0.12	1.5 MB	2.4%	0.44
Dec 30 04:00:00 - 04:15:00	104	0.5%	0.12	303.8 KB	0.5%	0.34
TOTAL: 24:00:00	20669	100.0%	0.24	64.2 MB	100.0%	0.76

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	2512	290	4	2218
2	csiph-out.feeder.erje.net	2491	164	4	2323
3	feeder.eternal-september.org	1418	39	1	1378
4	newsfeed.fu-berlin.de	690	25	1	664
5	news-out.mixmin.net	468	8	0	460
6	csiph-out.news.weretis.net	374	212	2	160
7	news.fcku.it	73	0	0	73
8	news.redatomik.org	29	0	0	29
9	news-out.aioe.org	23	16	1	6
10	news.freedyn.net	11	0	0	11
11	newsfeeds.xmission.com	7	3	1	3
12	news.swapon.de	3	0	0	3
13	news.bbs.geek.nz	1	0	1	0
14	nntp.pasdenom.info	1	0	1	0
TOTAL: 14		8101	757	16	7328

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	155
mpc.lists.freebsd.ports.bugs	104
mpc.lists.nanog	42
alt.windows7.general	37
mozilla.general	34
it.annunci.immobiliari	33
it.annunci.varie	30
it.annunci.commerciali	30
it-alt.sport.calcio.juventus	20
mozilla.support.firefox	14
alt.english.usage	13
spline.eisfair	12
mozilla.dev.security.policy	12
lada.auto	11
mozilla.support.seamonkey	11
lada.photo	10
alt.politics.usa	9
it-alt.discussioni.ebay	9
gmane.comp.gis.openstreetmap	9
zz.unity.chat	8
TOTAL: 102	757

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5831
Too many newsgroups	49
Scoring filter (10)	28
User-issued cancel	19
Scoring filter (8)	15
HTML Multipart	14
Scoring filter (9)	13
Binary Payload	10
Scoring filter (12)	9
Scoring filter (11)	7
EMP (phl)	5
Cancel for rejected article	3
Binary Image: misplaced jpg	3
Scoring filter (14)	2
Scoring filter (15)	2
Binary: misplaced par2	2
Binary: misplaced 315	1
Scoring filter (13)	1
Binary: misplaced 008	1
Scoring filter (18)	1
TOTAL: 33	6029

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	80	0	86	86
TOTAL: 1	80	1	86	86

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	179
	TOTAL: 1	179
Huge articles
	csiph-out.feeder.erje.net	173
	csiph-out.news.weretis.net	72
	feeder.eternal-september.org	14
	news.fcku.it	13
	news-out.mixmin.net	10
	news.freedyn.net	2
	newsfeeds.xmission.com	2
	news.swapon.de	1
	TOTAL: 8	287
Including strange strings
	newsfeed.fu-berlin.de	645
	csiph-out.feeder.erje.net	50
	news-out.mixmin.net	50
	csiph-out.news.weretis.net	33
	news.redatomik.org	27
	news-out.aioe.org	6
	newsfeed.neva.ru	3
	news.swapon.de	2
	newsfeeds.xmission.com	1
	TOTAL: 9	817
TOTAL: 3		1283

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	37703	1062	18833	122	3	2%	23:55:27
2	news.freedyn.net	21876	741	19905	6	0	3%	23:55:27
3	xanadu-bbs	22870	704	19961	0	1	3%	23:56:56
4	fu-berlin.de	19016	625	18391	0	0	3%	23:56:56
5	usenet.pasdenom.info	21557	82	19020	1507	8	0%	23:56:56
6	aioe	17557	38	17023	2	0	0%	23:55:27
7	neva.ru	19024	13	18498	93	0	0%	23:56:56
8	news.redatomik.org	19409	11	18809	0	1	0%	23:55:27
9	eternal-september	17617	3	17125	0	0	0%	23:56:56
10	news.mixmin.net	17781	2	17430	0	0	0%	23:56:56
11	news.swapon.de	1752	1	1736	0	0	0%	23:47:02
12	au2pb	0	0	0	0	20027	0%	23:54:31
13	erje	18149	0	17404	0	0	0%	23:56:56
14	news.fcku.it	1426	0	1422	0	0	0%	22:39:51
15	bwh	0	0	0	0	20024	0%	23:55:09
16	weretis.net	14831	0	13939	0	1	0%	23:55:27
17	xmission	20316	0	18443	0	1	0%	23:56:56
TOTAL: 17		270884	3282	237939	1730	40066	1%	405:29:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.4 MB	16.0 KB	9.4 MB	0.1 KB/s	12.9 KB	23:55:27
2	xanadu-bbs	9.2 MB	0.0 KB	9.2 MB	0.1 KB/s	13.4 KB	23:56:56
3	fu-berlin.de	9.1 MB	0.0 KB	9.1 MB	0.1 KB/s	14.8 KB	23:56:56
4	news.bbs.geek.nz	3.0 MB	457.7 KB	3.4 MB	0.0 KB/s	3.0 KB	23:55:27
5	usenet.pasdenom.info	241.0 KB	10.4 MB	10.7 MB	0.1 KB/s	6.9 KB	23:56:56
6	aioe	99.6 KB	5.8 KB	105.4 KB	0.0 KB/s	2.6 KB	23:55:27
7	neva.ru	41.3 KB	237.6 KB	279.0 KB	0.0 KB/s	2.6 KB	23:56:56
8	news.redatomik.org	29.8 KB	0.0 KB	29.8 KB	0.0 KB/s	2.7 KB	23:55:27
9	eternal-september	4.2 KB	0.0 KB	4.2 KB	0.0 KB/s	1.4 KB	23:56:56
10	news.mixmin.net	4.2 KB	0.0 KB	4.2 KB	0.0 KB/s	2.1 KB	23:56:56
11	news.swapon.de	1.9 KB	0.0 KB	1.9 KB	0.0 KB/s	1.9 KB	23:47:02
12	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:54:31
13	erje	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:56:56
14	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:39:51
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:55:09
16	weretis.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:55:27
17	xmission	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:56:56
TOTAL: 17		31.0 MB	11.1 MB	42.2 MB	0.0 KB/s	8.6 KB	405:29:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	141.168.117.108	48	85	255.1 KB	48	00:48:21
2	tempe0.bbox.io	29	13	20.1 KB	7	09:56:02
3	96-42-18-226.dhcp.mdsn.wi.charter.com	3	8	14.8 KB	1	01:01:20
4	a131033.upc-a.chello.nl	21	4	8.3 KB	2	04:01:28
5	challenger.nielsenmedia.com	2	1	5.8 KB	1	01:00:06
TOTAL: 5		103	111	304.1 KB	59	16:48:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	49	85	255.1 KB	48	00:48:31
2	*.bbox.io	29	13	20.1 KB	7	09:56:02
3	*.dhcp.mdsn.wi.charter.com	3	8	14.8 KB	1	01:01:20
4	*.upc-a.chello.nl	21	4	8.3 KB	2	04:01:28
5	*.nielsenmedia.com	2	1	5.8 KB	1	01:00:06
6	*.cust.centrio.cz	2	0	0.0 KB	0	00:00:55
TOTAL: 6		106	111	304.1 KB	59	16:48:25

NNRP auth users [Top 20]:

User	Conn
moussa	48
ctomczyk	2
TOTAL: 2	50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
tempe0.bbox.io	2.384	0.439	0.000	09:56:02
a131033.upc-a.chello.nl	1.626	0.293	0.000	04:01:28
96-42-18-226.dhcp.mdsn.wi.charter.com	0.267	0.024	0.000	01:01:20
challenger.nielsenmedia.com	0.187	0.017	0.000	01:00:06
141.168.117.108	5.283	1.053	0.185	00:48:21
194-73-239-109.cust.centrio.cz	0.167	0.038	0.010	00:00:55
104.236.140.121	0.092	0.008	0.000	00:00:09
TOTAL: 7	10.006	1.872	0.195	16:48:25

Curious NNRP server explorers [Top 20]:

System	Conn
194-73-239-109.cust.centrio.cz	2
104.236.140.121	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	12	0
challenger.nielsenmedia.com	2	0
96-42-18-226.dhcp.mdsn.wi.charter.com	2	0
TOTAL: 3	16	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	62	55.9%
2	comp	45	40.5%
3	news	4	3.6%
TOTAL: 3		111	100.0%

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

Newsgroup		Count
1	alt.comp.freeware	62
2	comp.sys.ibm.ps2.hardware	15
3	comp.os.vms	13
4	news.software.nntp	4
5	comp.os.msdos.djgpp	4
6	comp.sys.hp.hpux	4
7	comp.arch	3
8	comp.sys.ibm.as400.misc	2
9	comp.lang.awk	1
10	comp.unix.aix	1
11	comp.sys.ibm.pc.games.action	1
12	comp.os.os2.programmer.misc	1
TOTAL: 12		111

Daily Usenet report for csiph.com

Dec 29 04:15:00 -- Dec 30 04:15:00