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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Apr 26 05:36:14 csiph nnrpd[91385]: 91.187.33.87 rejected Expiring process 90255

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22035	50.4%	5.7 MB	65.8%
innd	17263	39.5%	2.2 MB	25.7%
innfeed	3666	8.4%	677.4 KB	7.6%
nnrpd	751	1.7%	81.1 KB	0.9%
nocem	17	0.0%	1.9 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	43734	100.0%	8.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	336449	56.3%
Negative hits	239597	40.1%
Do not exist	21352	3.6%
Cache misses	291	0.0%
TOTAL: 4	597689	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.267	0.0%	23	0.000	11.609	26.833
article cleanup	00:00:00.103	0.0%	21032	0.000	0.005	0.029
article logging	00:00:00.314	0.0%	21614	0.000	0.015	0.348
article parse	00:00:01.539	0.0%	61546	0.000	0.025	0.196
article write	00:00:12.848	0.0%	17953	0.144	0.716	11.043
artlog/artcncl	00:00:00.000	0.0%	23	0.000	0.000	0.000
artlog/artparse	00:00:00.001	0.0%	398	0.000	0.003	0.020
data move	00:00:00.186	0.0%	546692	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.064	0.0%	23	0.000	2.783	18.000
hishave/artcncl	00:00:00.005	0.0%	23	0.000	0.217	0.500
hishave/artparse	00:00:00.001	0.0%	395	0.000	0.003	0.050
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:40.174	0.0%	598671	0.002	0.067	0.376
history sync	00:00:01.156	0.0%	369	0.000	3.133	19.333
history write	00:00:55.363	0.1%	21005	0.042	2.636	11.725
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.003	0.0%	395	0.000	2.539	16.833
idle	23:57:29.867	99.8%	552430	88.867	156.128	405.293
nntp read	00:00:02.050	0.0%	556170	0.002	0.004	0.008
overview write	00:00:32.390	0.0%	17953	0.144	1.804	20.200
perl filter	00:00:27.572	0.0%	20982	0.997	1.314	2.279
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.278	0.0%	35922	0.000	0.008	0.037
TOTAL: 24:01:01.846	24:00:25.181	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:05.707	0.0%	9074210	0.000	0.001	0.018
article prepare	00:00:00.000	0.0%	7972	0.000	0.000	0.000
article read	00:00:00.247	0.0%	1167	0.000	0.212	42.000
backlog stats	00:00:01.446	0.0%	4354723	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:09.537	0.0%	2697423	0.001	0.004	0.010
data write	00:00:08.826	0.0%	2642557	0.000	0.003	0.012
idle	23:49:45.683	99.9%	4354723	10.620	19.699	562.322
status file	00:00:00.244	0.0%	313	0.000	0.780	4.750
TOTAL: 23:51:14.905	23:50:11.690	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	33:50:34.466	99.8%	464	211.000	262574.280	600276.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.033	0.0%	1109	0.000	0.931	23.250
readart	00:00:00.467	0.0%	43	0.000	10.860	43.000
TOTAL: 33:55:19.678	33:50:35.966	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	3
logmode	2
lowmark	1
mode	139
pause	3
paused	3
reload	4
reserve	2
TOTAL: 10	164

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	17585	4166	13393	26	23%	24:37:04
2	news-out.aioe.org	3	17604	2978	14537	89	16%	24:09:42
3	feeder.eternal-september.org	3	21094	2771	18101	222	13%	27:01:58
4	csiph-out.news.xs4all.nl	862	19915	1550	17232	1133	7%	71:22:28
5	csiph-out.news.weretis.net	4	36741	1429	35089	223	3%	48:37:54
6	csiph-out.feeder.erje.net	1440	90452	1320	88776	356	1%	118:51:08
7	news-out.mixmin.net	3	18364	1053	16951	360	5%	22:08:13
8	newsfeed.fu-berlin.de	15	17877	858	16756	263	4%	26:32:55
9	newsfeed.neva.ru	143	17598	750	16606	242	4%	71:45:18
10	csiph-out.feed.uzoreto.com	864	28866	579	27749	538	2%	71:16:59
11	news.fcku.it	11	1833	228	1602	3	12%	22:30:49
12	csiph-out.news.tnetconsulting.net	1	17670	155	17475	40	0%	24:11:53
13	nntp.pasdenom.info	2	12402	124	12255	23	0%	23:59:36
14	news-out.neodome.net	2	19044	119	18916	9	0%	23:59:47
15	news.swapon.de	5	1687	48	1639	0	2%	23:43:17
16	news.bbs.nz	2	27193	23	27170	0	0%	34:42:34
17	news.samoylyk.net	2	17558	15	17542	1	0%	23:59:40
18	localhost	29	3	3	0	0	100%	00:00:04
TOTAL: 18		3393	383486	18169	361789	3528	4%	683:31:19

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	12.4 MB	0.0 KB	1.7 MB	14.1 MB	87%	3.4 KB
2	feeder.eternal-september.org	7.3 MB	4.3 KB	3.4 MB	10.7 MB	68%	3.7 KB
3	news-out.aioe.org	7.3 MB	1.0 KB	194.3 KB	7.5 MB	97%	2.5 KB
4	csiph-out.feeder.erje.net	5.9 MB	15.8 KB	5.4 MB	11.4 MB	52%	6.9 KB
5	csiph-out.news.xs4all.nl	4.0 MB	0.0 KB	11.5 MB	15.5 MB	25%	5.9 KB
6	csiph-out.news.weretis.net	3.8 MB	5.3 KB	1.7 MB	5.5 MB	69%	3.4 KB
7	news-out.mixmin.net	2.8 MB	1.0 KB	626.8 KB	3.4 MB	82%	2.5 KB
8	newsfeed.neva.ru	2.0 MB	6.2 KB	617.0 KB	2.7 MB	77%	2.7 KB
9	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	565.0 KB	2.5 MB	77%	2.3 KB
10	csiph-out.feed.uzoreto.com	1.5 MB	2.4 KB	8.9 MB	10.4 MB	14%	9.6 KB
11	csiph-out.news.tnetconsulting.net	533.6 KB	2.0 KB	229.6 KB	765.1 KB	69%	3.9 KB
12	news.fcku.it	397.9 KB	1.0 KB	3.1 KB	402.0 KB	98%	1.7 KB
13	news-out.neodome.net	354.8 KB	2.3 KB	200.8 KB	558.0 KB	63%	4.4 KB
14	nntp.pasdenom.info	275.8 KB	0.0 KB	31.1 KB	306.9 KB	89%	2.1 KB
15	news.swapon.de	115.4 KB	0.0 KB	0.0 KB	115.4 KB	100%	2.4 KB
16	news.samoylyk.net	45.9 KB	0.0 KB	2.2 KB	48.1 KB	95%	3.0 KB
17	news.bbs.nz	31.8 KB	0.0 KB	0.0 KB	31.8 KB	100%	1.4 KB
18	localhost	4.3 KB	0.0 KB	0.0 KB	4.3 KB	100%	1.4 KB
TOTAL: 18		50.8 MB	41.3 KB	35.0 MB	85.9 MB	59%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 26 04:15:00 - 04:59:59	267	1.5%	0.10	909.6 KB	1.8%	0.34
Apr 26 05:00:00 - 05:59:59	349	1.9%	0.10	1.1 MB	2.2%	0.31
Apr 26 06:00:00 - 06:59:59	475	2.6%	0.13	1.4 MB	2.8%	0.40
Apr 26 07:00:00 - 07:59:59	607	3.4%	0.17	2.0 MB	3.9%	0.56
Apr 26 08:00:00 - 08:59:59	843	4.7%	0.23	2.3 MB	4.6%	0.66
Apr 26 09:00:00 - 09:59:59	828	4.6%	0.23	2.1 MB	4.1%	0.59
Apr 26 10:00:00 - 10:59:59	819	4.6%	0.23	2.1 MB	4.2%	0.60
Apr 26 11:00:00 - 11:59:59	986	5.5%	0.27	2.7 MB	5.4%	0.77
Apr 26 12:00:00 - 12:59:59	1038	5.8%	0.29	2.7 MB	5.3%	0.76
Apr 26 13:00:00 - 13:59:59	1046	5.8%	0.29	2.7 MB	5.3%	0.76
Apr 26 14:00:00 - 14:59:59	918	5.1%	0.26	2.5 MB	5.0%	0.71
Apr 26 15:00:00 - 15:59:59	995	5.5%	0.28	2.9 MB	5.8%	0.82
Apr 26 16:00:00 - 16:59:59	1013	5.6%	0.28	2.7 MB	5.4%	0.77
Apr 26 17:00:00 - 17:59:59	974	5.4%	0.27	2.6 MB	5.2%	0.74
Apr 26 18:00:00 - 18:59:59	1051	5.9%	0.29	3.0 MB	5.9%	0.85
Apr 26 19:00:00 - 19:59:59	931	5.2%	0.26	2.6 MB	5.2%	0.74
Apr 26 20:00:00 - 20:59:59	918	5.1%	0.26	2.5 MB	5.0%	0.72
Apr 26 21:00:00 - 21:59:59	887	4.9%	0.25	2.5 MB	4.9%	0.70
Apr 26 22:00:00 - 22:59:59	711	4.0%	0.20	2.0 MB	3.9%	0.56
Apr 26 23:00:00 - 23:59:59	486	2.7%	0.14	1.5 MB	2.9%	0.42
Apr 27 00:00:00 - 00:59:59	481	2.7%	0.13	1.5 MB	3.1%	0.44
Apr 27 01:00:00 - 01:59:59	408	2.3%	0.11	1.1 MB	2.2%	0.31
Apr 27 02:00:00 - 02:59:59	382	2.1%	0.11	1.2 MB	2.4%	0.34
Apr 27 03:00:00 - 03:59:59	443	2.5%	0.12	1.4 MB	2.7%	0.39
Apr 27 04:00:00 - 04:15:00	97	0.5%	0.11	356.3 KB	0.7%	0.40
TOTAL: 24:00:00	17953	100.0%	0.21	50.2 MB	100.0%	0.59

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Other
1	csiph-out.news.xs4all.nl	1161	46	0	0	1115
2	newsfeed.fu-berlin.de	672	47	0	0	625
3	csiph-out.feed.uzoreto.com	560	11	0	2	547
4	news-out.mixmin.net	373	0	0	1	372
5	csiph-out.feeder.erje.net	365	63	1	11	290
6	newsfeed.neva.ru	253	32	0	5	216
7	csiph-out.news.weretis.net	250	56	0	4	190
8	feeder.eternal-september.org	206	21	0	3	182
9	news-out.aioe.org	145	28	0	1	116
10	newsfeeds.xmission.com	27	2	0	0	25
11	nntp.pasdenom.info	23	0	0	0	23
12	news-out.neodome.net	9	0	0	2	7
13	news.swapon.de	8	0	0	0	8
14	news.fcku.it	4	2	0	1	1
15	csiph-out.news.tnetconsulting.net	3	2	0	0	1
16	news.samoylyk.net	1	0	0	0	1
TOTAL: 16		4060	310	1	30	3719

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.bbs.synchronet	41
spline.eisfair	33
alt.survival	26
it-alt.sport.calcio.juventus	22
lada.talk	21
alt.computer.workshop	15
alt.binaries.pictures.purebeauty	13
a.b.boneless	10
alt.windows7.general	7
spline.fli4l.dev	7
mozilla.support.thunderbird	6
oesterreich.tratsch	5
alt.politics	5
a.b.x	4
alt.philosophy	4
alt.binaries.sounds.radio.british.highspeed	4
free.it.religioni.scientology	4
alt.politics.usa	3
free.virginmedia.binaries.test	2
mozilla.dev.platform	2
TOTAL: 81	310

