Daily Usenet report for csiph.com: May 2 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	24502	52.4%	5.4 MB	67.7%
innd	10013	21.4%	1.3 MB	16.0%
nnrpd	9259	19.8%	848.1 KB	10.4%
innfeed	2863	6.1%	476.6 KB	5.8%
nocem	91	0.2%	10.5 KB	0.1%
TOTAL: 5	46728	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	295066	55.3%
Negative hits	214405	40.2%
Do not exist	23512	4.4%
Cache misses	511	0.1%
TOTAL: 4	533494	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.412	0.0%	205	0.000	2.010	45.250
article cleanup	00:00:00.180	0.0%	23294	0.000	0.008	0.047
article logging	00:00:00.864	0.0%	23980	0.000	0.036	1.943
article parse	00:00:01.891	0.0%	60780	0.000	0.031	0.336
article write	00:01:58.787	0.1%	19114	0.218	6.215	93.646
artlog/artcncl	00:00:00.002	0.0%	173	0.000	0.012	0.080
artlog/artparse	00:00:00.005	0.0%	340	0.000	0.015	0.500
data move	00:00:00.165	0.0%	511512	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.033	0.0%	205	0.000	0.161	4.000
hishave/artcncl	00:00:00.050	0.0%	205	0.000	0.244	4.333
hishave/artparse	00:00:00.001	0.0%	338	0.000	0.003	0.056
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:44.410	0.1%	535727	0.003	0.083	0.748
history sync	00:00:02.054	0.0%	383	0.000	5.363	62.667
history write	00:01:00.597	0.1%	23291	0.224	2.602	31.473
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.152	0.0%	338	0.000	3.408	49.000
idle	23:45:47.601	98.9%	508563	85.830	168.214	442.481
nntp read	00:00:02.545	0.0%	516279	0.003	0.005	0.011
overview write	00:10:13.735	0.7%	19114	0.431	32.109	3169.296
perl filter	00:00:46.289	0.1%	23289	1.411	1.988	4.441
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.312	0.0%	38314	0.000	0.008	0.042
TOTAL: 24:01:34.266	24:00:41.085	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.048	0.0%	20888	0.000	0.002	0.016
article prepare	00:00:00.009	0.0%	32188	0.000	0.000	0.010
article read	00:00:06.825	0.0%	5962	0.000	1.145	33.481
backlog stats	00:00:00.197	0.0%	360385	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.854	0.0%	277337	0.002	0.007	0.012
data write	00:00:01.651	0.0%	261103	0.000	0.006	0.014
idle	23:53:20.753	100.0%	360385	77.321	238.636	711.870
status file	00:00:02.723	0.0%	265	0.000	10.275	131.000
TOTAL: 23:53:44.863	23:53:34.060	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	64:06:51.165	100.0%	8512	0.000	27115.973	600062.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.105	0.0%	17540	0.000	0.006	0.167
readart	00:00:00.230	0.0%	25	0.000	9.200	76.000
TOTAL: 64:08:04.629	64:06:51.500	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	8	40357	7101	32624	632	17%	48:40:48
2	csiph-out.feeder.erje.net	1152	81039	2886	76672	1481	3%	95:02:18
3	feeder.eternal-september.org	9	21711	2471	18397	843	11%	25:57:55
4	news.redatomik.org	2	18267	1902	16298	67	10%	23:47:09
5	newsfeed.fu-berlin.de	18	17340	1176	15966	198	6%	25:44:51
6	newsfeed.neva.ru	148	20170	1082	18480	608	5%	71:40:33
7	news-out.aioe.org	2	19545	839	18615	91	4%	24:09:03
8	news.fcku.it	1	8767	751	7867	149	8%	23:59:56
9	news-out.mixmin.net	3	20282	538	19344	400	2%	24:08:51
10	newsfeeds.xmission.com	2	19326	355	18957	14	1%	25:07:19
11	news.swapon.de	3	1508	70	1436	2	4%	24:22:31
12	news.bbs.geek.nz	4	13818	57	13754	7	0%	24:49:59
13	news.freedyn.net	1	21175	48	21048	79	0%	24:09:48
14	nntp.pasdenom.info	5	17766	38	17717	11	0%	23:57:11
15	xanadu-bbs.net	19	694	3	686	5	0%	22:35:26
16	localhost	33	1	1	0	0	100%	00:00:07
TOTAL: 16		1410	321766	19318	297861	4587	6%	508:13:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	20.9 MB	14.5 KB	8.8 MB	29.7 MB	70%	3.9 KB
2	csiph-out.feeder.erje.net	13.4 MB	0.0 KB	12.3 MB	25.7 MB	52%	6.0 KB
3	feeder.eternal-september.org	6.6 MB	267.0 KB	6.4 MB	13.3 MB	49%	4.1 KB
4	news.redatomik.org	4.0 MB	4.1 KB	283.1 KB	4.3 MB	93%	2.2 KB
5	newsfeed.neva.ru	3.4 MB	5.5 KB	2.7 MB	6.1 MB	56%	3.7 KB
6	news-out.aioe.org	2.7 MB	20.2 KB	287.4 KB	3.0 MB	90%	3.4 KB
7	newsfeed.fu-berlin.de	2.6 MB	127.5 KB	677.9 KB	3.4 MB	76%	2.5 KB
8	news-out.mixmin.net	2.0 MB	36.7 KB	1022.6 KB	3.0 MB	65%	3.3 KB
9	news.fcku.it	1.8 MB	4.1 KB	604.1 KB	2.4 MB	75%	2.8 KB
10	newsfeeds.xmission.com	954.1 KB	40.5 KB	290.3 KB	1.3 MB	74%	3.5 KB
11	news.bbs.geek.nz	207.4 KB	24.4 KB	24.4 KB	256.2 KB	80%	4.0 KB
12	news.swapon.de	184.4 KB	0.0 KB	3.5 KB	187.9 KB	98%	2.6 KB
13	news.freedyn.net	132.0 KB	33.6 KB	335.6 KB	501.1 KB	26%	3.9 KB
14	nntp.pasdenom.info	96.2 KB	11.8 KB	30.6 KB	138.6 KB	69%	2.8 KB
15	xanadu-bbs.net	7.7 KB	0.0 KB	19.1 KB	26.8 KB	28%	3.3 KB
16	localhost	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 KB
TOTAL: 16		59.0 MB	589.9 KB	33.6 MB	93.2 MB	63%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 2 04:15:00 - 04:59:59	355	1.9%	0.13	1.4 MB	2.4%	0.53
May 2 05:00:00 - 05:59:59	482	2.5%	0.13	1.6 MB	2.8%	0.46
May 2 06:00:00 - 06:59:59	544	2.8%	0.15	1.7 MB	2.9%	0.48
May 2 07:00:00 - 07:59:59	749	3.9%	0.21	2.1 MB	3.6%	0.60
May 2 08:00:00 - 08:59:59	703	3.7%	0.20	1.7 MB	2.9%	0.49
May 2 09:00:00 - 09:59:59	817	4.3%	0.23	2.0 MB	3.4%	0.56
May 2 10:00:00 - 10:59:59	859	4.5%	0.24	3.0 MB	5.1%	0.84
May 2 11:00:00 - 11:59:59	815	4.3%	0.23	2.2 MB	3.8%	0.63
May 2 12:00:00 - 12:59:59	930	4.9%	0.26	2.5 MB	4.3%	0.71
May 2 13:00:00 - 13:59:59	967	5.1%	0.27	2.6 MB	4.5%	0.74
May 2 14:00:00 - 14:59:59	1240	6.5%	0.34	3.9 MB	6.7%	1.11
May 2 15:00:00 - 15:59:59	1257	6.6%	0.35	3.8 MB	6.5%	1.08
May 2 16:00:00 - 16:59:59	1133	5.9%	0.31	3.1 MB	5.3%	0.88
May 2 17:00:00 - 17:59:59	1108	5.8%	0.31	2.9 MB	5.1%	0.84
May 2 18:00:00 - 18:59:59	1087	5.7%	0.30	3.5 MB	5.9%	0.98
May 2 19:00:00 - 19:59:59	1117	5.8%	0.31	3.5 MB	6.1%	1.01
May 2 20:00:00 - 20:59:59	951	5.0%	0.26	3.3 MB	5.6%	0.93
May 2 21:00:00 - 21:59:59	900	4.7%	0.25	2.9 MB	4.9%	0.82
May 2 22:00:00 - 22:59:59	636	3.3%	0.18	2.1 MB	3.7%	0.61
May 2 23:00:00 - 23:59:59	495	2.6%	0.14	1.4 MB	2.5%	0.41
May 3 00:00:00 - 00:59:59	479	2.5%	0.13	1.5 MB	2.6%	0.43
May 3 01:00:00 - 01:59:59	490	2.6%	0.14	1.6 MB	2.7%	0.45
May 3 02:00:00 - 02:59:59	484	2.5%	0.13	2.0 MB	3.4%	0.56
May 3 03:00:00 - 03:59:59	395	2.1%	0.11	1.3 MB	2.2%	0.36
May 3 04:00:00 - 04:15:00	120	0.6%	0.13	609.4 KB	1.0%	0.68
TOTAL: 24:00:00	19113	100.0%	0.22	58.2 MB	100.0%	0.69

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	1488	177	0	1	1310
2	feeder.eternal-september.org	819	58	1	0	760
3	newsfeed.fu-berlin.de	785	40	0	0	745
4	csiph-out.news.weretis.net	651	265	0	0	386
5	newsfeed.neva.ru	609	111	0	0	498
6	news-out.mixmin.net	416	2	0	0	414
7	news.fcku.it	147	4	0	0	143
8	news-out.aioe.org	92	71	0	0	21
9	news.redatomik.org	92	0	0	0	92
10	news.freedyn.net	74	1	3	0	70
11	news.swapon.de	21	0	0	0	21
12	nntp.pasdenom.info	9	5	1	0	3
13	newsfeeds.xmission.com	5	1	0	0	4
14	xanadu-bbs.net	5	0	0	0	5
TOTAL: 14		5213	735	5	1	4472

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	105
it.annunci.commerciali	55
it.annunci.varie	54
lada.talk	53
it.annunci.immobiliari	52
gmane.linux.alsa.devel	46
mpc.lists.freebsd.ports.bugs	37
alt.windows7.general	32
alt.survival	29
mpc.lists.freebsd.gnome	24
lada.auto	22
mozilla.dev.platform	16
spline.eisfair	13
mpc.lists.freebsd.x11	10
gmane.comp.hardware.netbook.arm.sunxi	9
mozilla.general	9
mozilla.support.firefox	8
gmane.comp.gis.openstreetmap	8
spline.eisfair.dev	7
mozilla.support.seamonkey	6
TOTAL: 93	735

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
it.scienza.astronomia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2874
EMP (phn path)	213
EMP (phn nph)	78
Too many newsgroups	56
EMP (phl)	43
HTML Multipart	43
Binary Payload	32
Scoring filter (9)	30
User-issued cancel	16
Scoring filter (11)	14
Scoring filter (10)	10
Scoring filter (12)	8
Scoring filter (14)	7
HTML post	5
Scoring filter (8)	4
Scoring filter (16)	4
User-issued spam cancel	3
Too many newsgroups (low_xpost)	3
Binary: misplaced cst	1
Scoring filter (13)	1
TOTAL: 20	3445

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	53	0	56	56
TOTAL: 1	53	33	56	56

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.feeder.erje.net	167
	csiph-out.news.weretis.net	111
	feeder.eternal-september.org	44
	news-out.mixmin.net	5
	newsfeeds.xmission.com	4
	newsfeed.fu-berlin.de	3
	news.fcku.it	3
	news.freedyn.net	2
	news.redatomik.org	1
	TOTAL: 9	340
