Daily Usenet report for csiph.com: Aug 27 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20072	48.7%	5.2 MB	65.0%
innd	17354	42.1%	2.2 MB	27.4%
innfeed	3344	8.1%	567.0 KB	7.0%
nnrpd	354	0.9%	37.8 KB	0.5%
nocem	65	0.2%	6.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41190	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	280166	57.6%
Negative hits	186181	38.3%
Do not exist	19368	4.0%
Cache misses	305	0.1%
TOTAL: 4	486020	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.040	0.0%	130	0.000	8.000	92.000
article cleanup	00:00:00.102	0.0%	18878	0.000	0.005	0.032
article logging	00:00:00.374	0.0%	19359	0.000	0.019	0.246
article parse	00:00:03.429	0.0%	70710	0.000	0.048	0.891
article write	00:04:12.544	0.3%	15896	0.342	15.887	51.496
artlog/artcncl	00:00:00.011	0.0%	100	0.000	0.110	1.833
artlog/artparse	00:00:00.021	0.0%	621	0.000	0.034	1.083
data move	00:00:00.121	0.0%	466994	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.050	0.0%	128	0.000	0.391	22.000
hishave/artcncl	00:00:00.083	0.0%	130	0.000	0.638	27.500
hishave/artparse	00:00:00.002	0.0%	615	0.000	0.003	0.059
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:55.689	0.1%	487853	0.002	0.114	0.541
history sync	00:00:01.094	0.0%	391	0.000	2.798	48.000
history write	00:00:58.273	0.1%	18870	0.286	3.088	20.591
hiswrite/artcncl	00:00:00.002	0.0%	2	1.000	1.000	1.000
hiswrite/artparse	00:00:02.740	0.0%	615	0.000	4.455	68.000
idle	23:49:52.521	99.2%	458819	107.897	186.986	866.752
nntp read	00:00:02.185	0.0%	475256	0.002	0.005	0.060
overview write	00:03:45.473	0.3%	15896	1.000	14.184	268.360
perl filter	00:00:34.389	0.0%	18861	1.404	1.823	5.964
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.291	0.0%	31851	0.000	0.009	0.030
TOTAL: 24:01:02.631	24:00:30.434	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.048	0.0%	16185	0.000	0.003	0.037
article prepare	00:00:00.001	0.0%	9860	0.000	0.000	0.005
article read	00:00:03.810	0.0%	860	0.000	4.430	126.250
backlog stats	00:00:00.188	0.0%	310707	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.274	0.0%	240879	0.000	0.005	0.012
data write	00:00:01.479	0.0%	227608	0.000	0.006	0.015
idle	23:55:10.319	100.0%	310707	174.066	277.143	1179.186
status file	00:00:01.390	0.0%	292	0.000	4.760	85.000
TOTAL: 23:55:25.105	23:55:18.509	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	11:56:39.712	99.9%	302	319.333	142383.152	600053.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	728	0.000	0.008	0.167
readart	00:00:00.446	0.0%	37	0.000	12.054	65.000
TOTAL: 11:57:06.187	11:56:40.164	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	32743	3221	29265	257	9%	48:33:09
2	csiph-out.feeder.erje.net	1440	78440	1810	76231	399	2%	119:00:31
3	feeder.eternal-september.org	6	16264	1790	14422	52	11%	21:26:40
4	newsfeeds.xmission.com	2	14549	1750	12791	8	12%	22:10:00
5	csiph-out.news.xs4all.nl	864	17507	1697	14845	965	9%	71:13:38
6	news-out.aioe.org	2	15523	1446	14046	31	9%	24:09:03
7	newsfeed.fu-berlin.de	10	16844	1018	15601	225	6%	28:17:25
8	news-out.mixmin.net	1	16756	907	15560	289	5%	23:59:48
9	news.freedyn.net	2	18763	838	17143	782	4%	24:09:02
10	newsfeed.neva.ru	147	18775	732	17637	406	3%	71:40:57
11	csiph-out.feed.uzoreto.com	863	24216	244	23797	175	1%	71:17:45
12	news.fcku.it	13	1865	240	1618	7	12%	21:58:59
13	nntp.pasdenom.info	8	11849	62	11773	14	0%	23:53:33
14	news.swapon.de	6	1245	23	1222	0	1%	22:29:05
15	news.bbs.nz	1	15886	8	15878	0	0%	23:59:53
16	localhost	35	2	2	0	0	100%	00:00:00
17	xanadu-bbs.net	20	521	1	520	0	0%	20:31:31
TOTAL: 17		3425	301748	15789	282349	3610	5%	638:50:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	10.3 MB	5.3 KB	6.5 MB	16.8 MB	61%	4.9 KB
2	csiph-out.feeder.erje.net	9.6 MB	23.4 KB	11.5 MB	21.2 MB	45%	9.8 KB
3	feeder.eternal-september.org	5.0 MB	2.7 KB	186.0 KB	5.2 MB	96%	2.9 KB
4	newsfeeds.xmission.com	4.9 MB	0.0 KB	366.9 KB	5.3 MB	93%	3.1 KB
5	csiph-out.news.xs4all.nl	4.2 MB	0.0 KB	7.4 MB	11.6 MB	36%	4.5 KB
6	news-out.aioe.org	3.4 MB	2.6 KB	74.3 KB	3.5 MB	97%	2.4 KB
7	news-out.mixmin.net	2.6 MB	2.6 KB	568.4 KB	3.2 MB	82%	2.7 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	585.8 KB	3.1 MB	81%	2.5 KB
9	news.freedyn.net	2.3 MB	2.7 KB	42.4 MB	44.7 MB	5%	28.2 KB
10	newsfeed.neva.ru	1.7 MB	5.2 KB	1.3 MB	3.0 MB	55%	2.7 KB
11	csiph-out.feed.uzoreto.com	706.5 KB	2.6 KB	2.7 MB	3.4 MB	20%	8.4 KB
12	news.fcku.it	450.0 KB	0.0 KB	7.1 KB	457.1 KB	98%	1.9 KB
13	nntp.pasdenom.info	144.1 KB	2.5 KB	23.2 KB	169.7 KB	84%	2.2 KB
14	news.swapon.de	55.5 KB	0.0 KB	0.0 KB	55.5 KB	100%	2.4 KB
15	news.bbs.nz	10.9 KB	0.0 KB	0.0 KB	10.9 KB	100%	1.4 KB
16	localhost	4.6 KB	0.0 KB	0.0 KB	4.6 KB	100%	2.3 KB
17	xanadu-bbs.net	3.0 KB	0.0 KB	0.0 KB	3.0 KB	100%	3.0 KB
TOTAL: 17		47.9 MB	49.7 KB	73.6 MB	121.5 MB	39%	6.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 27 04:15:00 - 04:59:59	213	1.3%	0.08	847.3 KB	1.7%	0.31
Aug 27 05:00:00 - 05:59:59	464	2.9%	0.13	1.9 MB	3.9%	0.53
Aug 27 06:00:00 - 06:59:59	443	2.8%	0.12	1.6 MB	3.4%	0.47
Aug 27 07:00:00 - 07:59:59	665	4.2%	0.18	1.9 MB	3.9%	0.53
Aug 27 08:00:00 - 08:59:59	618	3.9%	0.17	1.7 MB	3.5%	0.47
Aug 27 09:00:00 - 09:59:59	832	5.2%	0.23	2.6 MB	5.4%	0.73
Aug 27 10:00:00 - 10:59:59	810	5.1%	0.23	2.4 MB	5.0%	0.69
Aug 27 11:00:00 - 11:59:59	691	4.3%	0.19	2.0 MB	4.1%	0.56
Aug 27 12:00:00 - 12:59:59	879	5.5%	0.24	2.4 MB	5.0%	0.68
Aug 27 13:00:00 - 13:59:59	865	5.4%	0.24	2.2 MB	4.5%	0.62
Aug 27 14:00:00 - 14:59:59	962	6.1%	0.27	3.0 MB	6.2%	0.85
Aug 27 15:00:00 - 15:59:59	812	5.1%	0.23	2.2 MB	4.5%	0.62
Aug 27 16:00:00 - 16:59:59	818	5.1%	0.23	2.2 MB	4.5%	0.61
Aug 27 17:00:00 - 17:59:59	859	5.4%	0.24	2.9 MB	6.1%	0.84
Aug 27 18:00:00 - 18:59:59	852	5.4%	0.24	2.7 MB	5.6%	0.77
Aug 27 19:00:00 - 19:59:59	809	5.1%	0.22	2.2 MB	4.7%	0.64
Aug 27 20:00:00 - 20:59:59	819	5.2%	0.23	2.6 MB	5.5%	0.75
Aug 27 21:00:00 - 21:59:59	814	5.1%	0.23	2.2 MB	4.6%	0.62
Aug 27 22:00:00 - 22:59:59	639	4.0%	0.18	2.0 MB	4.3%	0.58
Aug 27 23:00:00 - 23:59:59	554	3.5%	0.15	1.5 MB	3.2%	0.43
Aug 28 00:00:00 - 00:59:59	403	2.5%	0.11	1.3 MB	2.6%	0.36
Aug 28 01:00:00 - 01:59:59	308	1.9%	0.09	1.1 MB	2.2%	0.31
Aug 28 02:00:00 - 02:59:59	375	2.4%	0.10	1.3 MB	2.8%	0.38
Aug 28 03:00:00 - 03:59:59	284	1.8%	0.08	912.3 KB	1.9%	0.25
Aug 28 04:00:00 - 04:15:00	104	0.7%	0.12	429.6 KB	0.9%	0.48
TOTAL: 24:00:00	15892	100.0%	0.18	48.0 MB	100.0%	0.57

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	995	53	0	942
2	news.freedyn.net	788	19	1	768
3	newsfeed.fu-berlin.de	619	11	0	608
4	newsfeed.neva.ru	407	167	2	238
5	csiph-out.feeder.erje.net	403	54	4	345
6	news-out.mixmin.net	294	0	1	293
7	csiph-out.news.weretis.net	268	97	2	169
8	csiph-out.feed.uzoreto.com	176	6	1	169
9	feeder.eternal-september.org	60	25	1	34
10	news-out.aioe.org	31	26	1	4
11	nntp.pasdenom.info	14	0	1	13
12	newsfeeds.xmission.com	12	6	0	6
13	news.fcku.it	7	7	0	0
14	news.swapon.de	7	0	0	7
TOTAL: 14		4081	471	14	3596

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	147
alt.bbs.synchronet	54
it-alt.sport.calcio.juventus	35
mozilla.support.seamonkey	23
alt.computer.workshop	19
alt.politics	12
mozilla.support.thunderbird	10
lada.commerce	10
mozilla.dev.security.policy	9
alt.politics.usa	8
alt.binaries.pictures.purebeauty	8
alt.windows7.general	8
lada.auto	7
alt.english.usage	7
mozilla.dev.platform	7
alt.job	6
at.jobs	6
alt.survival	4
aus.jobs	4
oesterreich.tratsch	4
TOTAL: 78	471

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1822
EMP (phn path)	354
Too many newsgroups	89
EMP (phl)	86
EMP (phn nph)	47
User-issued cancel	21
HTML Multipart	16
Binary: misplaced rar	15
Scoring filter (14)	7
User-issued spam cancel	6
Binary Payload	5
Scoring filter (12)	4
HTML post	3
Scoring filter (9)	3
Scoring filter (8)	2
HTML Image Tags	2
Too many newsgroups (meow)	2
Scoring filter (13)	2
Scoring filter (11)	2
Scoring filter (10)	1
TOTAL: 21	2490

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	51	0	54	54
TOTAL: 1	51	8	54	54

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	321
	csiph-out.feeder.erje.net	211
	csiph-out.news.weretis.net	66
	csiph-out.feed.uzoreto.com	6
	csiph-out.news.xs4all.nl	6
	newsfeeds.xmission.com	5
	feeder.eternal-september.org	1
	TOTAL: 7	616