Unwanted distributions [Top 20]:

Distribution	Count
local	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1866
EMP (phn path)	390
EMP (phl)	227
0	96
EMP (phn nph)	46
Too many newsgroups	41
Binary Payload	17
Scoring filter	14
HTML Multipart	11
Binary: misplaced binary	8
U2 violation - invalid distribution	1
TOTAL: 11	2717

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.news.xs4all.nl	138
	csiph-out.feed.uzoreto.com	106
	csiph-out.feeder.erje.net	91
	newsfeeds.xmission.com	24
	csiph-out.news.weretis.net	18
	feeder.eternal-september.org	15
	news-out.neodome.net	3
	csiph-out.news.tnetconsulting.net	1
	news-out.mixmin.net	1
	TOTAL: 9	397
Including strange strings
	newsfeed.fu-berlin.de	414
	news-out.aioe.org	59
	csiph-out.news.xs4all.nl	28
	csiph-out.news.weretis.net	22
	csiph-out.feed.uzoreto.com	20
	news-out.mixmin.net	12
	csiph-out.feeder.erje.net	9
	newsfeed.neva.ru	9
	news.swapon.de	8
	newsfeeds.xmission.com	1
	TOTAL: 10	582
No colon-space in header
	csiph-out.feeder.erje.net	3
	TOTAL: 1	3
