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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19985	49.3%	5.1 MB	64.0%
innd	16614	41.0%	2.2 MB	27.9%
innfeed	3364	8.3%	600.7 KB	7.4%
nnrpd	521	1.3%	57.3 KB	0.7%
nocem	25	0.1%	2.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40510	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	312832	56.9%
Negative hits	217726	39.6%
Do not exist	19364	3.5%
Cache misses	258	0.0%
TOTAL: 4	550180	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.822	0.0%	32	0.000	25.688	45.000
article cleanup	00:00:00.108	0.0%	19022	0.000	0.006	0.046
article logging	00:00:00.307	0.0%	19470	0.000	0.016	0.304
article parse	00:00:01.105	0.0%	60118	0.000	0.018	0.285
article write	00:00:13.961	0.0%	16737	0.150	0.834	35.062
artlog/artcncl	00:00:00.001	0.0%	32	0.000	0.031	0.125
artlog/artparse	00:00:00.023	0.0%	484	0.000	0.048	17.000
data move	00:00:00.161	0.0%	514749	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.020	0.0%	32	0.000	0.625	1.000
hishave/artcncl	00:00:00.003	0.0%	32	0.000	0.094	1.000
hishave/artparse	00:00:00.001	0.0%	479	0.000	0.002	0.053
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:09.797	0.0%	552128	0.003	0.018	0.106
history sync	00:00:00.874	0.0%	381	0.000	2.294	9.500
history write	00:00:16.077	0.0%	18965	0.212	0.848	3.066
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.539	0.0%	479	0.000	1.125	20.857
idle	23:58:22.992	99.8%	522480	91.965	165.180	480.476
nntp read	00:00:02.009	0.0%	523990	0.001	0.004	0.009
overview write	00:00:55.767	0.1%	16737	0.157	3.332	202.088
perl filter	00:00:27.182	0.0%	18957	1.183	1.434	2.259
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.211	0.0%	33498	0.000	0.006	0.025
TOTAL: 24:01:06.569	24:00:31.960	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	17146	0.000	0.002	0.036
article prepare	00:00:00.002	0.0%	25140	0.000	0.000	0.011
article read	00:00:00.457	0.0%	3095	0.000	0.148	14.667
backlog stats	00:00:00.202	0.0%	388067	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.371	0.0%	298078	0.000	0.005	0.010
data write	00:00:01.645	0.0%	284193	0.000	0.006	0.012
idle	23:54:24.983	100.0%	388067	129.220	221.779	550.014
status file	00:00:00.189	0.0%	268	0.000	0.705	11.000
TOTAL: 23:54:37.418	23:54:28.888	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	22:03:59.098	100.0%	360	0.000	220664.161	600149.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	858	0.000	0.007	0.167
readart	00:00:00.250	0.0%	30	0.000	8.333	42.000
TOTAL: 22:04:14.392	22:03:59.354	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	newsfeeds.xmission.com	4	16848	3857	12962	29	22%	27:43:48
2	news-out.aioe.org	3	15813	3200	12558	55	20%	24:12:58
3	feeder.eternal-september.org	4	17812	2053	15666	93	11%	25:00:20
4	csiph-out.feeder.erje.net	1440	76920	1622	74883	415	2%	118:43:22
5	csiph-out.news.xs4all.nl	862	17417	1058	15721	638	6%	71:28:09
6	newsfeed.neva.ru	147	17527	1052	16143	332	6%	71:20:22
7	csiph-out.news.weretis.net	4	46765	1041	45594	130	2%	66:25:46
8	news.freedyn.net	3	18633	942	17119	572	5%	24:09:03
9	newsfeed.fu-berlin.de	7	14999	893	14073	33	5%	23:22:06
10	news-out.mixmin.net	3	17203	705	16311	187	4%	24:02:49
11	news.fcku.it	10	1906	199	1704	3	10%	22:52:51
12	csiph-out.feed.uzoreto.com	864	24869	191	24437	241	0%	71:11:57
13	nntp.pasdenom.info	2	10873	47	10814	12	0%	23:58:39
14	csiph-out.news.tnetconsulting.net	2	16075	41	16022	12	0%	23:59:56
15	news.swapon.de	5	1389	26	1363	0	1%	23:56:49
16	news.bbs.nz	1	15605	15	15586	4	0%	23:59:53
17	news.samoylyk.net	3	15885	14	15842	29	0%	25:36:36
18	localhost	30	4	4	0	0	100%	00:00:00
19	xanadu-bbs.net	32	393	0	393	0	0%	20:58:52
TOTAL: 19		3426	346936	16960	327191	2785	4%	713:04:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.0 MB	55.8 KB	1.1 MB	12.1 MB	90%	3.2 KB
2	csiph-out.feeder.erje.net	10.3 MB	4.3 KB	10.9 MB	21.2 MB	48%	10.7 KB
3	news-out.aioe.org	8.2 MB	4.7 KB	171.8 KB	8.3 MB	97%	2.6 KB
4	feeder.eternal-september.org	5.7 MB	59.7 KB	889.6 KB	6.6 MB	86%	3.2 KB
5	csiph-out.news.weretis.net	3.1 MB	111.1 KB	2.4 MB	5.6 MB	54%	4.9 KB
6	csiph-out.news.xs4all.nl	3.0 MB	27.1 KB	6.0 MB	9.0 MB	32%	5.4 KB
7	news.freedyn.net	2.7 MB	56.8 KB	2.7 MB	5.5 MB	49%	3.7 KB
8	newsfeed.neva.ru	2.7 MB	6.6 KB	2.2 MB	4.9 MB	55%	3.7 KB
9	newsfeed.fu-berlin.de	2.2 MB	2.6 KB	205.3 KB	2.4 MB	91%	2.7 KB
10	news-out.mixmin.net	2.1 MB	57.7 KB	398.7 KB	2.5 MB	82%	2.9 KB
11	csiph-out.feed.uzoreto.com	741.4 KB	25.3 KB	5.2 MB	5.9 MB	12%	14.0 KB
12	news.fcku.it	345.2 KB	0.0 KB	100.3 KB	445.5 KB	77%	2.2 KB
13	csiph-out.news.tnetconsulting.net	137.2 KB	27.1 KB	127.7 KB	292.1 KB	46%	5.5 KB
14	nntp.pasdenom.info	84.2 KB	0.0 KB	23.6 KB	107.7 KB	78%	1.8 KB
15	news.bbs.nz	63.5 KB	52.0 KB	52.0 KB	167.4 KB	37%	8.8 KB
16	news.samoylyk.net	59.8 KB	33.4 KB	1.4 MB	1.5 MB	3%	35.9 KB
17	news.swapon.de	58.2 KB	0.0 KB	0.0 KB	58.2 KB	100%	2.2 KB
18	localhost	5.0 KB	0.0 KB	0.0 KB	5.0 KB	100%	1.2 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		52.5 MB	524.1 KB	33.9 MB	86.8 MB	60%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 4 04:15:00 - 04:59:59	251	1.5%	0.09	802.5 KB	1.5%	0.30
Dec 4 05:00:00 - 05:59:59	472	2.8%	0.13	2.6 MB	5.0%	0.74
Dec 4 06:00:00 - 06:59:59	434	2.6%	0.12	1.6 MB	3.1%	0.46
Dec 4 07:00:00 - 07:59:59	622	3.7%	0.17	2.6 MB	5.0%	0.74
Dec 4 08:00:00 - 08:59:59	553	3.3%	0.15	1.6 MB	3.0%	0.45
Dec 4 09:00:00 - 09:59:59	649	3.9%	0.18	1.7 MB	3.3%	0.49
Dec 4 10:00:00 - 10:59:59	663	4.0%	0.18	1.9 MB	3.6%	0.53
Dec 4 11:00:00 - 11:59:59	701	4.2%	0.19	2.2 MB	4.3%	0.64
Dec 4 12:00:00 - 12:59:59	796	4.8%	0.22	2.1 MB	4.1%	0.60
Dec 4 13:00:00 - 13:59:59	756	4.5%	0.21	2.0 MB	3.8%	0.56
Dec 4 14:00:00 - 14:59:59	802	4.8%	0.22	2.1 MB	4.0%	0.59
Dec 4 15:00:00 - 15:59:59	998	6.0%	0.28	3.2 MB	6.2%	0.92
Dec 4 16:00:00 - 16:59:59	1044	6.2%	0.29	2.9 MB	5.6%	0.83
Dec 4 17:00:00 - 17:59:59	874	5.2%	0.24	3.1 MB	6.0%	0.89
Dec 4 18:00:00 - 18:59:59	890	5.3%	0.25	2.5 MB	4.8%	0.71
Dec 4 19:00:00 - 19:59:59	991	5.9%	0.28	3.0 MB	5.7%	0.84
Dec 4 20:00:00 - 20:59:59	949	5.7%	0.26	2.7 MB	5.2%	0.76
Dec 4 21:00:00 - 21:59:59	886	5.3%	0.25	2.6 MB	5.1%	0.75
Dec 4 22:00:00 - 22:59:59	719	4.3%	0.20	2.0 MB	3.9%	0.58
Dec 4 23:00:00 - 23:59:59	730	4.4%	0.20	2.7 MB	5.1%	0.76
Dec 5 00:00:00 - 00:59:59	596	3.6%	0.17	1.8 MB	3.5%	0.51
Dec 5 01:00:00 - 01:59:59	483	2.9%	0.13	1.4 MB	2.7%	0.40
Dec 5 02:00:00 - 02:59:59	413	2.5%	0.11	1.2 MB	2.3%	0.34
Dec 5 03:00:00 - 03:59:59	387	2.3%	0.11	1.4 MB	2.7%	0.40
Dec 5 04:00:00 - 04:15:00	77	0.5%	0.09	291.1 KB	0.5%	0.32
TOTAL: 24:00:00	16736	100.0%	0.19	52.0 MB	100.0%	0.62

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	640	33	2	605
2	news.freedyn.net	573	8	6	559
3	csiph-out.feeder.erje.net	417	62	2	353
4	newsfeed.fu-berlin.de	373	16	1	356
5	newsfeed.neva.ru	332	84	3	245
6	csiph-out.feed.uzoreto.com	242	11	1	230
7	news-out.mixmin.net	190	0	7	183
8	news-out.aioe.org	142	38	2	102
9	csiph-out.news.weretis.net	114	59	11	44
10	feeder.eternal-september.org	96	24	8	64
11	news.samoylyk.net	29	0	5	24
12	csiph-out.news.tnetconsulting.net	25	16	5	4
13	newsfeeds.xmission.com	25	4	5	16
14	nntp.pasdenom.info	12	0	0	12
15	news.bbs.nz	4	0	4	0
16	news.fcku.it	3	2	0	1
TOTAL: 16		3217	357	62	2798

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	35
alt.bbs.synchronet	24
lada.auto	19
mozilla.support.firefox	19
alt.windows7.general	17
mozilla.support.seamonkey	15
lada.commerce	13
it-alt.sport.calcio.juventus	11
alt.binaries.ebooks	10
alt.binaries.pictures.purebeauty	10
alt.computer.workshop	9
alt.survival	9
alt.binaries.ctb	9
mozilla.support.thunderbird	9
test.post	7
alt.politics	7
alt.tv.star-trek	7
mozilla.dev.security.policy	7
mozilla.general	6
hk.politics	6
TOTAL: 88	357

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1537
EMP (phn nph)	136
Too many newsgroups	57
0	48
EMP (phl)	46
HTML Multipart	13
Scoring filter	13
Binary: misplaced binary	7
Binary Payload	4
Too many newsgroups (meow)	2
TOTAL: 10	1863

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	1
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	3
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	196
	csiph-out.news.xs4all.nl	93
	csiph-out.feed.uzoreto.com	86
	news.freedyn.net	28
	csiph-out.news.weretis.net	25
	news.samoylyk.net	24
	newsfeeds.xmission.com	15
	feeder.eternal-september.org	5
	csiph-out.news.tnetconsulting.net	4
	newsfeed.fu-berlin.de	2
	TOTAL: 11	479
