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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18547	48.7%	4.8 MB	63.7%
innd	15144	39.8%	2.0 MB	26.9%
innfeed	3525	9.3%	629.8 KB	8.2%
nnrpd	833	2.2%	93.4 KB	1.2%
nocem	39	0.1%	4.8 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	38089	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	302561	52.7%
Negative hits	253164	44.1%
Do not exist	17824	3.1%
Cache misses	337	0.1%
TOTAL: 4	573886	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.351	0.0%	15	0.000	23.400	60.400
article cleanup	00:00:00.106	0.0%	17587	0.000	0.006	0.035
article logging	00:00:00.597	0.0%	18165	0.000	0.033	1.538
article parse	00:00:01.096	0.0%	55213	0.000	0.020	0.179
article write	00:00:30.085	0.0%	15222	0.151	1.976	64.211
artlog/artcncl	00:00:00.000	0.0%	15	0.000	0.000	0.000
artlog/artparse	00:00:00.020	0.0%	398	0.000	0.050	1.091
data move	00:00:00.177	0.0%	541114	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.024	0.0%	15	0.000	1.600	18.000
hishave/artcncl	00:00:00.003	0.0%	15	0.000	0.200	0.600
hishave/artparse	00:00:00.000	0.0%	392	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:22.789	0.0%	578945	0.002	0.039	0.221
history sync	00:00:00.929	0.0%	373	0.000	2.491	14.333
history write	00:00:21.665	0.0%	17591	0.450	1.232	5.684
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.537	0.0%	392	0.000	1.370	15.000
idle	23:57:11.610	99.7%	546063	89.586	157.915	563.848
nntp read	00:00:02.114	0.0%	550190	0.001	0.004	0.008
overview write	00:01:25.438	0.1%	15222	0.056	5.613	426.246
perl filter	00:00:24.668	0.0%	17585	1.126	1.403	1.967
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.250	0.0%	30482	0.000	0.008	0.036
TOTAL: 24:00:58.498	24:00:22.459	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	15966	0.000	0.003	0.023
article prepare	00:00:00.001	0.0%	12612	0.000	0.000	0.017
article read	00:00:00.260	0.0%	1381	0.000	0.188	3.769
backlog stats	00:00:00.178	0.0%	348680	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.312	0.0%	275719	0.001	0.005	0.010
data write	00:00:01.538	0.0%	263620	0.002	0.006	0.011
idle	23:54:52.750	100.0%	348680	133.150	246.910	889.933
status file	00:00:00.202	0.0%	266	0.000	0.759	2.000
TOTAL: 23:55:02.529	23:54:56.287	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	42:10:42.439	99.9%	543	242.667	279636.168	900048.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.010	0.0%	1261	0.000	0.008	0.167
readart	00:00:00.212	0.0%	23	0.000	9.217	42.000
TOTAL: 42:14:06.471	42:10:42.661	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	493	17458	3672	12360	1426	21%	68:57:33
2	news-out.mixmin.net	2	16025	2374	13498	153	14%	24:08:56
3	feeder.eternal-september.org	4	16363	2052	14262	49	12%	24:07:05
4	newsfeeds.xmission.com	2	13071	1479	11538	54	11%	20:06:36
5	news-out.aioe.org	2	14604	1427	13108	69	9%	23:59:43
6	newsfeed.fu-berlin.de	8	15667	914	14658	95	5%	27:15:20
7	csiph-out.feeder.erje.net	1440	74582	810	73512	260	1%	118:46:20
8	csiph-out.news.weretis.net	2	33056	715	32091	250	2%	51:36:51
9	newsfeed.neva.ru	141	8716	607	7915	194	6%	71:47:52
10	csiph-out.feed.uzoreto.com	864	22516	316	21937	263	1%	71:12:00
11	news.fcku.it	11	1666	309	1349	8	18%	21:51:51
12	news-out.neodome.net	2	16052	214	15829	9	1%	23:59:54
13	nntp.pasdenom.info	2	9928	92	9824	12	0%	23:58:55
14	news.swapon.de	5	1427	45	1382	0	3%	23:53:41
15	news.freedyn.net	1	17774	28	17708	38	0%	23:59:57
16	csiph-out.news.tnetconsulting.net	1	14965	8	14957	0	0%	23:59:55
17	news.samoylyk.net	2	14783	4	14778	1	0%	23:05:16
18	xanadu-bbs.net	32	425	3	422	0	0%	21:46:33
19	news.bbs.nz	2	14145	0	14145	0	0%	23:59:39
20	localhost	26	0	0	0	0	0%	00:00:04
TOTAL: 20		3042	323223	15069	305273	2881	4%	712:34:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	9.8 MB	0.0 KB	13.8 MB	23.6 MB	41%	4.7 KB
2	feeder.eternal-september.org	6.0 MB	5.3 KB	908.6 KB	6.8 MB	86%	3.3 KB
3	news-out.mixmin.net	5.0 MB	0.0 KB	428.8 KB	5.5 MB	92%	2.2 KB
4	news-out.aioe.org	4.9 MB	0.0 KB	261.4 KB	5.2 MB	95%	3.5 KB
5	newsfeeds.xmission.com	4.4 MB	0.0 KB	2.1 MB	6.5 MB	67%	4.3 KB
6	csiph-out.feeder.erje.net	4.3 MB	0.0 KB	2.6 MB	6.9 MB	61%	6.6 KB
7	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	457.9 KB	2.8 MB	83%	2.8 KB
8	csiph-out.news.weretis.net	1.8 MB	0.0 KB	1.6 MB	3.4 MB	51%	3.6 KB
9	newsfeed.neva.ru	1.6 MB	0.0 KB	575.3 KB	2.2 MB	73%	2.8 KB
10	csiph-out.feed.uzoreto.com	1.1 MB	0.0 KB	8.0 MB	9.2 MB	12%	16.2 KB
11	news-out.neodome.net	579.2 KB	0.0 KB	169.4 KB	748.7 KB	77%	3.4 KB
12	news.fcku.it	484.6 KB	0.0 KB	13.1 KB	497.7 KB	97%	1.6 KB
13	news.freedyn.net	353.1 KB	5.2 KB	1.4 MB	1.7 MB	19%	26.9 KB
14	nntp.pasdenom.info	222.0 KB	0.0 KB	15.5 KB	237.5 KB	93%	2.3 KB
15	news.swapon.de	114.3 KB	0.0 KB	0.0 KB	114.3 KB	100%	2.5 KB
16	csiph-out.news.tnetconsulting.net	28.0 KB	0.0 KB	0.0 KB	28.0 KB	100%	3.5 KB
17	xanadu-bbs.net	19.9 KB	0.0 KB	0.0 KB	19.9 KB	100%	6.6 KB
18	news.samoylyk.net	8.3 KB	0.0 KB	63.0 KB	71.4 KB	11%	14.3 KB
19	news.bbs.nz	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		42.9 MB	10.5 KB	32.4 MB	75.4 MB	56%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 18 04:15:00 - 04:59:59	249	1.6%	0.09	1008.3 KB	2.3%	0.37
Sep 18 05:00:00 - 05:59:59	419	2.8%	0.12	1.3 MB	3.0%	0.37
Sep 18 06:00:00 - 06:59:59	407	2.7%	0.11	1009.0 KB	2.3%	0.28
Sep 18 07:00:00 - 07:59:59	624	4.1%	0.17	1.6 MB	3.8%	0.46
Sep 18 08:00:00 - 08:59:59	702	4.6%	0.20	2.0 MB	4.7%	0.58
Sep 18 09:00:00 - 09:59:59	784	5.2%	0.22	1.9 MB	4.5%	0.55
Sep 18 10:00:00 - 10:59:59	721	4.7%	0.20	2.0 MB	4.6%	0.57
Sep 18 11:00:00 - 11:59:59	640	4.2%	0.18	1.7 MB	4.1%	0.50
Sep 18 12:00:00 - 12:59:59	764	5.0%	0.21	2.0 MB	4.5%	0.56
Sep 18 13:00:00 - 13:59:59	817	5.4%	0.23	2.0 MB	4.7%	0.57
Sep 18 14:00:00 - 14:59:59	829	5.5%	0.23	2.2 MB	5.2%	0.63
Sep 18 15:00:00 - 15:59:59	896	5.9%	0.25	2.7 MB	6.2%	0.77
Sep 18 16:00:00 - 16:59:59	895	5.9%	0.25	2.5 MB	5.8%	0.71
Sep 18 17:00:00 - 17:59:59	803	5.3%	0.22	2.4 MB	5.5%	0.67
Sep 18 18:00:00 - 18:59:59	758	5.0%	0.21	2.2 MB	5.1%	0.63
Sep 18 19:00:00 - 19:59:59	808	5.3%	0.22	2.0 MB	4.6%	0.56
Sep 18 20:00:00 - 20:59:59	742	4.9%	0.21	2.2 MB	5.0%	0.62
Sep 18 21:00:00 - 21:59:59	606	4.0%	0.17	1.7 MB	4.0%	0.49
Sep 18 22:00:00 - 22:59:59	650	4.3%	0.18	2.1 MB	4.8%	0.59
Sep 18 23:00:00 - 23:59:59	427	2.8%	0.12	1.4 MB	3.3%	0.41
Sep 19 00:00:00 - 00:59:59	470	3.1%	0.13	1.5 MB	3.6%	0.44
Sep 19 01:00:00 - 01:59:59	511	3.4%	0.14	1.6 MB	3.7%	0.45
Sep 19 02:00:00 - 02:59:59	326	2.1%	0.09	1.0 MB	2.4%	0.30
Sep 19 03:00:00 - 03:59:59	255	1.7%	0.07	711.9 KB	1.6%	0.20
Sep 19 04:00:00 - 04:15:00	100	0.7%	0.11	383.4 KB	0.9%	0.43
TOTAL: 24:00:00	15203	100.0%	0.18	43.2 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1457	92	0	1365
2	newsfeed.fu-berlin.de	512	7	0	505
3	csiph-out.news.weretis.net	275	56	0	219
4	csiph-out.feed.uzoreto.com	263	8	0	255
5	news-out.mixmin.net	191	1	0	190
6	newsfeed.neva.ru	190	39	0	151
7	csiph-out.feeder.erje.net	133	24	0	109
8	news-out.aioe.org	84	54	0	30
9	newsfeeds.xmission.com	65	3	0	62
10	feeder.eternal-september.org	51	16	1	34
11	news.freedyn.net	38	0	1	37
12	news-out.neodome.net	24	1	0	23
13	news.swapon.de	19	0	0	19
14	nntp.pasdenom.info	12	0	0	12
15	news.fcku.it	8	7	0	1
16	news.samoylyk.net	3	0	0	3
17	csiph-out.news.tnetconsulting.net	2	0	0	2
TOTAL: 17		3327	308	2	3017

