Daily Usenet report for csiph.com: Dec 6 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19696	48.8%	5.0 MB	63.5%
innd	16818	41.7%	2.2 MB	28.3%
innfeed	3292	8.2%	607.8 KB	7.5%
nnrpd	482	1.2%	51.4 KB	0.6%
nocem	39	0.1%	4.4 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40328	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	316528	58.5%
Negative hits	205494	38.0%
Do not exist	19077	3.5%
Cache misses	267	0.0%
TOTAL: 4	541366	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.227	0.0%	27	0.000	8.407	24.000
article cleanup	00:00:00.113	0.0%	18672	0.000	0.006	0.029
article logging	00:00:00.267	0.0%	19151	0.000	0.014	0.106
article parse	00:00:01.260	0.0%	58835	0.000	0.021	0.439
article write	00:00:13.290	0.0%	16190	0.119	0.821	38.320
artlog/artcncl	00:00:00.000	0.0%	27	0.000	0.000	0.000
artlog/artparse	00:00:00.003	0.0%	504	0.000	0.006	0.333
data move	00:00:00.177	0.0%	502832	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.010	0.0%	26	0.000	0.385	1.000
hishave/artcncl	00:00:00.002	0.0%	27	0.000	0.074	0.500
hishave/artparse	00:00:00.001	0.0%	498	0.000	0.002	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:07.619	0.0%	543140	0.002	0.014	0.742
history sync	00:00:00.980	0.0%	377	0.000	2.599	14.000
history write	00:00:12.106	0.0%	18666	0.214	0.649	11.026
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.756	0.0%	498	0.000	1.518	33.000
idle	23:58:47.056	99.8%	513934	93.550	167.973	905.109
nntp read	00:00:02.024	0.0%	514576	0.001	0.004	0.008
overview write	00:00:41.802	0.0%	16190	0.099	2.582	76.500
perl filter	00:00:25.764	0.0%	18658	1.004	1.381	3.960
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.250	0.0%	32418	0.000	0.008	0.030
TOTAL: 24:01:07.561	24:00:33.707	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	16761	0.000	0.002	0.050
article prepare	00:00:00.000	0.0%	14096	0.000	0.000	0.000
article read	00:00:00.240	0.0%	1256	0.000	0.191	20.000
backlog stats	00:00:00.173	0.0%	375820	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.317	0.0%	290815	0.000	0.005	0.009
data write	00:00:01.579	0.0%	273939	0.002	0.006	0.016
idle	23:54:28.264	100.0%	375820	129.560	229.015	1243.017
status file	00:00:00.153	0.0%	238	0.000	0.643	2.000
TOTAL: 23:54:39.395	23:54:31.764	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	15:55:35.423	100.0%	397	58.500	144421.720	600161.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.005	0.0%	941	0.000	0.005	0.143
readart	00:00:00.335	0.0%	43	0.000	7.791	42.000
TOTAL: 15:55:46.120	15:55:35.763	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	76891	3973	72487	431	5%	118:39:25
2	news-out.aioe.org	3	15538	3057	12434	47	19%	24:09:00
3	feeder.eternal-september.org	1	16814	1928	14584	302	11%	22:07:42
4	newsfeeds.xmission.com	3	14400	1611	12746	43	11%	21:18:16
5	newsfeed.neva.ru	147	18970	1044	17596	330	5%	71:20:41
6	csiph-out.news.weretis.net	4	31327	1008	30206	113	3%	46:50:53
7	news-out.mixmin.net	1	16858	815	15729	314	4%	23:59:51
8	newsfeed.fu-berlin.de	11	15447	808	14593	46	5%	24:57:50
9	csiph-out.news.xs4all.nl	861	17242	800	15630	812	4%	71:27:41
10	news.freedyn.net	2	18256	493	17641	122	2%	23:59:53
11	news.fcku.it	8	2036	264	1769	3	12%	21:37:20
12	csiph-out.feed.uzoreto.com	857	31517	135	31056	326	0%	71:13:18
13	nntp.pasdenom.info	2	10623	57	10545	21	0%	23:58:29
14	news.swapon.de	2	1582	34	1545	3	2%	24:52:07
15	news.samoylyk.net	2	15721	14	15703	4	0%	24:55:26
16	csiph-out.news.tnetconsulting.net	1	15981	9	15967	5	0%	23:59:56
17	localhost	33	7	7	0	0	100%	00:00:04
18	news.bbs.nz	1	15563	5	15558	0	0%	23:59:53
19	xanadu-bbs.net	26	447	0	447	0	0%	22:04:12
TOTAL: 19		3405	335220	16062	316236	2922	4%	685:31:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	16.3 MB	2.7 KB	11.1 MB	27.4 MB	59%	6.4 KB
2	news-out.aioe.org	7.4 MB	1.4 KB	117.8 KB	7.5 MB	98%	2.5 KB
3	feeder.eternal-september.org	5.5 MB	0.0 KB	1.0 MB	6.6 MB	84%	3.0 KB
4	newsfeeds.xmission.com	4.8 MB	0.0 KB	1.9 MB	6.7 MB	71%	4.2 KB
5	csiph-out.news.weretis.net	2.7 MB	1.4 KB	1.7 MB	4.4 MB	61%	4.1 KB
6	csiph-out.news.xs4all.nl	2.4 MB	0.0 KB	7.3 MB	9.7 MB	24%	6.1 KB
7	newsfeed.neva.ru	2.4 MB	17.5 KB	1.2 MB	3.6 MB	65%	2.7 KB
8	news-out.mixmin.net	2.4 MB	15.0 KB	551.5 KB	2.9 MB	81%	2.6 KB
9	news.freedyn.net	1.9 MB	1.4 KB	1.6 MB	3.4 MB	54%	5.7 KB
10	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	181.1 KB	2.0 MB	91%	2.4 KB
11	csiph-out.feed.uzoreto.com	499.2 KB	1.3 KB	6.8 MB	7.2 MB	6%	16.1 KB
12	news.fcku.it	418.5 KB	0.0 KB	5.0 KB	423.5 KB	98%	1.6 KB
13	nntp.pasdenom.info	107.9 KB	0.0 KB	39.1 KB	147.0 KB	73%	1.9 KB
14	news.swapon.de	76.6 KB	0.0 KB	970.4 KB	1.0 MB	7%	28.3 KB
15	csiph-out.news.tnetconsulting.net	49.1 KB	0.0 KB	52.2 KB	101.2 KB	48%	7.2 KB
16	news.samoylyk.net	47.2 KB	0.0 KB	168.3 KB	215.5 KB	21%	12.0 KB
17	news.bbs.nz	24.8 KB	0.0 KB	0.0 KB	24.8 KB	100%	5.0 KB
18	localhost	10.0 KB	0.0 KB	0.0 KB	10.0 KB	100%	1.4 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		48.7 MB	40.7 KB	34.6 MB	83.4 MB	58%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 6 04:15:00 - 04:59:59	155	1.0%	0.06	424.7 KB	0.8%	0.16
Dec 6 05:00:00 - 05:59:59	335	2.1%	0.09	1.7 MB	3.4%	0.48
Dec 6 06:00:00 - 06:59:59	439	2.7%	0.12	1.5 MB	3.1%	0.44
Dec 6 07:00:00 - 07:59:59	541	3.3%	0.15	1.7 MB	3.5%	0.49
Dec 6 08:00:00 - 08:59:59	714	4.4%	0.20	2.1 MB	4.3%	0.60
Dec 6 09:00:00 - 09:59:59	663	4.1%	0.18	1.7 MB	3.5%	0.49
Dec 6 10:00:00 - 10:59:59	765	4.7%	0.21	2.1 MB	4.3%	0.61
Dec 6 11:00:00 - 11:59:59	786	4.9%	0.22	2.9 MB	5.8%	0.81
Dec 6 12:00:00 - 12:59:59	710	4.4%	0.20	2.0 MB	4.0%	0.56
Dec 6 13:00:00 - 13:59:59	898	5.5%	0.25	2.4 MB	5.0%	0.69
Dec 6 14:00:00 - 14:59:59	943	5.8%	0.26	2.4 MB	4.8%	0.67
Dec 6 15:00:00 - 15:59:59	908	5.6%	0.25	3.1 MB	6.3%	0.88
Dec 6 16:00:00 - 16:59:59	949	5.9%	0.26	2.8 MB	5.8%	0.81
Dec 6 17:00:00 - 17:59:59	935	5.8%	0.26	2.3 MB	4.8%	0.67
Dec 6 18:00:00 - 18:59:59	954	5.9%	0.27	2.9 MB	6.0%	0.84
Dec 6 19:00:00 - 19:59:59	914	5.6%	0.25	2.9 MB	6.0%	0.83
Dec 6 20:00:00 - 20:59:59	796	4.9%	0.22	2.2 MB	4.5%	0.62
Dec 6 21:00:00 - 21:59:59	831	5.1%	0.23	2.4 MB	4.8%	0.67
Dec 6 22:00:00 - 22:59:59	790	4.9%	0.22	2.3 MB	4.7%	0.66
Dec 6 23:00:00 - 23:59:59	588	3.6%	0.16	2.1 MB	4.3%	0.60
Dec 7 00:00:00 - 00:59:59	455	2.8%	0.13	1.3 MB	2.7%	0.37
Dec 7 01:00:00 - 01:59:59	454	2.8%	0.13	1.3 MB	2.6%	0.37
Dec 7 02:00:00 - 02:59:59	363	2.2%	0.10	1.1 MB	2.2%	0.31
Dec 7 03:00:00 - 03:59:59	238	1.5%	0.07	1.1 MB	2.2%	0.31
Dec 7 04:00:00 - 04:15:00	78	0.5%	0.09	258.7 KB	0.5%	0.29
TOTAL: 24:00:00	16202	100.0%	0.19	49.1 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	817	37	0	780
2	csiph-out.feeder.erje.net	434	78	2	354
3	newsfeed.fu-berlin.de	375	23	0	352
4	newsfeed.neva.ru	333	63	2	268
5	csiph-out.feed.uzoreto.com	329	7	1	321
6	news-out.mixmin.net	325	2	2	321
7	feeder.eternal-september.org	315	35	0	280
8	csiph-out.news.weretis.net	167	29	1	137
9	news-out.aioe.org	147	30	1	116
10	news.freedyn.net	133	2	1	130
11	newsfeeds.xmission.com	46	3	0	43
12	nntp.pasdenom.info	21	0	0	21
13	csiph-out.news.tnetconsulting.net	11	5	2	4
14	news.swapon.de	7	0	0	7
15	news.samoylyk.net	4	0	0	4
16	news.fcku.it	3	3	0	0
TOTAL: 16		3467	317	12	3138

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	38
alt.windows7.general	34
mozilla.support.thunderbird	13
alt.survival	12
mozilla.dev.platform	12
mozilla.support.firefox	12
alt.binaries.ebooks	12
alt.computer.workshop	10
it-alt.sport.calcio.juventus	9
lada.auto	9
mozilla.support.seamonkey	9
alt.music.pink-floyd.publius	8
alt.politics	7
alt.binaries.ctb	7
oesterreich.tratsch	6
alt.philosophy	5
cakewalk.audio	5
alt.binaries.sounds.lossless.1990s	5
alt.bbs.synchronet	5
alt.binaries.pictures.purebeauty	4
TOTAL: 89	317

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1667
EMP (phn nph)	169
EMP (phn path)	122
0	72
EMP (phl)	55
Too many newsgroups	29
HTML Multipart	19
Scoring filter	12
Binary Payload	3
Binary: misplaced binary	2
HTML post	2
U2 violation - invalid distribution	1
TOTAL: 12	2153

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	197
	csiph-out.news.xs4all.nl	109
	csiph-out.feed.uzoreto.com	106
	newsfeeds.xmission.com	40
	csiph-out.news.weretis.net	17
	news.freedyn.net	16
	feeder.eternal-september.org	4
	csiph-out.news.tnetconsulting.net	3
	news.swapon.de	3
	news.samoylyk.net	2
	TOTAL: 11	498
