Daily Usenet report for csiph.com: Sep 17 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18885	49.5%	4.9 MB	64.7%
innd	14838	38.9%	2.0 MB	26.0%
innfeed	3560	9.3%	624.7 KB	8.0%
nnrpd	862	2.3%	95.5 KB	1.2%
nocem	36	0.1%	4.3 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	38182	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	312523	53.6%
Negative hits	252294	43.3%
Do not exist	18156	3.1%
Cache misses	349	0.1%
TOTAL: 4	583322	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.422	0.0%	34	0.000	12.412	42.000
article cleanup	00:00:00.123	0.0%	17794	0.000	0.007	0.039
article logging	00:00:00.293	0.0%	18361	0.000	0.016	0.333
article parse	00:00:01.963	0.0%	58439	0.000	0.034	0.413
article write	00:00:21.807	0.0%	15740	0.170	1.385	70.429
artlog/artcncl	00:00:00.000	0.0%	34	0.000	0.000	0.000
artlog/artparse	00:00:00.005	0.0%	516	0.000	0.010	0.286
data move	00:00:00.195	0.0%	550935	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.112	0.0%	34	0.000	3.294	24.000
hishave/artcncl	00:00:00.060	0.0%	34	0.000	1.765	5.200
hishave/artparse	00:00:00.002	0.0%	508	0.000	0.004	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:32.670	0.0%	586846	0.002	0.056	0.312
history sync	00:00:00.923	0.0%	367	0.000	2.515	13.333
history write	00:00:46.367	0.1%	17785	0.204	2.607	12.138
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.428	0.0%	508	0.000	2.811	77.500
idle	23:56:48.368	99.7%	553548	96.252	155.738	563.493
nntp read	00:00:02.161	0.0%	558723	0.002	0.004	0.009
overview write	00:01:18.085	0.1%	15740	0.129	4.961	410.814
perl filter	00:00:24.784	0.0%	17777	1.167	1.394	2.111
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.245	0.0%	31516	0.000	0.008	0.026
TOTAL: 24:00:58.625	24:00:20.013	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.032	0.0%	16940	0.000	0.002	0.038
article prepare	00:00:00.002	0.0%	15280	0.000	0.000	0.016
article read	00:00:00.283	0.0%	1778	0.000	0.159	2.938
backlog stats	00:00:00.231	0.0%	362857	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.285	0.0%	285993	0.000	0.004	0.010
data write	00:00:01.607	0.0%	273267	0.001	0.006	0.012
idle	24:04:32.900	100.0%	362857	136.715	238.862	897.555
status file	00:00:00.294	0.0%	301	0.000	0.977	11.444
TOTAL: 24:04:45.473	24:04:36.634	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	42:16:27.988	99.9%	560	114.000	271764.264	600166.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.008	0.0%	1306	0.000	0.006	0.167
readart	00:00:00.171	0.0%	29	0.000	5.897	42.000
TOTAL: 42:20:10.237	42:16:28.167	99.9%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	136
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	156

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	463	17662	3022	13483	1157	17%	68:30:44
2	news-out.mixmin.net	3	16712	2511	14045	156	15%	24:08:54
3	feeder.eternal-september.org	2	17031	2240	14693	98	13%	23:24:26
4	newsfeeds.xmission.com	4	14991	2154	12775	62	14%	23:47:16
5	news-out.aioe.org	4	15336	1412	13828	96	9%	24:12:11
6	csiph-out.feeder.erje.net	1440	75969	971	74832	166	1%	118:47:28
7	newsfeed.fu-berlin.de	15	15328	923	14336	69	6%	25:38:45
8	newsfeed.neva.ru	143	10387	648	9570	169	6%	71:45:12
9	csiph-out.news.weretis.net	3	29852	637	28969	246	2%	43:05:16
10	news.fcku.it	18	2032	442	1575	15	21%	22:33:05
11	csiph-out.feed.uzoreto.com	864	23084	222	22608	254	0%	71:06:38
12	news-out.neodome.net	2	17040	207	16804	29	1%	23:59:45
13	nntp.pasdenom.info	2	10549	75	10449	25	0%	23:58:24
14	news.freedyn.net	1	18344	36	18273	35	0%	23:59:56
15	news.swapon.de	3	1338	34	1304	0	2%	23:56:30
16	csiph-out.news.tnetconsulting.net	1	16342	17	16323	2	0%	23:59:56
17	news.samoylyk.net	5	15449	12	15434	3	0%	23:08:20
18	news.bbs.nz	1	15799	2	15797	0	0%	23:59:53
19	xanadu-bbs.net	22	458	0	458	0	0%	22:02:14
20	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 20		3022	333703	15565	315556	2582	4%	706:04:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	8.1 MB	12.8 KB	14.2 MB	22.3 MB	36%	5.5 KB
2	newsfeeds.xmission.com	6.7 MB	0.0 KB	2.9 MB	9.5 MB	70%	4.4 KB
3	feeder.eternal-september.org	6.2 MB	0.0 KB	867.8 KB	7.1 MB	87%	3.1 KB
4	news-out.mixmin.net	5.3 MB	12.8 KB	434.5 KB	5.8 MB	92%	2.2 KB
5	news-out.aioe.org	5.1 MB	12.8 KB	347.0 KB	5.5 MB	93%	3.7 KB
6	csiph-out.feeder.erje.net	4.5 MB	50.7 KB	3.6 MB	8.2 MB	55%	7.4 KB
7	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	308.8 KB	2.4 MB	87%	2.5 KB
8	csiph-out.news.weretis.net	1.9 MB	25.6 KB	1.2 MB	3.2 MB	60%	3.7 KB
9	newsfeed.neva.ru	1.8 MB	25.6 KB	546.5 KB	2.3 MB	75%	2.9 KB
10	csiph-out.feed.uzoreto.com	957.1 KB	37.9 KB	11.6 MB	12.5 MB	7%	27.0 KB
11	news.fcku.it	690.0 KB	0.0 KB	19.6 KB	709.6 KB	97%	1.6 KB
12	news-out.neodome.net	626.6 KB	12.8 KB	1.5 MB	2.1 MB	28%	9.3 KB
13	news.freedyn.net	227.2 KB	12.8 KB	1.1 MB	1.4 MB	16%	19.8 KB
14	nntp.pasdenom.info	163.0 KB	0.0 KB	32.0 KB	194.9 KB	83%	1.9 KB
15	news.swapon.de	81.4 KB	0.0 KB	0.0 KB	81.4 KB	100%	2.4 KB
16	csiph-out.news.tnetconsulting.net	70.2 KB	12.8 KB	14.5 KB	97.5 KB	71%	5.1 KB
17	news.samoylyk.net	37.2 KB	0.0 KB	174.3 KB	211.4 KB	17%	14.1 KB
18	news.bbs.nz	2.5 KB	0.0 KB	0.0 KB	2.5 KB	100%	1.2 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		44.6 MB	216.6 KB	38.8 MB	83.6 MB	53%	4.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 17 04:15:00 - 04:59:59	299	1.9%	0.11	958.9 KB	2.1%	0.36
Sep 17 05:00:00 - 05:59:59	464	2.9%	0.13	1.5 MB	3.3%	0.43
Sep 17 06:00:00 - 06:59:59	536	3.4%	0.15	1.4 MB	3.1%	0.40
Sep 17 07:00:00 - 07:59:59	703	4.5%	0.20	2.1 MB	4.6%	0.59
Sep 17 08:00:00 - 08:59:59	694	4.4%	0.19	2.0 MB	4.5%	0.57
Sep 17 09:00:00 - 09:59:59	774	4.9%	0.21	2.0 MB	4.5%	0.58
Sep 17 10:00:00 - 10:59:59	822	5.2%	0.23	2.1 MB	4.7%	0.60
Sep 17 11:00:00 - 11:59:59	725	4.6%	0.20	2.1 MB	4.6%	0.59
Sep 17 12:00:00 - 12:59:59	832	5.3%	0.23	2.0 MB	4.5%	0.58
Sep 17 13:00:00 - 13:59:59	868	5.5%	0.24	2.4 MB	5.3%	0.69
Sep 17 14:00:00 - 14:59:59	867	5.5%	0.24	2.2 MB	5.0%	0.64
Sep 17 15:00:00 - 15:59:59	930	5.9%	0.26	2.7 MB	6.0%	0.77
Sep 17 16:00:00 - 16:59:59	865	5.5%	0.24	2.3 MB	5.1%	0.65
Sep 17 17:00:00 - 17:59:59	868	5.5%	0.24	2.2 MB	4.8%	0.62
Sep 17 18:00:00 - 18:59:59	845	5.4%	0.23	2.4 MB	5.3%	0.68
Sep 17 19:00:00 - 19:59:59	802	5.1%	0.22	2.6 MB	5.8%	0.75
Sep 17 20:00:00 - 20:59:59	831	5.3%	0.23	2.3 MB	5.2%	0.66
Sep 17 21:00:00 - 21:59:59	693	4.4%	0.19	2.0 MB	4.5%	0.57
Sep 17 22:00:00 - 22:59:59	496	3.2%	0.14	1.7 MB	3.8%	0.49
Sep 17 23:00:00 - 23:59:59	416	2.6%	0.12	1.5 MB	3.3%	0.42
Sep 18 00:00:00 - 00:59:59	395	2.5%	0.11	1.3 MB	2.9%	0.37
Sep 18 01:00:00 - 01:59:59	391	2.5%	0.11	1.3 MB	3.0%	0.38
Sep 18 02:00:00 - 02:59:59	276	1.8%	0.08	841.8 KB	1.8%	0.23
Sep 18 03:00:00 - 03:59:59	260	1.7%	0.07	750.5 KB	1.6%	0.21
Sep 18 04:00:00 - 04:15:00	92	0.6%	0.10	278.6 KB	0.6%	0.31
TOTAL: 24:00:00	15744	100.0%	0.18	45.1 MB	100.0%	0.53

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1180	55	1	1124
2	newsfeed.fu-berlin.de	492	9	0	483
3	csiph-out.news.weretis.net	275	53	2	220
4	csiph-out.feed.uzoreto.com	255	10	3	242
5	news-out.mixmin.net	231	3	1	227
6	newsfeed.neva.ru	180	57	2	121
7	news-out.aioe.org	127	76	1	50
8	csiph-out.feeder.erje.net	117	18	4	95
9	feeder.eternal-september.org	73	27	0	46
10	newsfeeds.xmission.com	60	3	0	57
11	news.freedyn.net	36	0	1	35
12	news-out.neodome.net	30	1	1	28
13	nntp.pasdenom.info	25	0	0	25
14	news.fcku.it	15	12	0	3
15	news.swapon.de	11	0	0	11
16	news.samoylyk.net	3	0	0	3
17	csiph-out.news.tnetconsulting.net	1	0	1	0
TOTAL: 17		3111	324	17	2770

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mozilla.support.thunderbird	45
it-alt.sport.calcio.juventus	34
lada.talk	31
scinet.stats	24
alt.bbs.synchronet	22
alt.philosophy	20
alt.survival	13
lada.auto	12
alt.politics	10
alt.windows7.general	9
oesterreich.tratsch	8
alt.tv.comedy-central.daily-show	8
mozilla.dev.platform	7
alt.sex.marketplace	6
mozilla.support.firefox	6
mozilla.support.seamonkey	5
it-alt.comp.software.mozilla	5
alt.job	4
att.jobs	4
alt.politics.usa	3
TOTAL: 56	324

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1107
EMP (phn path)	234
EMP (phl)	142
Too many newsgroups	79
0	58
EMP (phn nph)	45
Binary: misplaced binary	16
Scoring filter	13
HTML Multipart	9
Binary Payload	3
TOTAL: 10	1706

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	154
	csiph-out.feed.uzoreto.com	152
	csiph-out.feeder.erje.net	60
	newsfeeds.xmission.com	54
	news-out.neodome.net	27
	news.freedyn.net	21
	feeder.eternal-september.org	8
	csiph-out.news.weretis.net	7
	news-out.mixmin.net	3
	news.samoylyk.net	3
	TOTAL: 11	490