Unwanted newsgroups [Top 20]:

Newsgroup	Count
scinet.stats	28
alt.math.undergrad	27
mozilla.support.thunderbird	24
lada.talk	21
alt.survival	19
it-alt.sport.calcio.juventus	19
alt.philosophy	13
alt.tv.comedy-central.daily-show	12
alt.politics.usa	12
mozilla.support.firefox	11
lada.auto	10
att.jobs	10
alt.job	9
alt.politics	8
alt.bbs.synchronet	8
alt.binaries.pictures.purebeauty	6
mozilla.support.seamonkey	5
mozilla.general	5
trollnet.cinema	5
aus.jobs	4
TOTAL: 52	308

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1421
EMP (phn path)	273
EMP (phl)	183
Too many newsgroups	63
0	45
EMP (phn nph)	19
HTML Multipart	14
Scoring filter	14
Binary: misplaced binary	8
Binary Payload	3
TOTAL: 10	2043

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	134
	csiph-out.feed.uzoreto.com	97
	csiph-out.feeder.erje.net	47
	newsfeeds.xmission.com	42
	news.freedyn.net	24
	news-out.neodome.net	18
	csiph-out.news.weretis.net	11
	feeder.eternal-september.org	10
	newsfeed.fu-berlin.de	3
	news.samoylyk.net	3
	TOTAL: 12	392