Including strange strings
	newsfeed.fu-berlin.de	411
	csiph-out.news.xs4all.nl	37
	csiph-out.news.weretis.net	16
	news.swapon.de	7
	news-out.mixmin.net	3
	news-out.aioe.org	2
	csiph-out.feeder.erje.net	2
	newsfeed.neva.ru	1
	newsfeeds.xmission.com	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 10	481
No colon-space in header
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
TOTAL: 4		1143

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18244	624	15229	0	0	3%	23:55:52
2	news.freedyn.net	15216	456	14500	23	0	2%	23:59:10
3	fu-berlin.de	14699	439	14260	0	0	2%	23:50:04
4	news.bbs.nz	29212	177	15174	2	0	0%	23:50:05
5	usenet.pasdenom.info	16420	66	15242	516	100	0%	23:59:55
6	aioe	13544	28	13134	0	0	0%	23:59:38
7	newsfeed.xs4all.nl	14456	16	13697	4	35	0%	23:56:30
8	neva.ru	14573	5	14356	91	0	0%	23:50:04
9	news.mixmin.net	14767	4	14442	0	0	0%	23:58:41
10	weretis.net	12216	4	12124	0	0	0%	23:50:04
11	xmission	16289	2	13481	0	0	0%	23:58:09
12	eternal-september	13687	2	13325	0	193	0%	23:52:26
13	erje	14086	1	13903	1	0	0%	23:52:51
14	news.uzoreto.com	15186	1	15023	5	0	0%	23:50:05
15	news.swapon.de	1521	1	1491	1	0	0%	23:54:54
16	news.samoylyk.net	0	0	0	0	15819	0%	23:50:07
17	news.redatomik.org	0	0	0	0	15356	0%	23:50:04
18	news.fcku.it	1385	0	1380	0	0	0%	21:10:53
TOTAL: 18		225501	1826	200761	643	31503	0%	427:29:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.1 MB	0.0 KB	6.1 MB	0.1 KB/s	9.9 KB	23:55:52
2	news.freedyn.net	5.7 MB	51.3 KB	5.8 MB	0.1 KB/s	12.3 KB	23:59:10
3	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	12.9 KB	23:50:04
4	news.bbs.nz	434.6 KB	5.7 KB	440.3 KB	0.0 KB/s	2.5 KB	23:50:05
5	usenet.pasdenom.info	162.0 KB	5.9 MB	6.0 MB	0.1 KB/s	10.6 KB	23:59:55
6	aioe	61.4 KB	0.0 KB	61.4 KB	0.0 KB/s	2.2 KB	23:59:38
7	newsfeed.xs4all.nl	54.6 KB	14.2 KB	68.8 KB	0.0 KB/s	3.4 KB	23:56:30
8	neva.ru	16.0 KB	187.3 KB	203.3 KB	0.0 KB/s	2.1 KB	23:50:04
9	weretis.net	7.0 KB	0.0 KB	7.0 KB	0.0 KB/s	1.7 KB	23:50:04
10	news.mixmin.net	6.9 KB	0.0 KB	6.9 KB	0.0 KB/s	1.7 KB	23:58:41
11	xmission	4.6 KB	0.0 KB	4.6 KB	0.0 KB/s	2.3 KB	23:58:09
12	eternal-september	4.6 KB	0.0 KB	4.6 KB	0.0 KB/s	2.3 KB	23:52:26
13	news.uzoreto.com	3.5 KB	26.4 KB	29.9 KB	0.0 KB/s	5.0 KB	23:50:05
14	erje	1.1 KB	3.5 KB	4.6 KB	0.0 KB/s	2.3 KB	23:52:51
15	news.swapon.de	1.1 KB	3.5 KB	4.6 KB	0.0 KB/s	2.3 KB	23:54:54
16	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:10:53
TOTAL: 18		18.0 MB	6.1 MB	24.2 MB	0.0 KB/s	10.0 KB	427:29:32

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	23	15	29.9 KB	5	0	0	05:53:28
2	ip72-222-200-117.ph.ph.cox.net	6	10	18.6 KB	1	0	0	03:01:08
3	71-13-21-253.dhcp.dlth.mn.charter.com	7	9	17.4 KB	5	2	2	00:59:53
4	208-71-239-109.cust.centrio.cz	2	1	2.5 KB	1	0	0	01:00:08
5	challenger.nielsenmedia.com	1	1	1.5 KB	1	0	0	00:32:04
6	cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	1	1	1.8 KB	1	0	0	00:30:08
TOTAL: 6		40	37	71.6 KB	14	2	2	11:56:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	23	15	29.9 KB	5	0	0	05:53:28
2	*.ph.ph.cox.net	6	10	18.6 KB	1	0	0	03:01:08
3	*.dhcp.dlth.mn.charter.com	7	9	17.4 KB	5	2	2	00:59:53
4	*.nielsenmedia.com	1	1	1.5 KB	1	0	0	00:32:04
5	*.cust.centrio.cz	2	1	2.5 KB	1	0	0	01:00:08
6	*.19-1.cable.virginm.net	1	1	1.8 KB	1	0	0	00:30:08
7	unresolved	3	0	0.0 KB	0	0	0	00:00:03
TOTAL: 7		43	37	71.6 KB	14	2	2	11:56:54

