Daily Usenet report for csiph.com: Jan 9 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19673	50.2%	4.9 MB	65.1%
innd	13978	35.7%	1.8 MB	24.3%
innfeed	3534	9.0%	602.3 KB	7.7%
nnrpd	1991	5.1%	220.1 KB	2.8%
nocem	15	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39192	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	289790	46.3%
Negative hits	273791	43.7%
Cache misses	43475	6.9%
Do not exist	19111	3.1%
TOTAL: 4	626167	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.446	0.0%	16	2.000	27.875	37.125
article cleanup	00:00:00.100	0.0%	18814	0.000	0.005	0.026
article logging	00:00:00.461	0.0%	19256	0.000	0.024	0.927
article parse	00:00:01.556	0.0%	65386	0.000	0.024	0.651
article write	00:00:57.419	0.1%	15656	0.230	3.668	87.500
artlog/artcncl	00:00:00.017	0.0%	16	0.000	1.062	2.000
artlog/artparse	00:00:00.009	0.0%	406	0.000	0.022	1.000
data move	00:00:00.231	0.0%	584643	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.005	0.0%	16	0.250	0.312	1.000
hishave/artcncl	00:00:00.001	0.0%	16	0.000	0.062	0.125
hishave/artparse	00:00:00.001	0.0%	395	0.000	0.003	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:43.545	0.1%	628651	0.001	0.069	0.446
history sync	00:00:01.305	0.0%	366	0.000	3.566	21.500
history write	00:00:52.306	0.1%	18821	0.580	2.779	17.453
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.968	0.0%	395	0.000	2.451	72.000
idle	23:53:24.664	99.5%	580837	52.307	148.070	540.092
nntp read	00:00:02.237	0.0%	587900	0.001	0.004	0.008
overview write	00:03:46.934	0.3%	15656	0.121	14.495	3438.943
perl filter	00:00:27.190	0.0%	18807	1.046	1.446	2.780
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.266	0.0%	31326	0.000	0.008	0.038
TOTAL: 24:01:02.057	24:00:19.661	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	22105	0.000	0.002	0.020
article prepare	00:00:00.001	0.0%	15628	0.000	0.000	0.005
article read	00:00:01.013	0.0%	1878	0.000	0.539	30.333
backlog stats	00:00:00.199	0.0%	328573	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.240	0.0%	261605	0.000	0.005	0.013
data write	00:00:01.594	0.0%	249642	0.002	0.006	0.014
idle	23:55:27.915	100.0%	328573	127.773	262.127	1018.696
status file	00:00:00.702	0.0%	283	0.000	2.481	114.000
TOTAL: 23:55:41.459	23:55:32.699	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	52:21:15.409	99.6%	1086	80.500	173550.100	600093.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.021	0.0%	2555	0.000	0.008	0.167
readart	00:00:00.169	0.0%	18	0.000	9.389	42.000
TOTAL: 52:34:23.095	52:21:15.599	99.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	0	25446	4247	19508	1691	16%	66:32:12
2	feeder.eternal-september.org	23	4133	2271	1813	49	54%	28:39:11
3	news-out.aioe.org	3	14709	1640	13021	48	11%	24:09:44
4	csiph-out.news.weretis.net	3	30416	1469	28644	303	4%	44:43:29
5	news-out.mixmin.net	1	17190	1230	15629	331	7%	23:59:49
6	csiph-out.feeder.erje.net	1439	76723	966	75374	383	1%	118:47:31
7	newsfeeds.xmission.com	2	14438	771	13651	16	5%	23:33:54
8	newsfeed.fu-berlin.de	22	13296	729	12382	185	5%	23:30:37
9	newsfeed.neva.ru	141	9892	652	9027	213	6%	71:56:43
10	csiph-out.feed.uzoreto.com	864	31191	460	30484	247	1%	71:20:31
11	news-out.neodome.net	3	17729	370	17344	15	2%	24:05:52
12	nntp.pasdenom.info	2	10036	203	9833	0	2%	23:58:49
13	news.fcku.it	10	1245	140	1105	0	11%	21:25:28
14	news.freedyn.net	1	18419	138	18182	99	0%	23:59:56
15	csiph-out.news.tnetconsulting.net	2	15778	47	15731	0	0%	23:59:41
16	news.swapon.de	3	1439	39	1398	2	2%	23:04:37
17	news.samoylyk.net	3	15374	16	15358	0	0%	13:50:18
18	news.bbs.nz	1	15285	3	15282	0	0%	23:59:51
19	localhost	26	0	0	0	0	0%	00:00:02
20	xanadu-bbs.net	25	452	0	452	0	0%	21:56:11
TOTAL: 20		2574	333191	15391	314218	3582	4%	697:34:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	12.0 MB	1.8 KB	13.9 MB	25.9 MB	46%	4.5 KB
2	csiph-out.feeder.erje.net	7.3 MB	2.9 KB	3.4 MB	10.8 MB	67%	8.2 KB
3	feeder.eternal-september.org	6.0 MB	0.0 KB	367.2 KB	6.3 MB	94%	2.8 KB
4	csiph-out.news.weretis.net	4.4 MB	0.0 KB	2.1 MB	6.5 MB	68%	3.7 KB
5	news-out.aioe.org	4.0 MB	0.0 KB	79.6 KB	4.0 MB	98%	2.5 KB
6	news-out.mixmin.net	3.7 MB	0.0 KB	1.5 MB	5.2 MB	71%	3.4 KB
7	newsfeeds.xmission.com	2.1 MB	0.0 KB	66.2 KB	2.1 MB	96%	2.8 KB
8	newsfeed.neva.ru	1.9 MB	0.0 KB	602.4 KB	2.5 MB	76%	3.0 KB
9	newsfeed.fu-berlin.de	1.7 MB	0.0 KB	442.5 KB	2.2 MB	79%	2.4 KB
10	csiph-out.feed.uzoreto.com	1.6 MB	0.0 KB	7.9 MB	9.5 MB	17%	13.8 KB
11	news-out.neodome.net	1.3 MB	0.0 KB	33.6 KB	1.4 MB	97%	3.6 KB
12	news.freedyn.net	566.0 KB	1.4 KB	3.3 MB	3.8 MB	14%	16.5 KB
13	nntp.pasdenom.info	448.8 KB	0.0 KB	0.0 KB	448.8 KB	100%	2.2 KB
14	news.fcku.it	261.0 KB	0.0 KB	0.0 KB	261.0 KB	100%	1.9 KB
15	csiph-out.news.tnetconsulting.net	155.3 KB	0.0 KB	0.0 KB	155.3 KB	100%	3.3 KB
16	news.swapon.de	88.3 KB	0.0 KB	81.1 KB	169.4 KB	52%	4.1 KB
17	news.samoylyk.net	58.0 KB	0.0 KB	0.0 KB	58.0 KB	100%	3.6 KB
18	news.bbs.nz	4.4 KB	0.0 KB	0.0 KB	4.4 KB	100%	1.5 KB
19	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		47.6 MB	6.1 KB	33.7 MB	81.3 MB	58%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 9 04:15:00 - 04:59:59	212	1.4%	0.08	697.9 KB	1.4%	0.26
Jan 9 05:00:00 - 05:59:59	274	1.8%	0.08	905.0 KB	1.8%	0.25
Jan 9 06:00:00 - 06:59:59	314	2.0%	0.09	907.1 KB	1.8%	0.25
Jan 9 07:00:00 - 07:59:59	390	2.5%	0.11	1.1 MB	2.2%	0.30
Jan 9 08:00:00 - 08:59:59	583	3.7%	0.16	2.3 MB	4.8%	0.66
Jan 9 09:00:00 - 09:59:59	784	5.0%	0.22	3.2 MB	6.7%	0.92
Jan 9 10:00:00 - 10:59:59	787	5.0%	0.22	2.5 MB	5.1%	0.71
Jan 9 11:00:00 - 11:59:59	869	5.6%	0.24	2.5 MB	5.3%	0.72
Jan 9 12:00:00 - 12:59:59	818	5.2%	0.23	2.4 MB	5.0%	0.69
Jan 9 13:00:00 - 13:59:59	884	5.6%	0.25	2.6 MB	5.3%	0.74
Jan 9 14:00:00 - 14:59:59	837	5.3%	0.23	2.5 MB	5.2%	0.72
Jan 9 15:00:00 - 15:59:59	830	5.3%	0.23	2.2 MB	4.5%	0.62
Jan 9 16:00:00 - 16:59:59	967	6.2%	0.27	2.7 MB	5.5%	0.76
Jan 9 17:00:00 - 17:59:59	970	6.2%	0.27	3.5 MB	7.1%	0.98
Jan 9 18:00:00 - 18:59:59	766	4.9%	0.21	2.0 MB	4.1%	0.56
Jan 9 19:00:00 - 19:59:59	811	5.2%	0.23	2.2 MB	4.5%	0.62
Jan 9 20:00:00 - 20:59:59	768	4.9%	0.21	1.9 MB	4.0%	0.55
Jan 9 21:00:00 - 21:59:59	762	4.9%	0.21	2.2 MB	4.6%	0.63
Jan 9 22:00:00 - 22:59:59	816	5.2%	0.23	3.1 MB	6.4%	0.88
Jan 9 23:00:00 - 23:59:59	706	4.5%	0.20	2.6 MB	5.3%	0.73
Jan 10 00:00:00 - 00:59:59	475	3.0%	0.13	1.3 MB	2.6%	0.36
Jan 10 01:00:00 - 01:59:59	349	2.2%	0.10	1.1 MB	2.2%	0.30
Jan 10 02:00:00 - 02:59:59	266	1.7%	0.07	846.3 KB	1.7%	0.24
Jan 10 03:00:00 - 03:59:59	338	2.2%	0.09	1.0 MB	2.1%	0.29
Jan 10 04:00:00 - 04:15:00	78	0.5%	0.09	278.4 KB	0.6%	0.31
TOTAL: 24:00:00	15654	100.0%	0.18	48.4 MB	100.0%	0.57

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	csiph-out.news.xs4all.nl	1734	115	1	0	0	1618
2	newsfeed.fu-berlin.de	597	21	0	0	0	576
3	csiph-out.news.weretis.net	344	91	0	0	0	253
4	news-out.mixmin.net	330	9	0	0	0	321
5	csiph-out.feed.uzoreto.com	258	8	0	0	0	250
6	newsfeed.neva.ru	218	54	0	1	0	163
7	csiph-out.feeder.erje.net	206	74	2	0	0	130
8	news.freedyn.net	98	1	1	0	0	96
9	csiph-out.news.tnetconsulting.net	57	2	0	0	0	55
10	feeder.eternal-september.org	53	14	0	0	3	36
11	news-out.aioe.org	47	31	0	0	0	16
12	newsfeeds.xmission.com	28	2	0	0	0	26
13	news.swapon.de	20	0	0	0	0	20
14	news-out.neodome.net	13	2	0	0	0	11
TOTAL: 14		4003	424	4	1	3	3571

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	48
lada.talk	41
alt.binaries.pictures.purebeauty	30
mozilla.general	27
alt.survival	25
scinet.stats	24
alt.tv.comedy-central.daily-show	22
alt.politics	18
mozilla.support.seamonkey	16
alt.philosophy	14
scinet.general.chatter	14
scinet.gaming	13
lada.soft	10
it-alt.sport.calcio.juventus	7
alt.religion	7
scinet.sysop	6
at.jobs	4
alt.binaries.sounds.mp3.pop-crap	4
alt.politics.usa	4
it-alt.comp.software.gratuito	4
TOTAL: 76	424

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1769
EMP (phn path)	607
EMP (phn nph)	127
EMP (phl)	93
Too many newsgroups	68
0	22
Binary: misplaced binary	18
HTML Multipart	9
Binary Payload	9
Too many newsgroups (meow)	4
U2 violation - invalid distribution	1
Scoring filter	1
TOTAL: 12	2728

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	135
	csiph-out.feed.uzoreto.com	80
	csiph-out.feeder.erje.net	60
	csiph-out.news.tnetconsulting.net	55
	news.freedyn.net	37
	csiph-out.news.weretis.net	10
	news-out.mixmin.net	5
	feeder.eternal-september.org	3
	newsfeed.fu-berlin.de	3
	newsfeeds.xmission.com	1
	TOTAL: 11	390