Including strange strings
	newsfeed.fu-berlin.de	437
	csiph-out.news.xs4all.nl	44
	news-out.mixmin.net	39
	news.swapon.de	19
	news-out.aioe.org	17
	csiph-out.news.weretis.net	13
	csiph-out.feeder.erje.net	3
	newsfeed.neva.ru	2
	newsfeeds.xmission.com	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 10	576
No colon-space in header
	csiph-out.news.xs4all.nl	4
	csiph-out.feeder.erje.net	2
	TOTAL: 2	6
TOTAL: 4		1019

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18217	823	14357	19	256	4%	23:59:56
2	news.bbs.nz	28803	499	14308	18	0	1%	23:59:54
3	fu-berlin.de	14036	371	13665	0	0	2%	23:59:56
4	news.samoylyk.net	15519	368	14823	13	517	2%	23:58:41
5	tnet	17125	256	14570	0	0	1%	23:59:43
6	neva.ru	13935	125	13658	35	0	0%	23:59:56
7	erje	15862	88	13614	319	0	0%	23:59:07
8	xmission	14776	47	12996	0	0	0%	23:59:56
9	news.freedyn.net	14854	40	14594	0	0	0%	23:59:50
10	newsfeed.xs4all.nl	12080	32	11411	2	0	0%	23:59:55
11	news.uzoreto.com	13707	11	13446	84	0	0%	23:59:56
12	neodome.net	15182	3	14911	0	0	0%	23:59:55
13	usenet.pasdenom.info	15125	3	14726	0	13	0%	23:59:56
14	eternal-september	12975	3	12632	1	0	0%	23:59:56
15	aioe	10603	2	10254	0	0	0%	23:59:38
16	news.swapon.de	1417	1	1412	0	0	0%	23:45:36
17	news.mixmin.net	12613	1	12354	0	0	0%	23:59:56
18	weretis.net	14402	1	14081	0	0	0%	23:59:56
19	news.fcku.it	1283	0	1283	0	0	0%	21:21:57
TOTAL: 19		262514	2674	233095	491	786	1%	453:03:40

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.5 MB	62.9 KB	4.6 MB	0.1 KB/s	5.6 KB	23:59:56
2	fu-berlin.de	3.2 MB	0.0 KB	3.2 MB	0.0 KB/s	8.9 KB	23:59:56
3	news.samoylyk.net	3.2 MB	74.5 KB	3.2 MB	0.0 KB/s	8.7 KB	23:58:41
4	news.bbs.nz	1.2 MB	102.0 KB	1.3 MB	0.0 KB/s	2.7 KB	23:59:54
5	tnet	684.3 KB	0.0 KB	684.3 KB	0.0 KB/s	2.7 KB	23:59:43
6	news.freedyn.net	456.6 KB	0.0 KB	456.6 KB	0.0 KB/s	11.4 KB	23:59:50
7	neva.ru	329.3 KB	105.7 KB	435.0 KB	0.0 KB/s	2.7 KB	23:59:56
8	erje	202.3 KB	995.2 KB	1.2 MB	0.0 KB/s	2.9 KB	23:59:07
9	xmission	122.1 KB	0.0 KB	122.1 KB	0.0 KB/s	2.6 KB	23:59:56
10	newsfeed.xs4all.nl	97.7 KB	9.1 KB	106.8 KB	0.0 KB/s	3.1 KB	23:59:55
11	news.uzoreto.com	29.6 KB	210.8 KB	240.4 KB	0.0 KB/s	2.5 KB	23:59:56
12	usenet.pasdenom.info	14.3 KB	0.0 KB	14.3 KB	0.0 KB/s	4.8 KB	23:59:56
13	neodome.net	10.4 KB	0.0 KB	10.4 KB	0.0 KB/s	3.5 KB	23:59:55
14	eternal-september	8.8 KB	2.0 KB	10.8 KB	0.0 KB/s	2.7 KB	23:59:56
15	news.mixmin.net	6.9 KB	0.0 KB	6.9 KB	0.0 KB/s	6.9 KB	23:59:56
16	aioe	4.3 KB	0.0 KB	4.3 KB	0.0 KB/s	2.1 KB	23:59:38
17	news.swapon.de	1.8 KB	0.0 KB	1.8 KB	0.0 KB/s	1.8 KB	23:45:36
18	weretis.net	1.7 KB	0.0 KB	1.7 KB	0.0 KB/s	1.7 KB	23:59:56
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:21:57
TOTAL: 19		14.1 MB	1.5 MB	15.6 MB	0.0 KB/s	5.1 KB	453:03:40

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	ppp-2-87-179-175.home.otenet.gr	2	8	33.2 KB	1	00:00:07
2	a131033.upc-a.chello.nl	36	7	31.1 KB	3	10:58:28
3	096-042-018-108.res.spectrum.com	6	6	17.2 KB	2	02:30:31
4	ppp-2-87-177-163.home.otenet.gr	3	2	3.0 KB	1	00:00:04
TOTAL: 4		47	23	84.4 KB	7	42:14:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.home.otenet.gr	5	10	36.1 KB	2	00:00:12
2	*.upc-a.chello.nl	36	7	31.1 KB	3	10:58:28
3	*.res.spectrum.com	6	6	17.2 KB	2	02:30:31
4	*.net.upcbroadband.cz	23	0	0.0 KB	0	07:29:41
5	unresolved	49	0	0.0 KB	0	15:53:16
6	*.ph.ph.cox.net	14	0	0.0 KB	0	05:21:54
TOTAL: 6		133	23	84.4 KB	7	42:14:05

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	3.239	0.703	15:23:16
a131033.upc-a.chello.nl	2.483	0.494	10:58:28
ip-89-176-128-12.net.upcbroadband.cz	1.477	0.337	07:29:41
ip72-222-200-117.ph.ph.cox.net	1.135	0.241	05:21:54
096-042-018-108.res.spectrum.com	0.439	0.117	02:30:31
202.96.99.85	0.069	0.008	00:30:00
ppp-2-87-179-175.home.otenet.gr	0.163	0.032	00:00:07
ppp-2-87-177-163.home.otenet.gr	0.233	0.039	00:00:04
TOTAL: 8	9.238	1.971	42:14:05

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	48
ip-89-176-128-12.net.upcbroadband.cz	23
ip72-222-200-117.ph.ph.cox.net	14
202.96.99.85	1
TOTAL: 4	86

NNRP unrecognized commands (by host):

System	Conn
202.96.99.85	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.0	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	8	0
096-042-018-108.res.spectrum.com	5	0
202.96.99.85	1	0
TOTAL: 3	14	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 18 04:15:00 -- Sep 19 04:15:00