Daily Usenet report for csiph.com: Jun 19 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	43693	40.0%	3.8 MB	26.9%
inn	39845	36.5%	7.4 MB	52.4%
innd	22376	20.5%	2.4 MB	16.8%
innfeed	3166	2.9%	554.5 KB	3.8%
nocem	114	0.1%	11.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	109195	100.0%	14.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	432942	52.7%
Negative hits	348613	42.5%
Do not exist	38931	4.7%
Cache misses	627	0.1%
TOTAL: 4	821113	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.386	0.0%	273	0.000	1.414	24.400
article cleanup	00:00:00.272	0.0%	38545	0.000	0.007	0.025
article logging	00:00:00.534	0.0%	39052	0.000	0.014	0.107
article parse	00:00:01.290	0.0%	138959	0.000	0.009	0.018
article write	00:01:06.311	0.1%	21474	0.216	3.088	42.584
artlog/artcncl	00:00:00.002	0.0%	200	0.000	0.010	0.111
artlog/artparse	00:00:00.008	0.0%	608	0.000	0.013	0.500
data move	00:00:00.280	0.0%	781171	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.021	0.0%	264	0.000	0.080	12.000
hishave/artcncl	00:00:00.001	0.0%	273	0.000	0.004	0.200
hishave/artparse	00:00:00.000	0.0%	166	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:03.719	0.0%	825237	0.001	0.005	0.040
history sync	00:00:01.025	0.0%	357	0.000	2.871	17.667
history write	00:00:16.644	0.0%	38952	0.065	0.427	1.969
hiswrite/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hiswrite/artparse	00:00:00.054	0.0%	166	0.000	0.325	7.000
idle	23:55:46.545	99.6%	797138	60.330	108.070	251.116
nntp read	00:00:02.831	0.0%	803553	0.002	0.004	0.006
overview write	00:01:07.470	0.1%	21474	0.155	3.142	73.122
perl filter	00:01:35.682	0.1%	38372	1.348	2.494	5.381
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.365	0.0%	43053	0.000	0.008	0.056
TOTAL: 24:00:54.350	24:00:03.440	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.064	0.0%	21500	0.000	0.003	0.030
article prepare	00:00:00.004	0.0%	27528	0.000	0.000	0.010
article read	00:00:00.997	0.0%	3377	0.000	0.295	17.222
backlog stats	00:00:00.207	0.0%	483899	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.821	0.0%	361135	0.001	0.005	0.010
data write	00:00:02.431	0.0%	342857	0.003	0.007	0.014
idle	23:53:45.459	100.0%	483899	97.689	177.776	464.431
status file	00:00:00.359	0.0%	268	0.000	1.340	110.000
TOTAL: 23:53:59.479	23:53:51.342	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.071	0.0%	2078	0.000	0.034	0.069
idle	29:27:39.313	98.7%	57324	81.443	1850.173	600063.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.666	0.0%	115616	0.000	0.006	0.143
readart	00:00:17.381	0.0%	9677	0.000	1.796	62.000
TOTAL: 29:50:17.045	29:27:57.431	98.8%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
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	newsfeed.neva.ru	144	37528	4808	26454	6266	12%	72:02:21
2	csiph-out.feeder.erje.net	1152	123151	4612	112273	6266	3%	95:08:10
3	feeder.eternal-september.org	5	31782	3078	27512	1192	9%	24:37:35
4	csiph-out.news.weretis.net	4	48466	2993	44539	934	6%	48:15:04
5	usenet.blueworldhosting.com	288	25172	1584	21856	1732	6%	23:44:46
6	news-out.aioe.org	3	21196	1229	19947	20	5%	24:09:54
7	newsfeed.fu-berlin.de	28	20784	952	19518	314	4%	25:44:40
8	news-out.mixmin.net	3	24558	569	23348	641	2%	24:56:11
9	news.freedyn.net	3	24205	477	23636	92	1%	24:09:48
10	news.fcku.it	1	7693	370	7246	77	4%	22:00:08
11	news.redatomik.org	2	39189	327	38835	27	0%	44:52:15
12	newsfeeds.xmission.com	1	11757	112	11634	11	0%	15:15:23
13	au2pb.net	1	23014	88	22894	32	0%	24:12:48
14	news.swapon.de	3	1917	82	1835	0	4%	25:01:03
15	nntp.pasdenom.info	2	21004	29	20971	4	0%	23:59:24
16	news.bbs.geek.nz	2	20363	15	20348	0	0%	23:59:48
17	localhost	50	14	14	0	0	100%	00:00:00
18	xanadu-bbs.net	19	551	4	547	0	0%	22:33:46
TOTAL: 18		1711	482344	21343	443393	17608	4%	564:43:04

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	18.1 MB	198.5 KB	19.7 MB	38.0 MB	47%	3.6 KB
2	newsfeed.neva.ru	14.0 MB	174.4 KB	48.4 MB	62.6 MB	22%	5.8 KB
3	csiph-out.news.weretis.net	8.9 MB	0.0 KB	7.6 MB	16.6 MB	53%	4.3 KB
4	feeder.eternal-september.org	7.7 MB	1.9 KB	14.6 MB	22.3 MB	34%	5.3 KB
5	usenet.blueworldhosting.com	6.1 MB	0.0 KB	16.6 MB	22.8 MB	26%	7.0 KB
6	news-out.aioe.org	2.9 MB	0.0 KB	32.7 KB	2.9 MB	98%	2.4 KB
7	news-out.mixmin.net	2.6 MB	4.9 KB	1.3 MB	4.0 MB	66%	3.4 KB
8	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	740.9 KB	2.8 MB	74%	2.3 KB
9	news.freedyn.net	1.3 MB	0.0 KB	1.1 MB	2.4 MB	54%	4.3 KB
10	news.redatomik.org	1.1 MB	0.0 KB	1.9 MB	3.0 MB	36%	8.6 KB
11	news.fcku.it	755.6 KB	0.0 KB	2.6 MB	3.3 MB	22%	7.6 KB
12	newsfeeds.xmission.com	375.6 KB	0.0 KB	632.0 KB	1007.6 KB	37%	8.2 KB
13	au2pb.net	243.9 KB	0.0 KB	115.9 KB	359.8 KB	67%	3.0 KB
14	news.swapon.de	223.6 KB	0.0 KB	0.0 KB	223.6 KB	100%	2.7 KB
15	nntp.pasdenom.info	74.6 KB	0.0 KB	10.2 KB	84.8 KB	87%	2.6 KB
16	news.bbs.geek.nz	26.5 KB	0.0 KB	0.0 KB	26.5 KB	100%	1.8 KB
17	localhost	22.5 KB	0.0 KB	0.0 KB	22.5 KB	100%	1.6 KB
18	xanadu-bbs.net	9.4 KB	0.0 KB	0.0 KB	9.4 KB	100%	2.4 KB
TOTAL: 18		66.6 MB	379.7 KB	115.3 MB	182.2 MB	36%	4.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 19 04:15:00 - 04:59:59	317	1.5%	0.12	987.0 KB	1.4%	0.37
Jun 19 05:00:00 - 05:59:59	486	2.3%	0.14	1.7 MB	2.5%	0.48
Jun 19 06:00:00 - 06:59:59	563	2.6%	0.16	1.9 MB	2.7%	0.53
Jun 19 07:00:00 - 07:59:59	665	3.1%	0.18	1.7 MB	2.6%	0.50
Jun 19 08:00:00 - 08:59:59	704	3.3%	0.20	1.8 MB	2.7%	0.52
Jun 19 09:00:00 - 09:59:59	1042	4.8%	0.29	4.1 MB	6.0%	1.16
Jun 19 10:00:00 - 10:59:59	1086	5.1%	0.30	3.8 MB	5.7%	1.09
Jun 19 11:00:00 - 11:59:59	1040	4.8%	0.29	3.5 MB	5.1%	0.99
Jun 19 12:00:00 - 12:59:59	867	4.0%	0.24	2.3 MB	3.5%	0.66
Jun 19 13:00:00 - 13:59:59	1196	5.6%	0.33	3.6 MB	5.4%	1.03
Jun 19 14:00:00 - 14:59:59	1177	5.5%	0.33	3.5 MB	5.2%	0.99
Jun 19 15:00:00 - 15:59:59	1174	5.5%	0.33	3.5 MB	5.2%	1.00
Jun 19 16:00:00 - 16:59:59	1387	6.5%	0.39	4.3 MB	6.3%	1.22
Jun 19 17:00:00 - 17:59:59	1300	6.0%	0.36	3.6 MB	5.3%	1.02
Jun 19 18:00:00 - 18:59:59	1291	6.0%	0.36	4.3 MB	6.3%	1.22
Jun 19 19:00:00 - 19:59:59	1172	5.5%	0.33	4.0 MB	6.0%	1.15
Jun 19 20:00:00 - 20:59:59	1190	5.5%	0.33	3.3 MB	4.9%	0.94
Jun 19 21:00:00 - 21:59:59	1157	5.4%	0.32	3.6 MB	5.4%	1.04
Jun 19 22:00:00 - 22:59:59	778	3.6%	0.22	2.5 MB	3.8%	0.72
Jun 19 23:00:00 - 23:59:59	630	2.9%	0.17	1.9 MB	2.7%	0.53
Jun 20 00:00:00 - 00:59:59	550	2.6%	0.15	2.1 MB	3.1%	0.59
Jun 20 01:00:00 - 01:59:59	473	2.2%	0.13	1.2 MB	1.8%	0.35
Jun 20 02:00:00 - 02:59:59	539	2.5%	0.15	1.8 MB	2.6%	0.50
Jun 20 03:00:00 - 03:59:59	558	2.6%	0.15	1.9 MB	2.9%	0.55
Jun 20 04:00:00 - 04:15:00	146	0.7%	0.16	573.8 KB	0.8%	0.64
TOTAL: 24:00:00	21488	100.0%	0.25	67.5 MB	100.0%	0.80

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	6318	341	21	5956
2	newsfeed.neva.ru	6266	683	11	5572
3	usenet.blueworldhosting.com	1731	707	0	1024
4	feeder.eternal-september.org	1242	99	0	1143
5	csiph-out.news.weretis.net	942	157	0	785
6	newsfeed.fu-berlin.de	719	32	0	687
7	news-out.mixmin.net	650	1	3	646
8	news.freedyn.net	95	1	0	94
9	news.fcku.it	80	0	0	80
10	au2pb.net	40	35	0	5
11	news-out.aioe.org	22	15	0	7
12	news.redatomik.org	20	0	0	20
13	newsfeeds.xmission.com	18	9	0	9
14	news.swapon.de	11	0	0	11
15	nntp.pasdenom.info	3	3	0	0
TOTAL: 15		18157	2083	35	16039

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	241
ut.test	138
mailing.freebsd.pkg-fallout	135
lada.talk	123
mailing.freebsd.ports-bugs	91
it-alt.sport.calcio.juventus	77
mozilla.general	55
mailing.freebsd.svn-ports-all	37
alt.windows7.general	35
mailing.freebsd.svn-ports-head	35
mailing.freebsd.svn-src-head	34
mailing.openbsd.misc	34
mailing.freebsd.svn-src-all	34
lada.auto	30
mailing.freebsd.python	29
cakewalk.audio	29
fa.freebsd.bugs	29
mailing.freebsd.bugs	29
fa.freebsd.cvs-all	27
alt.survival	25
TOTAL: 199	2083

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10225
EMP (phn path)	1998
PFM bot misc	1344
EMP (phl)	809
EMP (phn nph)	168
Too many newsgroups	52
Scoring filter (11)	27
Scoring filter (9)	23
HTML Multipart	21
Binary Payload	16
Scoring filter (10)	15
Scoring filter (13)	14
Scoring filter (12)	14
Scoring filter (8)	12
Scoring filter (14)	11
Scoring filter (15)	9
Scoring filter (16)	8
User-issued cancel	7
Scoring filter (18)	3
HTML post	3
TOTAL: 27	14786

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	104	0	110	110
TOTAL: 1	104	0	110	110

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	135
	usenet.blueworldhosting.com	3
	TOTAL: 2	138
