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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	143543	73.7%	14.3 MB	60.4%
inn	32713	16.8%	7.1 MB	29.9%
innd	15188	7.8%	1.8 MB	7.5%
innfeed	3252	1.7%	517.0 KB	2.1%
nocem	77	0.0%	8.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	194774	100.0%	23.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	396922	56.3%
Negative hits	275686	39.1%
Do not exist	31859	4.5%
Cache misses	430	0.1%
TOTAL: 4	704897	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.129	0.0%	249	0.000	4.534	46.000
article cleanup	00:00:00.298	0.0%	31622	0.000	0.009	0.030
article logging	00:00:04.243	0.0%	32130	0.000	0.132	2.090
article parse	00:00:04.863	0.0%	95697	0.003	0.051	0.468
article write	00:03:24.706	0.2%	23579	0.191	8.682	54.895
artlog/artcncl	00:00:00.025	0.0%	174	0.000	0.144	14.000
artlog/artparse	00:00:00.171	0.0%	432	0.000	0.396	17.000
data move	00:00:00.539	0.0%	659419	0.000	0.001	0.037
hisgrep/artcncl	00:00:00.472	0.0%	246	0.000	1.919	18.000
hishave/artcncl	00:00:00.142	0.0%	249	0.000	0.570	3.583
hishave/artparse	00:00:00.002	0.0%	430	0.000	0.005	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:26.733	0.2%	707246	0.001	0.207	1.066
history sync	00:00:02.740	0.0%	352	0.000	7.784	133.000
history write	00:03:09.040	0.2%	31576	0.863	5.987	42.238
hiswrite/artcncl	00:00:00.014	0.0%	3	0.000	4.667	13.000
hiswrite/artparse	00:00:03.434	0.0%	430	0.000	7.986	108.000
idle	23:43:29.645	98.8%	642100	78.505	133.016	362.732
nntp read	00:00:03.347	0.0%	669187	0.002	0.005	0.027
overview write	00:05:21.466	0.4%	23579	1.401	13.634	161.094
perl filter	00:01:15.019	0.1%	31572	1.456	2.376	17.606
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.444	0.0%	47230	0.000	0.009	0.030
TOTAL: 24:00:50.525	23:59:28.472	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.085	0.0%	24505	0.000	0.003	0.025
article prepare	00:00:00.005	0.0%	27384	0.000	0.000	0.006
article read	00:00:01.955	0.0%	4561	0.000	0.429	44.333
backlog stats	00:00:00.223	0.0%	401577	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.285	0.0%	321075	0.000	0.007	0.013
data write	00:00:01.817	0.0%	307630	0.002	0.006	0.012
idle	23:53:28.145	100.0%	401577	118.291	214.176	578.319
status file	00:00:00.527	0.0%	306	0.000	1.722	21.000
TOTAL: 23:53:45.331	23:53:35.042	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:12.429	0.0%	2305	3.056	5.392	8.809
idle	26:59:43.572	99.7%	182343	145.045	532.971	600081.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:07.015	0.0%	402803	0.000	0.017	0.138
readart	00:02:02.727	0.1%	25374	0.000	4.837	68.000
TOTAL: 27:03:52.742	27:02:05.743	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.feeder.erje.net	1440	128252	5249	117931	5072	4%	118:43:02
2	csiph-out.news.weretis.net	4	44932	4745	39641	546	10%	43:39:36
3	feeder.eternal-september.org	10	29459	3497	24806	1156	11%	25:46:38
4	news-out.mixmin.net	3	24220	2060	21823	337	8%	24:11:53
5	newsfeeds.xmission.com	3	23558	1803	21749	6	7%	26:13:15
6	news-out.aioe.org	6	21694	1629	20029	36	7%	22:09:59
7	newsfeed.neva.ru	150	29898	1463	27654	781	4%	71:51:24
8	newsfeed.fu-berlin.de	51	21172	959	19966	247	4%	30:03:35
9	news.redatomik.org	1	21833	946	20883	4	4%	23:35:11
10	news.fcku.it	2	10197	452	9667	78	4%	23:59:14
11	news.freedyn.net	3	25043	327	24569	147	1%	24:09:37
12	xanadu-bbs.net	7	789	192	595	2	24%	22:38:49
13	news.swapon.de	2	1931	59	1867	5	3%	27:42:45
14	nntp.pasdenom.info	3	22170	47	22121	2	0%	24:08:29
15	localhost	37	5	5	0	0	100%	00:00:01
16	news.bbs.geek.nz	2	19539	2	19536	1	0%	23:59:36
TOTAL: 16		1724	424692	23435	392837	8420	5%	532:53:04

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	22.4 MB	187.3 KB	30.0 MB	52.5 MB	42%	5.2 KB
2	csiph-out.news.weretis.net	13.5 MB	2.5 KB	4.8 MB	18.3 MB	73%	3.6 KB
3	feeder.eternal-september.org	10.6 MB	152.8 KB	25.8 MB	36.6 MB	29%	8.0 KB
4	news-out.mixmin.net	8.4 MB	8.1 KB	1.4 MB	9.8 MB	86%	4.2 KB
5	newsfeeds.xmission.com	5.0 MB	0.0 KB	244.4 KB	5.2 MB	95%	3.0 KB
6	news-out.aioe.org	4.1 MB	22.6 KB	72.0 KB	4.2 MB	97%	2.6 KB
7	newsfeed.neva.ru	4.1 MB	33.1 KB	3.2 MB	7.3 MB	55%	3.4 KB
8	news.redatomik.org	2.9 MB	1.5 KB	88.6 KB	3.0 MB	97%	3.2 KB
9	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	539.1 KB	2.8 MB	81%	2.4 KB
10	news.freedyn.net	1014.1 KB	0.0 KB	417.7 KB	1.4 MB	70%	3.0 KB
11	news.fcku.it	922.9 KB	1.4 KB	701.8 KB	1.6 MB	56%	3.1 KB
12	xanadu-bbs.net	510.8 KB	0.0 KB	49.0 KB	559.9 KB	91%	2.9 KB
13	news.swapon.de	160.2 KB	0.0 KB	1.9 MB	2.1 MB	7%	33.3 KB
14	nntp.pasdenom.info	99.4 KB	0.0 KB	3.8 KB	103.2 KB	96%	2.1 KB
15	localhost	25.5 KB	0.0 KB	0.0 KB	25.5 KB	100%	5.1 KB
16	news.bbs.geek.nz	1.9 KB	1.4 KB	1.4 KB	4.7 KB	39%	1.6 KB
TOTAL: 16		76.0 MB	410.5 KB	69.2 MB	145.6 MB	52%	4.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 18 04:15:00 - 04:59:59	518	2.2%	0.19	2.8 MB	3.7%	1.07
May 18 05:00:00 - 05:59:59	615	2.6%	0.17	2.5 MB	3.3%	0.71
May 18 06:00:00 - 06:59:59	678	2.9%	0.19	1.9 MB	2.5%	0.54
May 18 07:00:00 - 07:59:59	950	4.0%	0.26	2.6 MB	3.5%	0.75
May 18 08:00:00 - 08:59:59	966	4.1%	0.27	2.7 MB	3.6%	0.78
May 18 09:00:00 - 09:59:59	970	4.1%	0.27	2.5 MB	3.3%	0.71
May 18 10:00:00 - 10:59:59	987	4.2%	0.27	3.0 MB	3.9%	0.85
May 18 11:00:00 - 11:59:59	1335	5.7%	0.37	4.4 MB	5.7%	1.24
May 18 12:00:00 - 12:59:59	1088	4.6%	0.30	2.8 MB	3.7%	0.79
May 18 13:00:00 - 13:59:59	1234	5.2%	0.34	3.5 MB	4.5%	0.99
May 18 14:00:00 - 14:59:59	1392	5.9%	0.39	4.7 MB	6.1%	1.33
May 18 15:00:00 - 15:59:59	1509	6.4%	0.42	4.9 MB	6.4%	1.40
May 18 16:00:00 - 16:59:59	1310	5.6%	0.36	3.9 MB	5.1%	1.11
May 18 17:00:00 - 17:59:59	1443	6.1%	0.40	4.6 MB	6.0%	1.31
May 18 18:00:00 - 18:59:59	1251	5.3%	0.35	3.8 MB	4.9%	1.07
May 18 19:00:00 - 19:59:59	1219	5.2%	0.34	3.6 MB	4.7%	1.01
May 18 20:00:00 - 20:59:59	1111	4.7%	0.31	3.0 MB	3.9%	0.85
May 18 21:00:00 - 21:59:59	1012	4.3%	0.28	3.0 MB	3.9%	0.85
May 18 22:00:00 - 22:59:59	758	3.2%	0.21	2.6 MB	3.4%	0.74
May 18 23:00:00 - 23:59:59	645	2.7%	0.18	2.0 MB	2.7%	0.58
May 19 00:00:00 - 00:59:59	524	2.2%	0.15	1.8 MB	2.3%	0.50
May 19 01:00:00 - 01:59:59	757	3.2%	0.21	3.5 MB	4.5%	0.98
May 19 02:00:00 - 02:59:59	704	3.0%	0.20	4.4 MB	5.7%	1.24
May 19 03:00:00 - 03:59:59	447	1.9%	0.12	1.5 MB	2.0%	0.43
May 19 04:00:00 - 04:15:00	125	0.5%	0.14	417.6 KB	0.5%	0.46
TOTAL: 24:00:00	23548	100.0%	0.27	76.3 MB	100.0%	0.90

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	5108	229	21	4858
2	feeder.eternal-september.org	1151	54	12	1085
3	newsfeed.neva.ru	792	171	5	616
4	csiph-out.news.weretis.net	622	189	1	432
5	newsfeed.fu-berlin.de	621	16	0	605
6	news-out.mixmin.net	381	3	3	375
7	news.freedyn.net	147	21	0	126
8	news.fcku.it	87	9	0	78
9	news-out.aioe.org	40	27	5	8
10	xanadu-bbs.net	13	0	0	13
11	news.redatomik.org	10	0	0	10
12	newsfeeds.xmission.com	9	1	0	8
13	news.swapon.de	8	0	0	8
14	nntp.pasdenom.info	2	0	0	2
15	news.bbs.geek.nz	1	0	1	0
TOTAL: 15		8992	720	48	8224

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	101
it-alt.sport.calcio.juventus	97
lada.talk	57
mpc.lists.freebsd.ports.bugs	44
lada.auto	32
alt.windows7.general	28
gmane.linux.alsa.devel	24
it.annunci.immobiliari	23
it.annunci.varie	22
mozilla.support.seamonkey	19
it.annunci.commerciali	18
mozilla.dev.platform	14
lada.commerce	13
alt.computer.workshop	12
mozilla.support.thunderbird	11
cornell.marketplace	9
oesterreich.tratsch	9
mozilla.dev.l10n	8
eagle.support.ger	8
alt.survival	8
TOTAL: 103	720

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5822
EMP (phn path)	870
EMP (phl)	192
Scoring filter (10)	64
Scoring filter (8)	51
Too many newsgroups	50
Scoring filter (12)	35
EMP (phn nph)	31
MID-Bot	30
Scoring filter (11)	26
Binary Payload	24
HTML Multipart	17
User-issued cancel	16
Scoring filter (14)	12
Scoring filter (13)	11
Scoring filter (9)	8
Scoring filter (15)	7
Scoring filter (16)	6
EMP (fsl)	3
User-issued spam cancel	3
TOTAL: 26	7287

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.feeder.erje.net	234
	feeder.eternal-september.org	119
	csiph-out.news.weretis.net	40
	news-out.mixmin.net	16
	news.fcku.it	6
	news.swapon.de	5
	newsfeeds.xmission.com	4
	news.freedyn.net	1
	xanadu-bbs.net	1
	news.redatomik.org	1
	TOTAL: 10	427
