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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	42782	62.0%	8.1 MB	73.1%
innd	21921	31.8%	2.3 MB	21.0%
innfeed	3222	4.7%	558.0 KB	4.9%
nnrpd	981	1.4%	106.6 KB	0.9%
nocem	59	0.1%	6.0 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	68966	100.0%	11.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	457065	55.0%
Negative hits	330992	39.9%
Do not exist	41762	5.0%
Cache misses	755	0.1%
TOTAL: 4	830574	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.514	0.0%	207	0.000	7.314	69.333
article cleanup	00:00:00.277	0.0%	41757	0.000	0.007	0.025
article logging	00:00:00.671	0.0%	42307	0.000	0.016	0.426
article parse	00:00:01.627	0.0%	144465	0.002	0.011	0.079
article write	00:07:21.393	0.5%	24110	0.779	18.307	60.313
artlog/artcncl	00:00:00.001	0.0%	176	0.000	0.006	0.043
artlog/artparse	00:00:00.005	0.0%	340	0.000	0.015	1.000
data move	00:00:00.287	0.0%	769580	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.321	0.0%	204	0.000	1.574	11.000
hishave/artcncl	00:00:00.080	0.0%	207	0.000	0.386	13.333
hishave/artparse	00:00:00.002	0.0%	339	0.000	0.006	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:09.709	0.0%	836017	0.002	0.012	0.224
history sync	00:00:01.984	0.0%	356	0.000	5.573	63.333
history write	00:00:41.433	0.0%	41698	0.091	0.994	18.204
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hiswrite/artparse	00:00:00.280	0.0%	336	0.000	0.833	8.000
idle	23:47:55.147	99.1%	774705	66.211	110.591	340.097
nntp read	00:00:02.790	0.0%	794289	0.001	0.004	0.006
overview write	00:01:53.197	0.1%	24110	0.208	4.695	161.495
perl filter	00:01:48.914	0.1%	41696	1.647	2.612	3.773
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.453	0.0%	48271	0.000	0.009	0.036
TOTAL: 24:00:52.274	24:00:00.085	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.085	0.0%	24290	0.000	0.003	0.029
article prepare	00:00:00.003	0.0%	22660	0.000	0.000	0.005
article read	00:00:03.734	0.0%	3305	0.000	1.130	14.400
backlog stats	00:00:00.239	0.0%	449418	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.907	0.0%	342830	0.001	0.006	0.012
data write	00:00:02.383	0.0%	326939	0.003	0.007	0.016
idle	23:53:06.460	100.0%	449418	110.906	191.328	576.647
status file	00:00:00.480	0.0%	319	0.000	1.505	13.000
TOTAL: 23:53:24.017	23:53:15.291	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.122	0.0%	964	0.000	0.127	1.000
idle	57:53:44.035	100.0%	1347	250.000	154732.023	900523.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.036	0.0%	2811	0.000	0.013	0.250
readart	00:00:11.514	0.0%	3472	0.000	3.316	72.000
TOTAL: 57:54:27.131	57:53:55.707	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	137
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	157

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	131296	5874	118351	7071	4%	94:58:46
2	csiph-out.news.weretis.net	3	55356	5239	48882	1235	9%	48:11:07
3	feeder.eternal-september.org	2	37419	3491	32604	1324	9%	24:14:48
4	news-out.mixmin.net	3	27115	2140	24260	715	7%	24:11:55
5	news-out.aioe.org	2	23763	2064	21671	28	8%	24:09:56
6	newsfeed.neva.ru	145	49695	1736	41122	6837	3%	71:51:05
7	newsfeeds.xmission.com	1	24059	1061	22972	26	4%	23:25:36
8	newsfeed.fu-berlin.de	60	25045	1048	23649	348	4%	34:43:44
9	news.fcku.it	2	10864	628	10123	113	5%	23:59:51
10	news.redatomik.org	4	22213	618	21534	61	2%	21:11:14
11	xanadu-bbs.net	4	1581	337	1233	11	21%	38:49:35
12	news.swapon.de	2	3707	106	3601	0	2%	45:30:25
13	news.freedyn.net	3	27263	45	27172	46	0%	24:09:45
14	news.bbs.geek.nz	1	22133	6	22127	0	0%	23:59:51
15	nntp.pasdenom.info	2	39194	4	39085	105	0%	23:59:56
16	localhost	36	1	1	0	0	100%	00:00:00
TOTAL: 16		1422	500704	24398	458386	17920	4%	547:27:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	18.2 MB	602.2 KB	30.4 MB	49.1 MB	36%	3.9 KB
2	csiph-out.news.weretis.net	15.3 MB	0.0 KB	15.7 MB	31.0 MB	49%	4.9 KB
3	feeder.eternal-september.org	8.5 MB	0.0 KB	18.8 MB	27.4 MB	31%	5.8 KB
4	news-out.mixmin.net	7.7 MB	0.0 KB	2.8 MB	10.5 MB	73%	3.8 KB
5	news-out.aioe.org	5.2 MB	0.0 KB	49.9 KB	5.3 MB	99%	2.6 KB
6	newsfeed.neva.ru	5.0 MB	125.4 KB	62.7 MB	67.9 MB	7%	8.1 KB
7	newsfeeds.xmission.com	3.1 MB	0.0 KB	1.1 MB	4.2 MB	73%	4.0 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	943.1 KB	3.4 MB	72%	2.5 KB
9	news.redatomik.org	1.5 MB	0.0 KB	679.5 KB	2.2 MB	69%	3.3 KB
10	news.fcku.it	1.4 MB	0.0 KB	1.5 MB	2.8 MB	48%	3.9 KB
11	xanadu-bbs.net	882.7 KB	6.7 KB	986.2 KB	1.8 MB	47%	5.4 KB
12	news.swapon.de	282.0 KB	0.0 KB	0.0 KB	282.0 KB	100%	2.7 KB
13	news.freedyn.net	150.6 KB	0.0 KB	2.6 MB	2.8 MB	5%	31.3 KB
14	news.bbs.geek.nz	24.1 KB	0.0 KB	0.0 KB	24.1 KB	100%	4.0 KB
15	nntp.pasdenom.info	8.2 KB	0.0 KB	667.4 KB	675.6 KB	1%	6.2 KB
16	localhost	1.1 KB	0.0 KB	0.0 KB	1.1 KB	100%	1.1 KB
TOTAL: 16		69.7 MB	734.3 KB	138.9 MB	209.3 MB	33%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 6 04:15:00 - 04:59:59	457	1.9%	0.17	1.9 MB	2.7%	0.72
Oct 6 05:00:00 - 05:59:59	577	2.4%	0.16	2.1 MB	3.1%	0.60
Oct 6 06:00:00 - 06:59:59	697	2.9%	0.19	2.1 MB	3.0%	0.59
Oct 6 07:00:00 - 07:59:59	999	4.1%	0.28	3.2 MB	4.6%	0.91
Oct 6 08:00:00 - 08:59:59	1266	5.2%	0.35	3.3 MB	4.7%	0.93
Oct 6 09:00:00 - 09:59:59	1258	5.2%	0.35	3.3 MB	4.8%	0.94
Oct 6 10:00:00 - 10:59:59	1114	4.6%	0.31	3.3 MB	4.8%	0.94
Oct 6 11:00:00 - 11:59:59	1050	4.4%	0.29	2.7 MB	3.9%	0.77
Oct 6 12:00:00 - 12:59:59	1112	4.6%	0.31	2.8 MB	4.1%	0.80
Oct 6 13:00:00 - 13:59:59	1232	5.1%	0.34	3.0 MB	4.4%	0.87
Oct 6 14:00:00 - 14:59:59	1431	5.9%	0.40	4.1 MB	5.9%	1.16
Oct 6 15:00:00 - 15:59:59	1516	6.3%	0.42	4.1 MB	5.9%	1.16
Oct 6 16:00:00 - 16:59:59	1312	5.4%	0.36	3.5 MB	5.1%	0.99
Oct 6 17:00:00 - 17:59:59	1489	6.2%	0.41	4.8 MB	6.9%	1.36
Oct 6 18:00:00 - 18:59:59	1366	5.7%	0.38	3.9 MB	5.7%	1.12
Oct 6 19:00:00 - 19:59:59	1268	5.3%	0.35	3.4 MB	4.9%	0.96
Oct 6 20:00:00 - 20:59:59	1182	4.9%	0.33	3.2 MB	4.6%	0.90
Oct 6 21:00:00 - 21:59:59	1097	4.5%	0.30	2.9 MB	4.2%	0.82
Oct 6 22:00:00 - 22:59:59	979	4.1%	0.27	3.3 MB	4.8%	0.93
Oct 6 23:00:00 - 23:59:59	712	3.0%	0.20	2.2 MB	3.2%	0.63
Oct 7 00:00:00 - 00:59:59	582	2.4%	0.16	1.7 MB	2.5%	0.48
Oct 7 01:00:00 - 01:59:59	460	1.9%	0.13	1.4 MB	2.1%	0.41
Oct 7 02:00:00 - 02:59:59	377	1.6%	0.10	1.1 MB	1.6%	0.32
Oct 7 03:00:00 - 03:59:59	453	1.9%	0.13	1.4 MB	2.0%	0.39
Oct 7 04:00:00 - 04:15:00	146	0.6%	0.16	452.3 KB	0.6%	0.50
TOTAL: 24:00:00	24132	100.0%	0.28	69.0 MB	100.0%	0.82

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	7076	416	50	6610
2	newsfeed.neva.ru	6862	1333	10	5519
3	feeder.eternal-september.org	1362	82	0	1280
4	csiph-out.news.weretis.net	1276	318	0	958
5	newsfeed.fu-berlin.de	728	44	0	684
6	news-out.mixmin.net	726	17	0	709
7	news.fcku.it	120	0	0	120
8	nntp.pasdenom.info	104	1	0	103
9	news.redatomik.org	86	0	0	86
10	news.freedyn.net	44	0	0	44
11	news-out.aioe.org	43	26	0	17
12	newsfeeds.xmission.com	29	1	0	28
13	news.swapon.de	10	0	0	10
14	xanadu-bbs.net	9	0	0	9
TOTAL: 14		18475	2238	60	16177

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	818
lada.talk	227
lada.auto	202
ut.test	106
alt.windows7.general	65
alt.survival	55
it-alt.sport.calcio.juventus	54
fa.openbsd.source-changes	47
fa.freebsd.cvs-all	42
fa.openbsd.tech	35
cakewalk.audio	23
fa.freebsd.current	21
spline.eisfair	21
fa.philos-l	19
mpc.lists.freebsd.ports.bugs	18
mozilla.dev.security.policy	17
it.annunci.varie	16
alt.english.usage	16
it.annunci.immobiliari	15
it.annunci.commerciali	15
TOTAL: 151	2238

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11898
EMP (phn path)	2443
EMP (phl)	557
Too many newsgroups	75
EMP (phn nph)	71
Binary Payload	63
Too many newsgroups (low_xpost)	34
Scoring filter (18)	23
HTML Multipart	11
Scoring filter (19)	11
User-issued cancel	10
Scoring filter (11)	9
HTML post	7
Scoring filter (10)	7
Scoring filter (8)	7
Binary: misplaced par2	6
Scoring filter (9)	6
Scoring filter (13)	6
Binary: non-binary distribution	5
Binary: misplaced nfo	4
TOTAL: 33	15284

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	50	0	52	52
TOTAL: 1	50	0	52	52

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	feeder.eternal-september.org	137
	csiph-out.news.weretis.net	90
	news-out.mixmin.net	29
	newsfeeds.xmission.com	27
	news.freedyn.net	21
	news.fcku.it	17
	news.redatomik.org	5
	newsfeed.fu-berlin.de	4
	nntp.pasdenom.info	3
	xanadu-bbs.net	3
	TOTAL: 10	336