Huge articles
	usenet.blueworldhosting.com	49
	csiph-out.news.weretis.net	30
	feeder.eternal-september.org	27
	news.redatomik.org	19
	news.fcku.it	15
	news.freedyn.net	10
	news-out.mixmin.net	8
	newsfeeds.xmission.com	8
	newsfeed.fu-berlin.de	1
	TOTAL: 9	167
Including strange strings
	newsfeed.fu-berlin.de	358
	csiph-out.news.weretis.net	49
	csiph-out.feeder.erje.net	48
	feeder.eternal-september.org	21
	news.swapon.de	11
	newsfeed.neva.ru	8
	news-out.mixmin.net	5
	news-out.aioe.org	3
	news.fcku.it	1
	news.redatomik.org	1
	TOTAL: 11	506
No colon-space in header
	csiph-out.feeder.erje.net	442
	TOTAL: 1	442
TOTAL: 4		1253

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	39394	1255	19263	25	0	3%	23:59:55
2	news.freedyn.net	22508	1087	19914	3	0	4%	23:59:49
3	xanadu-bbs	24908	976	20501	2	26	3%	23:59:54
4	fu-berlin.de	20207	898	19308	0	0	4%	23:59:51
5	erje	18038	622	16107	10	0	3%	23:59:53
6	weretis.net	18350	123	16777	31	0	0%	23:59:39
7	aioe	19595	89	18456	14	0	0%	23:59:54
8	usenet.pasdenom.info	24280	89	19988	1381	8	0%	23:59:36
9	news.redatomik.org	21759	77	19996	11	0	0%	23:59:53
10	au2pb	22649	45	20408	0	0	0%	23:59:55
11	xmission	25557	24	20258	0	0	0%	23:59:55
12	news.mixmin.net	21045	22	19589	0	31	0%	23:59:54
13	eternal-september	18800	21	17132	0	0	0%	23:59:53
14	bwh	21559	14	18876	0	0	0%	23:59:53
15	news.swapon.de	1880	4	1822	10	0	0%	23:51:07
16	neva.ru	16051	3	14602	73	0	0%	23:59:55
17	news.fcku.it	1665	0	1641	0	0	0%	22:59:49
TOTAL: 17		338245	5349	284638	1560	65	1%	406:48:45

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.5 MB	5.6 KB	9.5 MB	0.1 KB/s	8.9 KB	23:59:49
2	xanadu-bbs	9.1 MB	3.0 KB	9.1 MB	0.1 KB/s	9.5 KB	23:59:54
3	fu-berlin.de	8.9 MB	0.0 KB	8.9 MB	0.1 KB/s	10.2 KB	23:59:51
4	news.bbs.geek.nz	3.1 MB	39.3 KB	3.1 MB	0.0 KB/s	2.5 KB	23:59:55
5	erje	2.2 MB	93.2 KB	2.3 MB	0.0 KB/s	3.7 KB	23:59:53
6	weretis.net	323.2 KB	75.4 KB	398.6 KB	0.0 KB/s	2.6 KB	23:59:39
7	usenet.pasdenom.info	268.9 KB	9.7 MB	10.0 MB	0.1 KB/s	6.9 KB	23:59:36
8	news.redatomik.org	267.1 KB	33.6 KB	300.8 KB	0.0 KB/s	3.4 KB	23:59:53
9	aioe	266.4 KB	43.8 KB	310.2 KB	0.0 KB/s	3.0 KB	23:59:54
10	au2pb	99.3 KB	0.0 KB	99.3 KB	0.0 KB/s	2.2 KB	23:59:55
11	xmission	72.6 KB	0.0 KB	72.6 KB	0.0 KB/s	3.0 KB	23:59:55
12	news.mixmin.net	52.2 KB	0.0 KB	52.2 KB	0.0 KB/s	2.4 KB	23:59:54
13	eternal-september	47.0 KB	0.0 KB	47.0 KB	0.0 KB/s	2.2 KB	23:59:53
14	bwh	25.1 KB	0.0 KB	25.1 KB	0.0 KB/s	1.8 KB	23:59:53
15	news.swapon.de	8.0 KB	14.5 KB	22.5 KB	0.0 KB/s	1.6 KB	23:51:07
16	neva.ru	3.0 KB	517.1 KB	520.1 KB	0.0 KB/s	6.8 KB	23:59:55
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:59:49
TOTAL: 17		34.1 MB	10.5 MB	44.6 MB	0.0 KB/s	6.6 KB	406:48:45

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	55	128.4 KB	48	0	0	04:50:24
2	68-185-181-64.dhcp.mdsn.wi.charter.com	31	15	27.0 KB	12	13	13	06:39:30
3	tempe0.bbox.io	16	13	19.5 KB	4	1	1	04:28:27
4	mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	120	9	28.0 KB	8	0	0	00:34:05
5	dhcp-089-099-203-254.chello.nl	21	5	6.5 KB	3	0	0	05:31:11
6	challenger.nielsenmedia.com	5	4	8.6 KB	1	0	0	00:31:15
7	71.98.160.36	4	4	8.6 KB	1	0	0	01:19:47
TOTAL: 7		485	105	226.8 KB	77	14	14	29:50:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	296	59	137.1 KB	49	0	0	12:04:40
2	*.dhcp.mdsn.wi.charter.com	31	15	27.0 KB	12	13	13	06:39:30
3	*.bbox.io	16	13	19.5 KB	4	1	1	04:28:27
4	*.dsl.bell.ca	120	9	28.0 KB	8	0	0	00:34:05
5	*.chello.nl	21	5	6.5 KB	3	0	0	05:31:11
6	*.nielsenmedia.com	5	4	8.6 KB	1	0	0	00:31:15
7	*.rev.numericable.fr	2	0	0.0 KB	0	0	0	00:01:02
TOTAL: 7		491	105	226.8 KB	77	14	14	29:50:12