Including strange strings
	newsfeed.fu-berlin.de	593
	news.redatomik.org	32
	csiph-out.news.weretis.net	19
	news.swapon.de	19
	news-out.mixmin.net	7
	csiph-out.feeder.erje.net	6
	newsfeed.neva.ru	4
	news-out.aioe.org	3
	feeder.eternal-september.org	2
	TOTAL: 9	685
TOTAL: 2		1025

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	31653	4609	13947	31	2	220	14%	23:59:52
2	xanadu-bbs	21086	585	18525	0	0	0	2%	23:59:52
3	news.freedyn.net	20050	564	18505	0	0	0	2%	23:59:52
4	fu-berlin.de	17723	498	17205	0	0	142	2%	23:59:49
5	usenet.pasdenom.info	40568	263	18017	1136	0	609	0%	23:59:52
6	aioe	16361	53	15968	0	0	0	0%	23:59:45
7	erje	17085	45	16087	13	0	0	0%	23:59:52
8	eternal-september	16473	31	16007	9	0	513	0%	23:59:52
9	news.redatomik.org	16969	21	16625	0	0	0	0%	23:59:52
10	weretis.net	11139	15	10696	4	0	4	0%	23:59:52
11	news.mixmin.net	18086	10	17726	0	0	0	0%	23:59:52
12	xmission	21844	9	18165	2	0	1	0%	23:59:35
13	neva.ru	17481	5	17104	181	0	0	0%	23:59:43
14	news.swapon.de	1574	2	1543	0	0	0	0%	23:52:35
15	news.fcku.it	2044	0	2035	0	0	0	0%	22:16:08
TOTAL: 15		270136	6710	218155	1376	2	1489	2%	358:06:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	13.0 MB	87.9 KB	13.1 MB	0.2 KB/s	2.9 KB	23:59:52
2	xanadu-bbs	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	10.9 KB	23:59:52
3	news.freedyn.net	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	11.3 KB	23:59:52
4	fu-berlin.de	6.0 MB	0.0 KB	6.0 MB	0.1 KB/s	12.4 KB	23:59:49
5	usenet.pasdenom.info	839.8 KB	7.0 MB	7.9 MB	0.1 KB/s	5.8 KB	23:59:52
6	aioe	131.4 KB	0.0 KB	131.4 KB	0.0 KB/s	2.5 KB	23:59:45
7	eternal-september	98.6 KB	15.2 KB	113.8 KB	0.0 KB/s	2.8 KB	23:59:52
8	erje	91.7 KB	31.5 KB	123.2 KB	0.0 KB/s	2.1 KB	23:59:52
9	news.redatomik.org	46.1 KB	0.0 KB	46.1 KB	0.0 KB/s	2.2 KB	23:59:52
10	weretis.net	22.5 KB	10.6 KB	33.1 KB	0.0 KB/s	1.7 KB	23:59:52
11	news.mixmin.net	22.2 KB	0.0 KB	22.2 KB	0.0 KB/s	2.2 KB	23:59:52
12	xmission	17.1 KB	8.3 KB	25.4 KB	0.0 KB/s	2.3 KB	23:59:35
13	neva.ru	15.4 KB	406.7 KB	422.1 KB	0.0 KB/s	2.3 KB	23:59:43
14	news.swapon.de	4.5 KB	0.0 KB	4.5 KB	0.0 KB/s	2.2 KB	23:52:35
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:16:08
TOTAL: 15		32.8 MB	7.6 MB	40.4 MB	0.0 KB/s	5.1 KB	358:06:23

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	194-73-239-109.cust.centrio.cz	6	9	21.8 KB	4	0	0	01:08:14
2	141.168.117.108	48	8	17.2 KB	5	0	0	00:45:41
3	ip72-222-200-117.ph.ph.cox.net	305	8	16.9 KB	5	1	1	53:17:08
TOTAL: 3		359	25	55.9 KB	14	1	1	64:08:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cust.centrio.cz	6	9	21.8 KB	4	0	0	01:08:14
2	*.ph.ph.cox.net	305	8	16.9 KB	5	1	1	53:17:08
3	unresolved	48	8	17.2 KB	5	0	0	00:45:41
4	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	0	00:00:00
5	*.ampereinnotech.com	2	0	0.0 KB	0	0	0	00:00:00
6	*.dhcp.mdsn.wi.charter.com	8	0	0.0 KB	0	0	0	00:37:01
7	*.nielsenmedia.com	1	0	0.0 KB	0	0	0	00:30:10
8	*.upc-a.chello.nl	48	0	0.0 KB	0	0	0	07:49:43
TOTAL: 8		419	25	55.9 KB	14	1	1	64:08:00

