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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	39735	47.9%	7.2 MB	60.7%
innd	24188	29.2%	2.6 MB	21.7%
nnrpd	15757	19.0%	1.6 MB	13.2%
innfeed	3164	3.8%	515.1 KB	4.3%
nocem	104	0.1%	11.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	82949	100.0%	11.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	366363	54.8%
Negative hits	263059	39.4%
Do not exist	38201	5.7%
Cache misses	574	0.1%
TOTAL: 4	668197	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.132	0.0%	268	0.000	4.224	39.333
article cleanup	00:00:00.255	0.0%	38899	0.000	0.007	0.043
article logging	00:00:05.254	0.0%	39421	0.000	0.133	1.130
article parse	00:00:03.731	0.0%	132170	0.000	0.028	0.921
article write	00:02:59.599	0.2%	19864	0.271	9.041	41.873
artlog/artcncl	00:00:00.001	0.0%	203	0.000	0.005	0.167
artlog/artparse	00:00:00.046	0.0%	189	0.000	0.243	2.667
data move	00:00:00.472	0.0%	626846	0.000	0.001	0.022
hisgrep/artcncl	00:00:00.373	0.0%	267	0.000	1.397	22.000
hishave/artcncl	00:00:00.113	0.0%	268	0.000	0.422	4.333
hishave/artparse	00:00:00.000	0.0%	189	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:34.714	0.2%	676495	0.003	0.229	1.462
history sync	00:00:05.726	0.0%	370	0.000	15.476	202.500
history write	00:03:58.289	0.3%	38643	0.245	6.166	43.510
hiswrite/artcncl	00:00:00.001	0.0%	1	1.000	1.000	1.000
hiswrite/artparse	00:00:02.239	0.0%	189	0.000	11.847	131.000
idle	23:42:28.211	98.7%	608712	73.594	140.211	594.159
nntp read	00:00:02.928	0.0%	637621	0.002	0.005	0.012
overview write	00:05:30.313	0.4%	19864	1.627	16.629	428.324
perl filter	00:01:41.980	0.1%	38487	1.327	2.650	9.243
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.371	0.0%	39822	0.000	0.009	0.029
TOTAL: 24:00:53.390	23:59:35.748	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.064	0.0%	19846	0.000	0.003	0.028
article prepare	00:00:00.001	0.0%	25564	0.000	0.000	0.007
article read	00:00:01.038	0.0%	3652	0.000	0.284	16.000
backlog stats	00:00:00.233	0.0%	368148	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.051	0.0%	284157	0.002	0.007	0.012
data write	00:00:01.686	0.0%	266649	0.000	0.006	0.014
idle	23:53:39.947	100.0%	368148	129.991	233.656	773.671
status file	00:00:00.307	0.0%	277	0.000	1.108	16.000
TOTAL: 23:53:54.686	23:53:45.327	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.560	0.0%	1332	0.420	0.420	0.420
idle	40:06:42.186	99.9%	43997	81.200	3282.092	600109.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.588	0.0%	88295	0.000	0.007	0.167
readart	00:00:30.105	0.0%	1628	0.000	18.492	167.000
TOTAL: 40:08:59.220	40:07:13.439	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	4	41835	5329	35411	1095	12%	50:06:36
2	csiph-out.feeder.erje.net	1153	119560	5128	104625	9807	4%	94:28:08
3	feeder.eternal-september.org	8	35102	2418	27616	5068	6%	25:18:12
4	news-out.mixmin.net	3	21774	1828	19031	915	8%	24:08:48
5	news-out.aioe.org	3	19737	1782	17928	27	9%	24:09:01
6	newsfeed.neva.ru	148	47226	920	43796	2510	1%	71:36:59
7	newsfeed.fu-berlin.de	9	18355	893	17299	163	4%	21:50:25
8	newsfeeds.xmission.com	3	19133	870	18227	36	4%	25:41:24
9	news.redatomik.org	1	18372	284	18085	3	1%	23:40:14
10	xanadu-bbs.net	19	1585	116	1463	6	7%	36:55:31
11	news.fcku.it	2	8224	90	8093	41	1%	23:59:41
12	nntp.pasdenom.info	2	17736	82	17652	2	0%	23:58:36
13	news.freedyn.net	2	23011	54	22851	106	0%	23:59:46
14	news.swapon.de	5	1526	42	1477	7	2%	24:35:26
15	news.bbs.geek.nz	1	18072	8	18060	4	0%	23:59:48
16	localhost	39	5	5	0	0	100%	00:00:07
TOTAL: 16		1402	411253	19849	371614	19790	4%	518:28:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	18.0 MB	1.6 MB	59.9 MB	79.5 MB	22%	5.5 KB
2	csiph-out.news.weretis.net	16.3 MB	0.0 KB	10.9 MB	27.2 MB	59%	4.3 KB
3	feeder.eternal-september.org	6.4 MB	1.2 MB	67.3 MB	74.8 MB	8%	10.2 KB
4	news-out.mixmin.net	6.1 MB	0.0 KB	1.6 MB	7.7 MB	79%	2.9 KB
5	news-out.aioe.org	4.3 MB	0.0 KB	71.4 KB	4.4 MB	98%	2.5 KB
6	newsfeeds.xmission.com	2.7 MB	0.0 KB	256.4 KB	2.9 MB	91%	3.3 KB
7	newsfeed.neva.ru	2.2 MB	264.9 KB	23.4 MB	25.8 MB	8%	7.7 KB
8	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	340.1 KB	2.3 MB	85%	2.2 KB
9	news.redatomik.org	603.3 KB	0.0 KB	4.6 KB	608.0 KB	99%	2.1 KB
10	xanadu-bbs.net	425.9 KB	0.0 KB	180.5 KB	606.4 KB	70%	5.0 KB
11	news.freedyn.net	273.4 KB	0.0 KB	791.6 KB	1.0 MB	25%	6.7 KB
12	nntp.pasdenom.info	195.2 KB	0.0 KB	3.2 KB	198.3 KB	98%	2.4 KB
13	news.fcku.it	179.5 KB	0.0 KB	3.2 MB	3.4 MB	5%	26.5 KB
14	news.swapon.de	107.2 KB	0.0 KB	1.6 MB	1.8 MB	5%	36.6 KB
15	news.bbs.geek.nz	14.6 KB	0.0 KB	1.7 MB	1.7 MB	0%	142.8 KB
16	localhost	6.1 KB	0.0 KB	0.0 KB	6.1 KB	100%	1.2 KB
TOTAL: 16		59.6 MB	3.0 MB	171.3 MB	233.9 MB	25%	6.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 15 04:15:00 - 04:59:59	286	1.4%	0.11	844.3 KB	1.4%	0.31
Apr 15 05:00:00 - 05:59:59	507	2.6%	0.14	1.7 MB	2.8%	0.47
Apr 15 06:00:00 - 06:59:59	684	3.4%	0.19	2.7 MB	4.5%	0.76
Apr 15 07:00:00 - 07:59:59	804	4.0%	0.22	2.5 MB	4.3%	0.72
Apr 15 08:00:00 - 08:59:59	833	4.2%	0.23	2.3 MB	3.8%	0.64
Apr 15 09:00:00 - 09:59:59	888	4.5%	0.25	3.0 MB	5.1%	0.87
Apr 15 10:00:00 - 10:59:59	893	4.5%	0.25	2.5 MB	4.1%	0.70
Apr 15 11:00:00 - 11:59:59	887	4.5%	0.25	2.1 MB	3.5%	0.58
Apr 15 12:00:00 - 12:59:59	1004	5.1%	0.28	3.0 MB	5.1%	0.86
Apr 15 13:00:00 - 13:59:59	1092	5.5%	0.30	3.6 MB	6.0%	1.02
Apr 15 14:00:00 - 14:59:59	991	5.0%	0.28	3.2 MB	5.3%	0.90
Apr 15 15:00:00 - 15:59:59	1159	5.8%	0.32	3.7 MB	6.2%	1.04
Apr 15 16:00:00 - 16:59:59	1344	6.8%	0.37	3.4 MB	5.7%	0.97
Apr 15 17:00:00 - 17:59:59	1188	6.0%	0.33	3.4 MB	5.7%	0.97
Apr 15 18:00:00 - 18:59:59	1110	5.6%	0.31	3.3 MB	5.6%	0.94
Apr 15 19:00:00 - 19:59:59	945	4.8%	0.26	2.5 MB	4.3%	0.72
Apr 15 20:00:00 - 20:59:59	983	4.9%	0.27	2.7 MB	4.5%	0.76
Apr 15 21:00:00 - 21:59:59	956	4.8%	0.27	2.8 MB	4.7%	0.80
Apr 15 22:00:00 - 22:59:59	826	4.2%	0.23	2.6 MB	4.4%	0.74
Apr 15 23:00:00 - 23:59:59	609	3.1%	0.17	1.7 MB	2.8%	0.48
Apr 16 00:00:00 - 00:59:59	491	2.5%	0.14	1.5 MB	2.6%	0.44
Apr 16 01:00:00 - 01:59:59	477	2.4%	0.13	1.6 MB	2.6%	0.45
Apr 16 02:00:00 - 02:59:59	410	2.1%	0.11	1.5 MB	2.5%	0.43
Apr 16 03:00:00 - 03:59:59	349	1.8%	0.10	1.0 MB	1.7%	0.29
Apr 16 04:00:00 - 04:15:00	147	0.7%	0.16	480.5 KB	0.8%	0.53
TOTAL: 24:00:00	19863	100.0%	0.23	59.4 MB	100.0%	0.70

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	9246	175	135	1	8935
2	feeder.eternal-september.org	5039	36	96	0	4907
3	newsfeed.neva.ru	2514	270	28	0	2216
4	csiph-out.news.weretis.net	1141	179	2	0	960
5	news-out.mixmin.net	967	3	1	0	963
6	newsfeed.fu-berlin.de	502	46	0	0	456
7	news.freedyn.net	106	2	0	0	104
8	news.fcku.it	51	0	0	0	51
9	news-out.aioe.org	42	19	1	0	22
10	newsfeeds.xmission.com	33	0	0	0	33
11	news.swapon.de	10	0	0	0	10
12	news.redatomik.org	6	0	0	0	6
13	xanadu-bbs.net	6	0	0	0	6
14	nntp.pasdenom.info	2	0	0	0	2
TOTAL: 14		19665	730	263	1	18671

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	133
lada.auto	95
lada.talk	82
spline.eisfair	35
it-alt.sport.calcio.juventus	26
gmane.comp.gis.openstreetmap.tagging	22
mozilla.support.firefox	21
mpc.lists.freebsd.gecko	19
alt.2600	18
mozilla.support.thunderbird	15
alt.windows7.general	15
mozilla.support.seamonkey	11
lada.photo	10
mozilla.dev.l10n	10
mpc.lists.freebsd.x11	10
alt.politics.scorched-earth	9
alt.survival	9
alt.binaries.moovee	9
oesterreich.tratsch	7
mpc.lists.freebsd.gnome	7
TOTAL: 111	730

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)	16022
EMP (phn path)	1445
EMP (phn nph)	155
Too many newsgroups	55
User-issued cancel	22
Too many newsgroups (low_xpost)	15
Binary Payload	12
HTML Multipart	12
EMP (phl)	12
Scoring filter (10)	11
Scoring filter (14)	10
Scoring filter (12)	8
Scoring filter (8)	7
Scoring filter (11)	6
Cancel for rejected article	3
Scoring filter (9)	1
Scoring filter (13)	1
Scoring filter (34)	1
HTML Image Tags	1
HTML post	1
TOTAL: 20	17800

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	97	0	99	99
TOTAL: 1	97	0	99	99

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	156
	TOTAL: 1	156
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	feeder.eternal-september.org	98
	news.fcku.it	42
	csiph-out.news.weretis.net	36
	news-out.mixmin.net	4
	news.swapon.de	4
	xanadu-bbs.net	3
	newsfeeds.xmission.com	2
	TOTAL: 7	189