Including strange strings
	newsfeed.fu-berlin.de	355
	csiph-out.news.weretis.net	51
	feeder.eternal-september.org	46
	csiph-out.feeder.erje.net	29
	news-out.mixmin.net	22
	news-out.aioe.org	15
	newsfeed.neva.ru	13
	news.redatomik.org	11
	news.swapon.de	10
	xanadu-bbs.net	1
	TOTAL: 12	555
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		896

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	43963	2057	21376	38	0	4%	23:53:12
2	news.freedyn.net	25428	1007	23069	5	0	3%	23:59:18
3	xanadu-bbs	25892	615	23311	1	10	2%	23:59:52
4	fu-berlin.de	22774	550	22199	0	123	2%	23:56:22
5	usenet.pasdenom.info	26785	206	22999	923	0	0%	23:59:49
6	xmission	26690	104	22259	5	0	0%	23:50:03
7	weretis.net	16923	37	16235	2	0	0%	23:50:03
8	news.redatomik.org	24492	25	22787	6	95	0%	23:56:18
9	erje	18180	16	18067	32	0	0%	23:50:03
10	aioe	21271	13	20175	0	0	0%	23:50:03
11	news.mixmin.net	21745	7	20944	0	0	0%	23:51:45
12	eternal-september	21916	4	19618	0	0	0%	23:58:02
13	neva.ru	22910	2	21316	18	1	0%	23:52:10
14	news.swapon.de	2095	1	2033	1	0	0%	23:51:36
15	au2pb	0	0	0	0	23471	0%	23:50:04
16	bwh	0	0	0	0	23466	0%	23:50:04
17	news.fcku.it	3401	0	3252	0	0	0%	22:47:13
TOTAL: 17		324465	4644	279640	1031	47166	1%	405:05:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.1 MB	30.2 KB	6.1 MB	0.1 KB/s	6.2 KB	23:59:18
2	xanadu-bbs	5.2 MB	1.5 KB	5.2 MB	0.1 KB/s	8.6 KB	23:59:52
3	news.bbs.geek.nz	5.1 MB	101.2 KB	5.2 MB	0.1 KB/s	2.5 KB	23:53:12
4	fu-berlin.de	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	9.3 KB	23:56:22
5	usenet.pasdenom.info	573.0 KB	5.6 MB	6.2 MB	0.1 KB/s	5.6 KB	23:59:49
6	xmission	362.1 KB	11.4 KB	373.5 KB	0.0 KB/s	3.4 KB	23:50:03
7	weretis.net	140.8 KB	42.7 KB	183.6 KB	0.0 KB/s	4.7 KB	23:50:03
8	news.redatomik.org	77.5 KB	21.7 KB	99.2 KB	0.0 KB/s	3.2 KB	23:56:18
9	aioe	31.6 KB	0.0 KB	31.6 KB	0.0 KB/s	2.4 KB	23:50:03
10	erje	30.6 KB	176.2 KB	206.8 KB	0.0 KB/s	4.3 KB	23:50:03
11	news.mixmin.net	26.2 KB	0.0 KB	26.2 KB	0.0 KB/s	3.7 KB	23:51:45
12	eternal-september	7.3 KB	0.0 KB	7.3 KB	0.0 KB/s	1.8 KB	23:58:02
13	neva.ru	5.2 KB	62.9 KB	68.1 KB	0.0 KB/s	3.4 KB	23:52:10
14	news.swapon.de	1.1 KB	5.5 KB	6.6 KB	0.0 KB/s	3.3 KB	23:51:36
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:47:13
TOTAL: 17		22.6 MB	6.0 MB	28.7 MB	0.0 KB/s	5.2 KB	405:05:57

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	challenger.nielsenmedia.com	6	8	17.7 KB	2	0	0	02:34:19
2	tempe0.bbox.io	44	6	24.0 KB	4	0	0	06:10:36
3	92-108-162-83.dynamic.upc.nl	2	1	1.1 KB	1	0	0	00:01:12
4	68-185-181-64.dhcp.mdsn.wi.charter.com	3	1	1.1 KB	1	1	1	00:57:25
TOTAL: 4		55	16	43.9 KB	8	1	1	57:54:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.nielsenmedia.com	6	8	17.7 KB	2	0	0	02:34:19
2	*.bbox.io	44	6	24.0 KB	4	0	0	06:10:36
3	*.dynamic.upc.nl	2	1	1.1 KB	1	0	0	00:01:12
4	*.dhcp.mdsn.wi.charter.com	3	1	1.1 KB	1	1	1	00:57:25
5	*.chello.nl	16	0	0.0 KB	0	0	0	02:34:29
6	unresolved	86	0	0.0 KB	0	0	0	44:06:25
7	*.2.89.75.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	01:29:56
TOTAL: 7		158	16	43.9 KB	8	1	1	57:54:25