Including strange strings
	newsfeed.fu-berlin.de	340
	news-out.aioe.org	87
	csiph-out.news.weretis.net	6
	news-out.mixmin.net	4
	csiph-out.news.xs4all.nl	3
	news.freedyn.net	3
	csiph-out.feeder.erje.net	2
	newsfeed.neva.ru	1
	newsfeeds.xmission.com	1
	feeder.eternal-september.org	1
	TOTAL: 10	448
No colon-space in header
	csiph-out.feeder.erje.net	3
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	4
TOTAL: 5		979

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	20438	1730	15004	2	0	8%	23:59:54
2	erje	18406	1633	12905	486	10	8%	23:59:54
3	news.samoylyk.net	17183	553	16165	4	355	3%	23:59:54
4	news.freedyn.net	16071	542	15221	8	0	3%	23:59:50
5	fu-berlin.de	15654	540	15114	0	0	3%	23:59:54
6	news.bbs.nz	31465	350	15827	13	0	1%	23:59:54
7	usenet.pasdenom.info	16700	114	16015	26	17	0%	23:59:54
8	newsfeed.xs4all.nl	15864	24	15519	2	54	0%	23:59:48
9	tnet	17752	20	16182	0	0	0%	23:59:53
10	aioe	12465	19	12057	0	0	0%	23:59:43
11	news.uzoreto.com	16330	16	16066	71	0	0%	23:59:43
12	news.mixmin.net	15690	12	15277	0	0	0%	23:59:53
13	neva.ru	15130	10	14855	88	0	0%	23:59:47
14	eternal-september	14354	9	13959	0	0	0%	23:59:50
15	xmission	16957	9	12458	0	0	0%	23:59:53
16	weretis.net	15427	5	15201	1	0	0%	23:59:45
17	news.swapon.de	1442	4	1425	0	0	0%	23:46:00
18	news.fcku.it	1641	0	1639	0	0	0%	22:33:59
TOTAL: 18		278969	5590	240889	701	436	2%	430:17:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.7 MB	3.1 KB	9.7 MB	0.1 KB/s	5.8 KB	23:59:54
2	news.samoylyk.net	6.8 MB	4.3 KB	6.8 MB	0.1 KB/s	12.5 KB	23:59:54
3	news.freedyn.net	6.7 MB	13.5 KB	6.7 MB	0.1 KB/s	12.5 KB	23:59:50
4	fu-berlin.de	6.7 MB	0.0 KB	6.7 MB	0.1 KB/s	12.7 KB	23:59:54
5	erje	3.9 MB	1.8 MB	5.7 MB	0.1 KB/s	2.8 KB	23:59:54
6	news.bbs.nz	949.6 KB	50.0 KB	999.6 KB	0.0 KB/s	2.8 KB	23:59:54
7	usenet.pasdenom.info	330.3 KB	238.0 KB	568.3 KB	0.0 KB/s	4.1 KB	23:59:54
8	newsfeed.xs4all.nl	84.1 KB	24.5 KB	108.6 KB	0.0 KB/s	4.2 KB	23:59:48
9	aioe	40.5 KB	0.0 KB	40.5 KB	0.0 KB/s	2.1 KB	23:59:43
10	news.uzoreto.com	33.9 KB	202.3 KB	236.2 KB	0.0 KB/s	2.7 KB	23:59:43
11	news.mixmin.net	32.8 KB	0.0 KB	32.8 KB	0.0 KB/s	2.7 KB	23:59:53
12	tnet	32.7 KB	0.0 KB	32.7 KB	0.0 KB/s	1.6 KB	23:59:53
13	eternal-september	29.4 KB	0.0 KB	29.4 KB	0.0 KB/s	3.3 KB	23:59:50
14	xmission	28.5 KB	0.0 KB	28.5 KB	0.0 KB/s	3.2 KB	23:59:53
15	neva.ru	19.7 KB	209.7 KB	229.3 KB	0.0 KB/s	2.3 KB	23:59:47
16	weretis.net	6.6 KB	1.1 KB	7.7 KB	0.0 KB/s	1.3 KB	23:59:45
17	news.swapon.de	5.0 KB	0.0 KB	5.0 KB	0.0 KB/s	1.2 KB	23:46:00
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:33:59
TOTAL: 18		35.4 MB	2.5 MB	37.9 MB	0.0 KB/s	6.2 KB	430:17:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	28	10	15.5 KB	4	0	0	07:49:24
2	ip72-222-200-117.ph.ph.cox.net	9	9	12.8 KB	3	0	0	03:35:56
3	71-82-225-84.dhcp.stpt.wi.charter.com	34	9	12.8 KB	6	4	4	10:35:59
4	ip-173-119-137-87.chcgil.spcsdns.net	1	2	3.7 KB	1	0	0	00:02:26
TOTAL: 4		72	30	44.8 KB	14	4	4	22:04:13

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	28	10	15.5 KB	4	0	0	07:49:24
2	*.ph.ph.cox.net	9	9	12.8 KB	3	0	0	03:35:56
3	*.dhcp.stpt.wi.charter.com	34	9	12.8 KB	6	4	4	10:35:59
4	*.chcgil.spcsdns.net	1	2	3.7 KB	1	0	0	00:02:26
5	*.natnow.res.rr.com	1	0	0.0 KB	0	0	0	00:00:10
6	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	0	00:00:00
7	unresolved	6	0	0.0 KB	0	0	0	00:00:15
TOTAL: 7		80	30	44.8 KB	14	4	4	22:04:13