TOTAL: 4		1027

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.nz	33878	463	17050	4	267	1%	23:59:48
2	fu-berlin.de	16940	400	16540	0	0	2%	23:59:48
3	news.samoylyk.net	18636	370	17555	11	89	1%	23:59:48
4	erje	18887	125	16226	97	0	0%	23:59:49
5	news.freedyn.net	18398	96	17854	2	1	0%	23:59:48
6	newsfeed.xs4all.nl	16912	58	16210	10	155	0%	23:59:48
7	neva.ru	16740	52	16466	90	1	0%	23:59:48
8	aioe	15009	40	13719	9	1	0%	23:59:41
9	usenet.pasdenom.info	18234	27	17384	4	1	0%	23:59:48
10	news.mixmin.net	16752	27	16038	2	1	0%	23:59:48
11	tnet	10370019	27	2682	0	9095521	0%	23:59:49
12	neodome.net	18394	24	17724	0	1	0%	23:59:48
13	xmission	14824	18	13366	1	0	0%	23:59:16
14	news.uzoreto.com	16881	6	16524	19	1	0%	23:59:48
15	weretis.net	16245	6	15968	3	0	0%	23:59:49
16	eternal-september	15400	4	14709	0	1	0%	23:59:46
17	news.swapon.de	1679	3	1654	0	0	0%	23:44:43
18	news.fcku.it	1534	0	1524	0	0	0%	22:10:51
19	xanadu-bbs	0	0	0	0	17953	0%	23:59:24
TOTAL: 19		10645362	1746	249193	252	9113993	0%	453:51:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	fu-berlin.de	3.3 MB	0.0 KB	3.3 MB	8.4 KB	23:59:48
2	news.samoylyk.net	3.2 MB	105.4 KB	3.3 MB	8.8 KB	23:59:48
3	news.bbs.nz	1.2 MB	19.1 KB	1.3 MB	2.7 KB	23:59:48
4	news.freedyn.net	825.5 KB	3.8 KB	829.3 KB	8.5 KB	23:59:48
5	erje	241.2 KB	506.0 KB	747.2 KB	3.4 KB	23:59:49
6	newsfeed.xs4all.nl	149.0 KB	63.8 KB	212.7 KB	3.1 KB	23:59:48
7	neva.ru	113.3 KB	212.5 KB	325.8 KB	2.3 KB	23:59:48
8	aioe	99.6 KB	16.2 KB	115.8 KB	2.4 KB	23:59:41
9	usenet.pasdenom.info	71.8 KB	8.2 KB	80.0 KB	2.6 KB	23:59:48
10	tnet	44.4 KB	0.0 KB	44.4 KB	1.6 KB	23:59:49
11	news.mixmin.net	42.5 KB	13.1 KB	55.6 KB	1.9 KB	23:59:48
12	neodome.net	40.1 KB	0.0 KB	40.1 KB	1.7 KB	23:59:48
13	xmission	27.4 KB	1.1 KB	28.6 KB	1.5 KB	23:59:16
14	news.uzoreto.com	11.4 KB	74.1 KB	85.4 KB	3.4 KB	23:59:48
15	weretis.net	9.2 KB	3.3 KB	12.5 KB	1.4 KB	23:59:49
16	eternal-september	6.1 KB	0.0 KB	6.1 KB	1.5 KB	23:59:46
17	news.swapon.de	4.3 KB	0.0 KB	4.3 KB	1.4 KB	23:44:43
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0	22:10:51
19	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0	23:59:24
TOTAL: 19		9.3 MB	1.0 MB	10.3 MB	5.3 KB	453:51:08

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-84-163-204.home.otenet.gr	9	19	35.6 KB	3	0	0	00:00:19
2	a131033.upc-a.chello.nl	23	15	28.5 KB	6	0	0	04:34:24
3	096-042-018-055.res.spectrum.com	15	7	13.1 KB	6	3	3	05:15:40
4	p3ee2318a.dip0.t-ipconnect.de	1	2	2.3 KB	1	0	0	00:00:07
TOTAL: 4		48	43	79.6 KB	16	3	3	33:55:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	9	19	35.6 KB	3	0	0	00:00:19
2	*.upc-a.chello.nl	23	15	28.5 KB	6	0	0	04:34:24
3	*.res.spectrum.com	15	7	13.1 KB	6	3	3	05:15:40
4	*.dip0.t-ipconnect.de	1	2	2.3 KB	1	0	0	00:00:07
5	*.hsd1.wa.comcast.net	2	0	0.0 KB	0	0	0	01:00:01
6	unresolved	74	0	0.0 KB	0	0	0	23:04:44
TOTAL: 6		124	43	79.6 KB	16	3	3	33:55:18