NNRP auth users [Top 20]:

User	Conn
ohlandl	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.246.166.122	6.450	1.816	0.000	42:31:01
tempe0.bbox.io	3.709	1.007	0.000	06:10:36
dhcp-089-099-203-254.chello.nl	1.287	0.274	0.000	02:34:29
challenger.nielsenmedia.com	0.577	0.161	0.000	02:34:19
47.192.187.101	0.339	0.056	0.000	01:35:05
h114.2.89.75.dynamic.ip.windstream.net	0.133	0.050	0.000	01:29:56
68-185-181-64.dhcp.mdsn.wi.charter.com	0.288	0.079	0.003	00:57:25
92-108-162-83.dynamic.upc.nl	0.156	0.062	0.000	00:01:12
196.52.16.28	0.181	0.415	0.000	00:00:18
TOTAL: 9	13.120	3.920	0.003	57:54:25

Curious NNRP server explorers [Top 20]:

System	Conn
216.246.166.122	81
dhcp-089-099-203-254.chello.nl	16
47.192.187.101	4
196.52.16.28	1
h114.2.89.75.dynamic.ip.windstream.net	1
TOTAL: 5	103

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.246.166.122	85	0
tempe0.bbox.io	11	0
challenger.nielsenmedia.com	5	0
47.192.187.101	2	0
h114.2.89.75.dynamic.ip.windstream.net	1	0
68-185-181-64.dhcp.mdsn.wi.charter.com	1	0
TOTAL: 6	105	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	11	68.8%
2	muc	5	31.2%
TOTAL: 2		16	100.0%

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

Newsgroup		Count
1	comp.lang.awk	8
2	muc.lists.freebsd.stable	5
3	comp.sys.ibm.ps2.hardware	3
TOTAL: 3		16

Daily Usenet report for csiph.com

Oct 6 04:15:00 -- Oct 7 04:15:00