NNRP auth users [Top 20]:

User	Conn
ohlandl	4
TOTAL: 1	4

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-82-225-84.dhcp.stpt.wi.charter.com	2.447	0.453	0.011	10:35:59
a131033.upc-a.chello.nl	1.931	0.424	0.000	07:49:24
ip72-222-200-117.ph.ph.cox.net	0.761	0.151	0.000	03:35:56
ip-173-119-137-87.chcgil.spcsdns.net	0.101	0.023	0.000	00:02:26
cpe-76-167-37-92.natnow.res.rr.com	0.067	0.008	0.000	00:00:10
159.203.193.46	0.061	0.015	0.000	00:00:09
94.162.15.210	0.118	0.036	0.000	00:00:02
94.163.152.193	0.034	0.042	0.000	00:00:01
94.163.149.26	0.069	0.009	0.000	00:00:01
94.163.243.223	0.061	0.015	0.000	00:00:01
ec2-34-221-65-148.us-west-2.compute.amazonaws.com	0.060	0.015	0.000	00:00:00
TOTAL: 11	5.710	1.191	0.011	22:04:13

Curious NNRP server explorers [Top 20]:

System	Conn
94.162.15.210	2
ec2-34-221-65-148.us-west-2.compute.amazonaws.com	1
159.203.193.46	1
cpe-76-167-37-92.natnow.res.rr.com	1
94.163.243.223	1
94.163.149.26	1
94.163.152.193	1
TOTAL: 7	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-225-84.dhcp.stpt.wi.charter.com	12	0
ip72-222-200-117.ph.ph.cox.net	6	0
TOTAL: 2	18	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	28	93.3%
2	alt	2	6.7%
TOTAL: 2		30	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	28
2	alt.test	2
TOTAL: 2		30

Daily Usenet report for csiph.com

Dec 4 04:15:00 -- Dec 5 04:15:00