NNRP auth users [Top 20]:

User	Conn
moussa	286
ohlandl	13
kev009	1
TOTAL: 3	300

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
68-185-181-64.dhcp.mdsn.wi.charter.com	3.372	0.699	0.032	06:39:30
216.239.174.89	0.342	0.257	0.000	05:54:28
dhcp-089-099-203-254.chello.nl	1.926	0.336	0.000	05:31:11
202.89.176.220	29.373	7.558	0.848	04:50:24
tempe0.bbox.io	1.493	0.272	0.003	04:28:27
71.98.160.36	0.503	0.080	0.000	01:19:47
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	14.588	3.983	0.000	00:34:05
challenger.nielsenmedia.com	0.415	0.108	0.000	00:31:15
81-67-242-59.rev.numericable.fr	0.220	0.040	0.000	00:01:02
TOTAL: 9	52.232	13.333	0.883	29:50:12

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.89	4
81-67-242-59.rev.numericable.fr	2
TOTAL: 2	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	8	0
68-185-181-64.dhcp.mdsn.wi.charter.com	3	0
71.98.160.36	2	0
challenger.nielsenmedia.com	1	1
TOTAL: 4	14	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	97	92.4%
2	alt	4	3.8%
3	uk	3	2.9%
4	rec	1	1.0%
TOTAL: 4		105	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	47
2	comp.os.vms	28
3	comp.lang.awk	8
4	comp.arch	7
5	comp.os.cpm	4
6	alt.home.repair	2
7	uk.rec.sheds	1
8	alt.sports.basketball.nba.gs-warriors	1
9	alt.atheism	1
10	comp.arch.arithmetic	1
11	uk.legal	1
12	rec.autos.sport.f1	1
13	comp.arch.fpga	1
14	comp.mobile.ipad	1
15	uk.media.radio.archers	1
TOTAL: 15		105

Daily Usenet report for csiph.com

Jun 19 04:15:00 -- Jun 20 04:15:00