Including strange strings
	newsfeed.fu-berlin.de	329
	news-out.aioe.org	100
	csiph-out.news.weretis.net	19
	news.freedyn.net	10
	csiph-out.news.xs4all.nl	4
	csiph-out.feed.uzoreto.com	4
	news.swapon.de	4
	csiph-out.feeder.erje.net	3
	news-out.mixmin.net	3
	newsfeeds.xmission.com	2
	TOTAL: 11	479
No colon-space in header
	csiph-out.news.xs4all.nl	3
	TOTAL: 1	3
TOTAL: 5		1027

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	21205	776	15425	1	0	3%	23:59:54
2	news.freedyn.net	15962	610	15048	29	0	3%	23:59:54
3	news.samoylyk.net	16786	552	15633	3	38	3%	23:59:54
4	fu-berlin.de	15234	529	14705	0	0	3%	23:59:43
5	news.bbs.nz	30480	372	15247	26	0	1%	23:59:54
6	neva.ru	14798	142	14265	22	0	0%	23:59:43
7	usenet.pasdenom.info	16236	112	15455	30	18	0%	23:59:54
8	news.uzoreto.com	16336	67	15587	51	411	0%	23:59:54
9	tnet	18983	36	15620	0	0	0%	23:59:47
10	newsfeed.xs4all.nl	15808	36	15238	5	112	0%	23:59:54
11	aioe	12010	20	11609	14	0	0%	23:59:54
12	news.mixmin.net	15066	16	14624	0	0	0%	23:59:28
13	xmission	17919	10	13926	0	0	0%	23:59:54
14	erje	12119	9	11975	24	6	0%	23:59:54
15	weretis.net	15499	9	14512	7	0	0%	23:59:54
16	news.swapon.de	1594	8	1573	0	0	0%	23:56:24
17	eternal-september	13942	8	13528	0	0	0%	23:59:54
18	news.fcku.it	1701	1	1687	0	0	0%	21:08:35
TOTAL: 18		271678	3313	235657	212	585	1%	429:02:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.0 MB	12.8 KB	7.1 MB	0.1 KB/s	9.3 KB	23:59:54
2	news.freedyn.net	6.7 MB	50.5 KB	6.7 MB	0.1 KB/s	10.8 KB	23:59:54
3	news.samoylyk.net	6.5 MB	3.5 KB	6.5 MB	0.1 KB/s	12.0 KB	23:59:54
4	fu-berlin.de	6.4 MB	0.0 KB	6.4 MB	0.1 KB/s	12.5 KB	23:59:43
5	news.bbs.nz	1011.6 KB	65.0 KB	1.1 MB	0.0 KB/s	2.7 KB	23:59:54
6	neva.ru	363.1 KB	70.0 KB	433.1 KB	0.0 KB/s	2.6 KB	23:59:43
7	news.uzoreto.com	356.0 KB	160.1 KB	516.1 KB	0.0 KB/s	4.4 KB	23:59:54
8	usenet.pasdenom.info	323.4 KB	114.0 KB	437.5 KB	0.0 KB/s	3.1 KB	23:59:54
9	newsfeed.xs4all.nl	83.2 KB	14.1 KB	97.3 KB	0.0 KB/s	2.4 KB	23:59:54
10	tnet	76.8 KB	0.0 KB	76.8 KB	0.0 KB/s	2.1 KB	23:59:47
11	aioe	41.2 KB	33.8 KB	75.0 KB	0.0 KB/s	2.2 KB	23:59:54
12	xmission	38.6 KB	0.0 KB	38.6 KB	0.0 KB/s	3.9 KB	23:59:54
13	news.swapon.de	33.2 KB	0.0 KB	33.2 KB	0.0 KB/s	4.2 KB	23:56:24
14	news.mixmin.net	30.5 KB	0.0 KB	30.5 KB	0.0 KB/s	1.9 KB	23:59:28
15	erje	17.5 KB	49.6 KB	67.2 KB	0.0 KB/s	2.0 KB	23:59:54
16	weretis.net	15.4 KB	22.5 KB	38.0 KB	0.0 KB/s	2.4 KB	23:59:54
17	eternal-september	11.3 KB	0.0 KB	11.3 KB	0.0 KB/s	1.4 KB	23:59:54
18	news.fcku.it	2.3 KB	0.0 KB	2.3 KB	0.0 KB/s	2.3 KB	21:08:35
TOTAL: 18		29.0 MB	595.9 KB	29.6 MB	0.0 KB/s	8.6 KB	429:02:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	71-90-73-106.dhcp.ftbg.wi.charter.com	26	25	41.5 KB	12	5	4	08:32:49
2	a131033.upc-a.chello.nl	13	11	15.3 KB	4	0	0	02:48:00
3	ip72-222-200-117.ph.ph.cox.net	14	7	10.5 KB	6	2	2	04:34:51
TOTAL: 3		53	43	67.3 KB	22	7	6	15:55:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.ftbg.wi.charter.com	26	25	41.5 KB	12	5	4	08:32:49
2	*.upc-a.chello.nl	13	11	15.3 KB	4	0	0	02:48:00
3	*.ph.ph.cox.net	14	7	10.5 KB	6	2	2	04:34:51
4	unresolved	2	0	0.0 KB	0	0	0	00:00:03
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 5		56	43	67.3 KB	22	7	6	15:55:45

NNRP auth users [Top 20]:

User	Conn
ohlandl	4
kev009	2
TOTAL: 2	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-90-73-106.dhcp.ftbg.wi.charter.com	2.292	0.447	0.010	08:32:49
ip72-222-200-117.ph.ph.cox.net	1.321	0.170	0.005	04:34:51
a131033.upc-a.chello.nl	0.992	0.207	0.000	02:48:00
147.253.17.137	0.035	0.043	0.000	00:00:02
94.162.107.230	0.063	0.014	0.000	00:00:01
scan-141.security.ipip.net	0.048	0.032	0.000	00:00:00
TOTAL: 6	4.751	0.913	0.015	15:55:45

Curious NNRP server explorers [Top 20]:

System	Conn
147.253.17.137	1
94.162.107.230	1
scan-141.security.ipip.net	1
TOTAL: 3	3

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
ip72-222-200-117.ph.ph.cox.net	8	0
71-90-73-106.dhcp.ftbg.wi.charter.com	7	0
TOTAL: 2	15	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Dec 6 04:15:00 -- Dec 7 04:15:00