Including strange strings
	newsfeed.fu-berlin.de	357
	csiph-out.news.weretis.net	50
	news-out.mixmin.net	41
	csiph-out.feeder.erje.net	31
	newsfeed.neva.ru	10
	news.redatomik.org	7
	news-out.aioe.org	4
	news.swapon.de	3
	newsfeeds.xmission.com	2
	feeder.eternal-september.org	1
	TOTAL: 10	506
No colon-space in header
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
TOTAL: 3		935

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	41677	2599	20198	88	0	6%	23:59:45
2	news.freedyn.net	24334	815	22363	37	0	3%	23:59:34
3	xanadu-bbs	24240	687	22655	0	0	2%	23:59:54
4	fu-berlin.de	22333	650	21683	0	0	2%	23:59:54
5	aioe	20718	90	19888	8	390	0%	23:58:32
6	usenet.pasdenom.info	24748	89	21729	1681	8	0%	23:59:54
7	news.redatomik.org	22262	26	21842	3	0	0%	23:59:34
8	erje	18268	14	18147	9	0	0%	23:59:54
9	weretis.net	16874	12	16750	0	0	0%	23:58:10
10	xmission	22774	10	20970	5	0	0%	23:59:54
11	news.swapon.de	1833	8	1806	0	0	0%	23:52:51
12	eternal-september	19748	7	19116	0	0	0%	23:59:47
13	news.mixmin.net	20974	6	20592	0	0	0%	23:59:54
14	neva.ru	21437	4	21051	9	0	0%	23:59:40
15	news.fcku.it	2227	0	2217	0	0	0%	22:40:23
16	bwh	0	0	0	0	22630	0%	23:58:42
17	au2pb	0	0	0	0	22890	0%	23:58:42
TOTAL: 17		304447	5017	271007	1840	45918	1%	406:25:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.0 MB	311.1 KB	9.3 MB	0.1 KB/s	11.2 KB	23:59:34
2	xanadu-bbs	8.3 MB	0.0 KB	8.3 MB	0.1 KB/s	12.3 KB	23:59:54
3	fu-berlin.de	8.2 MB	0.0 KB	8.2 MB	0.1 KB/s	12.9 KB	23:59:54
4	news.bbs.geek.nz	6.8 MB	495.2 KB	7.3 MB	0.1 KB/s	2.8 KB	23:59:45
5	usenet.pasdenom.info	455.3 KB	9.9 MB	10.4 MB	0.1 KB/s	6.0 KB	23:59:54
6	aioe	210.9 KB	67.7 KB	278.6 KB	0.0 KB/s	2.8 KB	23:58:32
7	news.redatomik.org	138.5 KB	40.1 KB	178.7 KB	0.0 KB/s	6.2 KB	23:59:34
8	erje	76.5 KB	76.3 KB	152.8 KB	0.0 KB/s	6.6 KB	23:59:54
9	weretis.net	42.3 KB	0.0 KB	42.3 KB	0.0 KB/s	3.5 KB	23:58:10
10	xmission	38.9 KB	7.8 KB	46.7 KB	0.0 KB/s	3.1 KB	23:59:54
11	eternal-september	34.7 KB	0.0 KB	34.7 KB	0.0 KB/s	5.0 KB	23:59:47
12	news.swapon.de	31.9 KB	0.0 KB	31.9 KB	0.0 KB/s	4.0 KB	23:52:51
13	news.mixmin.net	28.5 KB	0.0 KB	28.5 KB	0.0 KB/s	4.8 KB	23:59:54
14	neva.ru	23.4 KB	23.9 KB	47.3 KB	0.0 KB/s	3.6 KB	23:59:40
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:40:23
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		33.3 MB	10.9 MB	44.2 MB	0.0 KB/s	6.6 KB	406:25:04

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	151	927	1.4 MB	14	0	0	07:31:14
2	144.138.90.254	287	49	186.6 KB	48	0	0	03:37:52
3	tempe0.bbox.io	9	11	53.6 KB	5	1	1	03:15:22
4	204-73-239-109.cust.centrio.cz	12	8	15.7 KB	7	0	0	04:01:00
5	96-42-16-100.dhcp.mdsn.wi.charter.com	21	5	25.5 KB	5	4	4	05:26:23
TOTAL: 5		480	1000	1.6 MB	79	5	5	27:03:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	151	927	1.4 MB	14	0	0	07:31:14
2	unresolved	287	49	186.6 KB	48	0	0	03:37:52
3	*.bbox.io	9	11	53.6 KB	5	1	1	03:15:22
4	*.cust.centrio.cz	12	8	15.7 KB	7	0	0	04:01:00
5	*.dhcp.mdsn.wi.charter.com	21	5	25.5 KB	5	4	4	05:26:23
6	*.156.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	03:11:54
TOTAL: 6		481	1000	1.6 MB	79	5	5	27:03:48