NNRP auth users [Top 20]:

User	Conn
ohlandl	2
ctomczyk	2
TOTAL: 2	4

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	1.846	0.350	0.000	05:53:28
ip72-222-200-117.ph.ph.cox.net	0.623	0.132	0.000	03:01:08
208-71-239-109.cust.centrio.cz	0.187	0.031	0.005	01:00:08
71-13-21-253.dhcp.dlth.mn.charter.com	0.799	0.121	0.006	00:59:53
challenger.nielsenmedia.com	0.115	0.015	0.000	00:32:04
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	0.092	0.038	0.000	00:30:08
94.160.56.117	0.073	0.008	0.000	00:00:01
94.160.105.233	0.072	0.008	0.000	00:00:01
94.161.51.85	0.048	0.032	0.000	00:00:01
TOTAL: 9	3.855	0.735	0.011	11:56:54

Curious NNRP server explorers [Top 20]:

System	Conn
94.161.51.85	1
94.160.105.233	1
94.160.56.117	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	6	0
71-13-21-253.dhcp.dlth.mn.charter.com	2	1
208-71-239-109.cust.centrio.cz	2	0
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	1	0
challenger.nielsenmedia.com	1	0
TOTAL: 5	12	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	37	100.0%
TOTAL: 1		37	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	34
2	comp.lang.pl1	1
3	comp.lang.awk	1
4	comp.lang.javascript	1
TOTAL: 4		37

Daily Usenet report for csiph.com

Aug 27 04:15:00 -- Aug 28 04:15:00