NNRP auth users [Top 20]:

User	Conn
ohlandl	3
TOTAL: 1	3

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.801	1.073	0.000	23:04:40
096-042-018-055.res.spectrum.com	1.161	0.315	0.007	05:15:40
a131033.upc-a.chello.nl	1.875	0.335	0.000	04:34:24
c-73-140-175-55.hsd1.wa.comcast.net	0.088	0.063	0.000	01:00:01
ppp-2-84-163-204.home.otenet.gr	0.679	0.134	0.000	00:00:19
p3ee2318a.dip0.t-ipconnect.de	0.124	0.049	0.000	00:00:07
5.87.223.252	0.058	0.022	0.000	00:00:01
94.161.78.230	0.063	0.016	0.000	00:00:01
94.165.27.161	0.061	0.015	0.000	00:00:01
TOTAL: 9	8.910	2.022	0.007	33:55:18

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	71
c-73-140-175-55.hsd1.wa.comcast.net	2
5.87.223.252	1
94.165.27.161	1
94.161.78.230	1
TOTAL: 5	76

NNRP client timeouts [Top 20]:

System	Conn	Peer
096-042-018-055.res.spectrum.com	6	0
c-73-140-175-55.hsd1.wa.comcast.net	2	0
TOTAL: 2	8	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	41	95.3%
2	alt	2	4.7%
TOTAL: 2		43	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	41
2	alt.free.newsservers	2
TOTAL: 2		43

Daily Usenet report for csiph.com

Apr 26 04:15:00 -- Apr 27 04:15:00