NNRP auth users [Top 20]:

User	Conn
ctomczyk	6
kev009	1
TOTAL: 2	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ip72-222-200-117.ph.ph.cox.net	22.192	5.339	0.003	53:17:08
a131033.upc-a.chello.nl	3.430	0.734	0.000	07:49:43
194-73-239-109.cust.centrio.cz	0.574	0.078	0.024	01:08:14
141.168.117.108	5.083	1.044	0.000	00:45:41
96-42-18-226.dhcp.mdsn.wi.charter.com	0.482	0.103	0.000	00:37:01
challenger.nielsenmedia.com	0.079	0.024	0.000	00:30:10
scan0.ampereinnotech.com	0.128	0.025	0.000	00:00:00
ec2-35-166-36-30.us-west-2.compute.amazonaws.com	0.077	0.023	0.000	00:00:00
TOTAL: 8	32.045	7.370	0.027	64:08:00

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	48
96-42-18-226.dhcp.mdsn.wi.charter.com	8
scan0.ampereinnotech.com	2
challenger.nielsenmedia.com	1
ec2-35-166-36-30.us-west-2.compute.amazonaws.com	1
TOTAL: 5	60

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	6	0
194-73-239-109.cust.centrio.cz	2	0
challenger.nielsenmedia.com	1	0
TOTAL: 3	9	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	24	96.0%
2	news	1	4.0%
TOTAL: 2		25	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	15
2	comp.lang.javascript	9
3	news.admin.peering	1
TOTAL: 3		25

Daily Usenet report for csiph.com

May 2 04:15:00 -- May 3 04:15:00