Including strange strings
	newsfeed.fu-berlin.de	409
	news-out.mixmin.net	75
	csiph-out.news.xs4all.nl	29
	news-out.aioe.org	29
	news.swapon.de	11
	csiph-out.news.weretis.net	10
	newsfeeds.xmission.com	2
	feeder.eternal-september.org	1
	news.freedyn.net	1
	news-out.neodome.net	1
	TOTAL: 10	568
No colon-space in header
	csiph-out.news.xs4all.nl	7
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	8
TOTAL: 4		1111

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.nz	29262	1120	14156	30	0	3%	23:52:58
2	xanadu-bbs	18840	928	14744	72	44	4%	23:59:53
3	tnet	17894	400	14902	0	0	2%	23:52:34
4	fu-berlin.de	14570	379	14191	0	0	2%	23:56:38
5	news.samoylyk.net	16009	364	15305	12	1100	2%	23:51:40
6	neva.ru	14282	117	14022	73	1	0%	23:49:49
7	erje	16311	50	14347	137	0	0%	23:52:22
8	aioe	10979	32	10650	1	0	0%	23:50:09
9	news.freedyn.net	15338	32	15060	0	0	0%	23:53:25
10	newsfeed.xs4all.nl	13098	31	12583	1	3	0%	23:59:51
11	xmission	14741	10	13021	0	17	0%	23:55:10
12	usenet.pasdenom.info	15707	9	15273	1	31	0%	23:59:53
13	eternal-september	13310	3	12987	1	0	0%	23:59:45
14	neodome.net	15642	2	15363	0	0	0%	23:50:09
15	news.uzoreto.com	14513	0	14274	1	0	0%	23:50:09
16	weretis.net	14799	0	14534	0	0	0%	23:53:44
17	news.swapon.de	1393	0	1391	0	0	0%	23:39:56
18	news.mixmin.net	12763	0	12523	0	0	0%	23:51:46
19	news.fcku.it	1467	0	1467	0	0	0%	22:00:19
TOTAL: 19		270918	3477	240793	329	1196	1%	452:00:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.6 MB	203.6 KB	4.8 MB	0.1 KB/s	4.9 KB	23:59:53
2	news.bbs.nz	3.3 MB	166.1 KB	3.4 MB	0.0 KB/s	3.0 KB	23:52:58
3	fu-berlin.de	2.9 MB	0.0 KB	2.9 MB	0.0 KB/s	7.9 KB	23:56:38
4	news.samoylyk.net	2.8 MB	133.8 KB	2.9 MB	0.0 KB/s	7.9 KB	23:51:40
5	tnet	1.1 MB	0.0 KB	1.1 MB	0.0 KB/s	2.8 KB	23:52:34
6	news.freedyn.net	327.7 KB	0.0 KB	327.7 KB	0.0 KB/s	10.2 KB	23:53:25
7	neva.ru	241.9 KB	194.1 KB	435.9 KB	0.0 KB/s	2.3 KB	23:49:49
8	erje	117.9 KB	408.6 KB	526.5 KB	0.0 KB/s	2.8 KB	23:52:22
9	aioe	97.3 KB	0.6 KB	97.9 KB	0.0 KB/s	3.0 KB	23:50:09
10	newsfeed.xs4all.nl	61.8 KB	1.9 KB	63.6 KB	0.0 KB/s	2.0 KB	23:59:51
11	usenet.pasdenom.info	57.7 KB	1.3 KB	59.0 KB	0.0 KB/s	5.9 KB	23:59:53
12	xmission	20.9 KB	0.0 KB	20.9 KB	0.0 KB/s	2.1 KB	23:55:10
13	eternal-september	4.5 KB	28.2 KB	32.7 KB	0.0 KB/s	8.2 KB	23:59:45
14	neodome.net	3.4 KB	0.0 KB	3.4 KB	0.0 KB/s	1.7 KB	23:50:09
15	news.uzoreto.com	0.0 KB	1.2 KB	1.2 KB	0.0 KB/s	1.2 KB	23:50:09
16	weretis.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:53:44
17	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:39:56
18	news.mixmin.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:51:46
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:00:19
TOTAL: 19		15.6 MB	1.1 MB	16.7 MB	0.0 KB/s	4.5 KB	452:00:10

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	ppp-2-87-179-175.home.otenet.gr	2	21	39.6 KB	1	00:00:16
2	024-178-047-152.res.spectrum.com	16	6	28.2 KB	5	05:55:43
3	a131033.upc-a.chello.nl	44	2	7.0 KB	2	11:19:22
TOTAL: 3		62	29	74.9 KB	8	42:20:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.home.otenet.gr	2	21	39.6 KB	1	00:00:16
2	*.res.spectrum.com	16	6	28.2 KB	5	05:55:43
3	*.upc-a.chello.nl	44	2	7.0 KB	2	11:19:22
4	*.net.upcbroadband.cz	19	0	0.0 KB	0	06:11:54
5	*.ph.ph.cox.net	4	0	0.0 KB	0	02:00:17
6	unresolved	50	0	0.0 KB	0	16:52:32
7	*.security.ipip.net	1	0	0.0 KB	0	00:00:02
TOTAL: 7		136	29	74.9 KB	8	42:20:09

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	3.289	0.874	16:52:32
a131033.upc-a.chello.nl	2.926	0.608	11:19:22
ip-89-176-128-12.net.upcbroadband.cz	1.223	0.274	06:11:54
024-178-047-152.res.spectrum.com	1.188	0.275	05:55:43
ip72-222-200-117.ph.ph.cox.net	0.414	0.085	02:00:17
ppp-2-87-179-175.home.otenet.gr	0.162	0.039	00:00:16
scan-141.security.ipip.net	0.046	0.038	00:00:02
TOTAL: 7	9.248	2.193	42:20:09

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	50
ip-89-176-128-12.net.upcbroadband.cz	19
ip72-222-200-117.ph.ph.cox.net	4
scan-141.security.ipip.net	1
TOTAL: 4	74

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
Host: 69.160.37.58:119	1
Connection: close	1
GET / HTTP/1.1	1
User-Agent: HTTP Banner Detection (https...	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
024-178-047-152.res.spectrum.com	8	1
ip72-222-200-117.ph.ph.cox.net	4	0
ip-89-176-128-12.net.upcbroadband.cz	1	0
TOTAL: 3	13	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	29
TOTAL: 1		29

Daily Usenet report for csiph.com

Sep 17 04:15:00 -- Sep 18 04:15:00