Including strange strings
	newsfeed.fu-berlin.de	326
	csiph-out.news.weretis.net	71
	news-out.mixmin.net	59
	csiph-out.feeder.erje.net	29
	newsfeed.neva.ru	14
	news-out.aioe.org	13
	news.swapon.de	4
	news.redatomik.org	3
	newsfeeds.xmission.com	2
	feeder.eternal-september.org	1
	TOTAL: 10	522
TOTAL: 4		871

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	35815	1253	17644	70	0	3%	23:50:05
2	news.freedyn.net	20552	909	18799	3	0	4%	23:50:05
3	xanadu-bbs	21390	870	18890	14	0	4%	23:59:55
4	fu-berlin.de	18567	755	17811	0	0	4%	23:50:41
5	usenet.pasdenom.info	21325	201	17458	2121	5	0%	23:59:54
6	xmission	19789	49	18017	4	0	0%	23:59:55
7	news.redatomik.org	19335	23	18592	9	0	0%	23:50:05
8	aioe	17401	22	16564	0	0	0%	23:50:05
9	weretis.net	13642	16	13311	1	0	0%	23:50:06
10	erje	14994	14	14644	13	22	0%	23:57:13
11	eternal-september	17880	10	16422	0	0	0%	23:59:54
12	news.mixmin.net	17676	10	16972	0	0	0%	23:55:47
13	neva.ru	18759	5	17986	15	0	0%	23:59:53
14	news.swapon.de	1661	3	1612	2	0	0%	23:43:27
15	au2pb	0	0	0	0	18979	0%	23:50:07
16	bwh	0	0	0	0	18979	0%	23:50:07
17	news.fcku.it	1961	0	1887	0	0	0%	22:47:25
TOTAL: 17		260747	4140	226609	2252	37985	1%	405:04:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.8 MB	3.7 KB	6.8 MB	0.1 KB/s	7.6 KB	23:50:05
2	xanadu-bbs	6.5 MB	25.0 KB	6.5 MB	0.1 KB/s	7.5 KB	23:59:55
3	fu-berlin.de	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	8.4 KB	23:50:41
4	news.bbs.geek.nz	3.3 MB	235.3 KB	3.6 MB	0.0 KB/s	2.8 KB	23:50:05
5	usenet.pasdenom.info	772.4 KB	8.5 MB	9.3 MB	0.1 KB/s	4.1 KB	23:59:54
6	erje	178.8 KB	50.3 KB	229.1 KB	0.0 KB/s	8.5 KB	23:57:13
7	xmission	83.6 KB	8.1 KB	91.7 KB	0.0 KB/s	1.7 KB	23:59:55
8	aioe	45.6 KB	0.0 KB	45.6 KB	0.0 KB/s	2.1 KB	23:50:05
9	news.redatomik.org	44.5 KB	15.2 KB	59.7 KB	0.0 KB/s	1.9 KB	23:50:05
10	weretis.net	34.4 KB	5.8 KB	40.2 KB	0.0 KB/s	2.4 KB	23:50:06
11	eternal-september	17.5 KB	0.0 KB	17.5 KB	0.0 KB/s	1.8 KB	23:59:54
12	news.mixmin.net	15.1 KB	0.0 KB	15.1 KB	0.0 KB/s	1.5 KB	23:55:47
13	neva.ru	8.9 KB	46.8 KB	55.7 KB	0.0 KB/s	2.8 KB	23:59:53
14	news.swapon.de	4.1 KB	2.1 KB	6.1 KB	0.0 KB/s	1.2 KB	23:43:27
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:47:25
TOTAL: 17		24.0 MB	8.9 MB	32.9 MB	0.0 KB/s	5.3 KB	405:04:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-04-70-53-246-3.dsl.bell.ca	650	28	85.6 KB	20	0	0	01:25:49
2	093105191108.choszczno.vectranet.pl	7	9	20.7 KB	2	0	0	01:30:28
3	144.138.90.254	62	5	16.2 KB	5	0	0	01:20:33
4	dhcp-089-099-203-254.chello.nl	77	5	8.3 KB	2	0	0	24:37:58
5	68-185-181-64.dhcp.mdsn.wi.charter.com	24	5	6.1 KB	5	5	5	05:32:52
TOTAL: 5		820	52	137.0 KB	34	5	5	40:08:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	650	28	85.6 KB	20	0	0	01:25:49
2	*.choszczno.vectranet.pl	7	9	20.7 KB	2	0	0	01:30:28
3	unresolved	62	5	16.2 KB	5	0	0	01:20:33
4	*.chello.nl	77	5	8.3 KB	2	0	0	24:37:58
5	*.dhcp.mdsn.wi.charter.com	24	5	6.1 KB	5	5	5	05:32:52
6	*.173.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	01:43:42
7	*.bbox.io	9	0	0.0 KB	0	0	0	03:57:27
TOTAL: 7		830	52	137.0 KB	34	5	5	40:08:52