NNRP auth users [Top 20]:

User	Conn
moussa	287
ctomczyk	8
ohlandl	4
kev009	1
TOTAL: 4	300

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	21.326	10.394	0.000	07:31:14
96-42-16-100.dhcp.mdsn.wi.charter.com	1.839	0.304	0.014	05:26:23
204-73-239-109.cust.centrio.cz	0.998	0.236	0.028	04:01:00
144.138.90.254	23.604	5.540	1.088	03:37:52
tempe0.bbox.io	1.080	0.136	0.003	03:15:22
h215.156.22.98.dynamic.ip.windstream.net	0.234	0.250	0.000	03:11:54
TOTAL: 6	49.081	16.860	1.133	27:03:48

Curious NNRP server explorers [Top 20]:

System	Conn
h215.156.22.98.dynamic.ip.windstream.net	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
204-73-239-109.cust.centrio.cz	8	0
tempe0.bbox.io	5	0
96-42-16-100.dhcp.mdsn.wi.charter.com	4	0
h215.156.22.98.dynamic.ip.windstream.net	2	0
TOTAL: 4	19	0

Newsgroup request counts (by category):

Category		Count	Pct
1	free	863	86.3%
2	comp	69	6.9%
3	alt	53	5.3%
4	uk	11	1.1%
5	news	4	0.4%
TOTAL: 5		1000	100.0%

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

Newsgroup		Count
1	free.usenet	863
2	alt.drugs	37
3	comp.sys.ibm.ps2.hardware	17
4	comp.arch.fpga	15
5	uk.rec.drugs.cannabis	11
6	alt.drugs.hard	11
7	comp.lang.javascript	8
8	comp.arch.embedded	6
9	comp.ai.philosophy	6
10	alt.support.chronic-pain	5
11	comp.os.cpm	4
12	comp.arch	4
13	news.software.nntp	4
14	comp.sys.ibm.pc.hardware.chips	2
15	comp.ai.games	2
16	comp.windows.x	1
17	comp.os.vms	1
18	comp.ai.neural-nets	1
19	comp.ai.alife	1
20	comp.sys.ibm.pc.games.war-historical	1
TOTAL: 20		1000

Daily Usenet report for csiph.com

May 18 04:15:00 -- May 19 04:15:00