Including strange strings
	newsfeed.fu-berlin.de	365
	news.swapon.de	18
	newsfeeds.xmission.com	12
	csiph-out.news.weretis.net	11
	csiph-out.feed.uzoreto.com	10
	csiph-out.news.xs4all.nl	8
	csiph-out.feeder.erje.net	6
	newsfeed.neva.ru	5
	feeder.eternal-september.org	4
	news-out.mixmin.net	2
	TOTAL: 11	442
No colon-space in header
	csiph-out.news.xs4all.nl	10
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	11
TOTAL: 4		888

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17415	1090	14563	1	5	6%	23:59:53
2	news.samoylyk.net	16764	620	15768	95	5601	3%	23:58:39
3	fu-berlin.de	14621	545	14076	0	0	3%	23:59:40
4	news.bbs.nz	29122	427	14583	21	0	1%	23:59:52
5	xmission	16431	191	13997	14	0	1%	23:59:48
6	tnet	15965	171	14871	3	0	1%	23:59:34
7	aioe	12183	71	11818	2	0	0%	23:59:42
8	newsfeed.xs4all.nl	11620	55	11031	4	10	0%	23:59:53
9	usenet.pasdenom.info	15150	39	14736	61	12	0%	23:59:53
10	news.freedyn.net	13999	26	13634	45	0	0%	23:59:46
11	erje	14601	13	14356	134	0	0%	23:59:53
12	neodome.net	15594	6	15221	0	0	0%	23:59:53
13	news.uzoreto.com	14700	5	14381	20	0	0%	23:59:52
14	neva.ru	14233	4	13925	246	0	0%	23:59:53
15	news.swapon.de	1460	4	1444	0	0	0%	23:23:33
16	news.mixmin.net	13646	3	13306	0	0	0%	23:59:52
17	news.fcku.it	1077	0	1077	0	0	0%	20:57:48
18	eternal-september	0	0	0	0	12671	0%	23:59:53
19	weretis.net	13532	0	13451	0	0	0%	23:59:53
TOTAL: 19		252113	3270	226238	646	18299	1%	452:17:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.4 MB	6.2 KB	7.4 MB	0.1 KB/s	7.0 KB	23:59:53
2	news.samoylyk.net	6.5 MB	402.6 KB	6.9 MB	0.1 KB/s	9.8 KB	23:58:39
3	fu-berlin.de	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	11.7 KB	23:59:40
4	news.bbs.nz	1.0 MB	102.6 KB	1.1 MB	0.0 KB/s	2.5 KB	23:59:52
5	xmission	566.0 KB	34.1 KB	600.0 KB	0.0 KB/s	2.9 KB	23:59:48
6	tnet	362.8 KB	8.0 KB	370.8 KB	0.0 KB/s	2.1 KB	23:59:34
7	aioe	149.0 KB	5.5 KB	154.4 KB	0.0 KB/s	2.1 KB	23:59:42
8	news.freedyn.net	128.6 KB	591.0 KB	719.5 KB	0.0 KB/s	10.1 KB	23:59:46
9	newsfeed.xs4all.nl	110.8 KB	57.0 KB	167.7 KB	0.0 KB/s	2.8 KB	23:59:53
10	usenet.pasdenom.info	87.3 KB	115.8 KB	203.1 KB	0.0 KB/s	2.0 KB	23:59:53
11	erje	31.0 KB	399.3 KB	430.3 KB	0.0 KB/s	2.9 KB	23:59:53
12	neva.ru	13.9 KB	541.2 KB	555.1 KB	0.0 KB/s	2.2 KB	23:59:53
13	neodome.net	11.6 KB	0.0 KB	11.6 KB	0.0 KB/s	1.9 KB	23:59:53
14	news.uzoreto.com	10.7 KB	123.9 KB	134.7 KB	0.0 KB/s	5.4 KB	23:59:52
15	news.swapon.de	7.0 KB	0.0 KB	7.0 KB	0.0 KB/s	1.8 KB	23:23:33
16	news.mixmin.net	3.9 KB	0.0 KB	3.9 KB	0.0 KB/s	1.3 KB	23:59:52
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:57:48
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
19	weretis.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 19		22.6 MB	2.3 MB	24.9 MB	0.0 KB/s	6.5 KB	452:17:10

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	a131033.upc-a.chello.nl	38	8	21.2 KB	3	09:35:50
2	ip-78-102-198-192.net.upcbroadband.cz	48	7	17.8 KB	9	06:37:42
3	172.83.100.218	9	3	7.9 KB	4	04:31:40
TOTAL: 3		95	18	46.9 KB	16	52:34:20

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.upc-a.chello.nl	38	8	21.2 KB	3	09:35:50
2	*.net.upcbroadband.cz	48	7	17.8 KB	9	06:37:42
3	unresolved	244	3	7.9 KB	4	36:20:32
4	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	00:00:14
5	*.security.ipip.net	1	0	0.0 KB	0	00:00:00
TOTAL: 5		332	18	46.9 KB	16	52:34:20

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	14.556	3.781	30:18:41
a131033.upc-a.chello.nl	2.618	0.504	09:35:50
ip-78-102-198-192.net.upcbroadband.cz	3.044	0.728	06:37:42
172.83.100.218	0.710	0.130	04:31:40
208.72.53.144	0.205	0.018	01:30:01
34.20.97.83.ro.ovo.sc	0.067	0.008	00:00:14
192.241.217.172	0.059	0.017	00:00:09
scan-141.security.ipip.net	0.062	0.015	00:00:00
TOTAL: 8	21.321	5.201	52:34:20

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	231
208.72.53.144	3
scan-141.security.ipip.net	1
192.241.217.172	1
34.20.97.83.ro.ovo.sc	1
TOTAL: 5	237

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
172.83.100.218	9	0
208.72.53.144	3	0
TOTAL: 2	12	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Jan 9 04:15:00 -- Jan 10 04:15:00