NNRP auth users [Top 20]:

User	Conn
moussa	62
ohlandl	5
ctomczyk	3
TOTAL: 3	70

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	5.999	1.336	0.000	24:37:58
68-185-181-64.dhcp.mdsn.wi.charter.com	2.096	0.546	0.019	05:32:52
tempe0.bbox.io	0.835	0.160	0.000	03:57:27
h71.173.22.98.dynamic.ip.windstream.net	0.090	0.114	0.000	01:43:42
093105191108.choszczno.vectranet.pl	0.489	0.106	0.009	01:30:28
mtrlpq2314w-lp140-04-70-53-246-3.dsl.bell.ca	53.519	8.737	0.000	01:25:49
144.138.90.254	4.798	1.229	0.221	01:20:33
TOTAL: 7	67.826	12.228	0.249	40:08:52

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	9
h71.173.22.98.dynamic.ip.windstream.net	1
TOTAL: 2	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	7	0
68-185-181-64.dhcp.mdsn.wi.charter.com	6	0
093105191108.choszczno.vectranet.pl	3	0
144.138.90.254	1	0
h71.173.22.98.dynamic.ip.windstream.net	1	0
TOTAL: 5	18	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	24	46.2%
2	alt	11	21.2%
3	talk	8	15.4%
4	rec	2	3.8%
5	soc	2	3.8%
6	sci	2	3.8%
7	misc	2	3.8%
8	uk	1	1.9%
TOTAL: 8		52	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	10
2	comp.lang.javascript	9
3	talk.politics.guns	7
4	alt.atheism	6
5	comp.arch	3
6	alt.society.liberalism	2
7	rec.arts.tv	2
8	sci.military.naval	2
9	comp.os.vms	2
10	soc.culture.usa	2
11	alt.california	2
12	misc.survivalism	2
13	talk.politics.misc	1
14	alt.home.repair	1
15	uk.legal	1
TOTAL: 15		52

Daily Usenet report for csiph.com

Apr 15 04:15:00 -- Apr 16 04:15:00