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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	37095	65.2%	7.8 MB	76.0%
innd	15679	27.6%	1.8 MB	17.7%
innfeed	3189	5.6%	554.0 KB	5.3%
nnrpd	869	1.5%	96.8 KB	0.9%
nocem	74	0.1%	7.8 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	56907	100.0%	10.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	436226	55.5%
Negative hits	312480	39.8%
Do not exist	36185	4.6%
Cache misses	574	0.1%
TOTAL: 4	785465	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.082	0.0%	255	0.000	4.243	37.000
article cleanup	00:00:00.231	0.0%	36071	0.000	0.006	0.022
article logging	00:00:00.859	0.0%	36621	0.000	0.023	1.574
article parse	00:00:03.149	0.0%	102922	0.000	0.031	0.633
article write	00:04:38.925	0.3%	25116	0.469	11.105	55.754
artlog/artcncl	00:00:00.005	0.0%	227	0.000	0.022	0.500
artlog/artparse	00:00:00.030	0.0%	254	0.000	0.118	1.100
data move	00:00:00.202	0.0%	738784	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.039	0.0%	255	0.000	0.153	1.333
hishave/artcncl	00:00:00.054	0.0%	255	0.000	0.212	1.714
hishave/artparse	00:00:00.000	0.0%	253	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:18.150	0.0%	787138	0.002	0.023	0.749
history sync	00:00:01.699	0.0%	363	0.000	4.680	48.500
history write	00:00:46.169	0.1%	36040	0.083	1.281	30.819
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:02.028	0.0%	253	0.000	8.016	58.000
idle	23:50:53.532	99.3%	740752	66.272	115.901	344.001
nntp read	00:00:02.472	0.0%	752756	0.002	0.003	0.007
overview write	00:02:01.040	0.1%	25116	0.296	4.819	94.254
perl filter	00:01:13.592	0.1%	36012	1.648	2.044	3.794
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.434	0.0%	50299	0.000	0.009	0.028
TOTAL: 24:00:53.095	24:00:03.692	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.102	0.0%	25085	0.000	0.004	0.028
article prepare	00:00:00.004	0.0%	28484	0.000	0.000	0.004
article read	00:00:03.138	0.0%	4400	0.000	0.713	19.750
backlog stats	00:00:00.205	0.0%	449720	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.812	0.0%	350507	0.002	0.005	0.010
data write	00:00:02.408	0.0%	332841	0.002	0.007	0.014
idle	23:53:43.368	100.0%	449720	114.282	191.282	596.699
status file	00:00:00.723	0.0%	315	0.000	2.295	52.000
TOTAL: 23:53:59.477	23:53:51.760	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.002	0.0%	47	0.000	0.043	1.000
idle	46:31:25.227	99.8%	477	283.000	351122.069	900165.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:25.194	0.0%	46673	0.000	0.540	11.375
readart	00:00:00.160	0.0%	21	0.000	7.619	49.000
TOTAL: 46:36:26.618	46:31:50.583	99.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	csiph-out.news.weretis.net	3	52174	5299	45978	897	10%	46:40:57
2	csiph-out.feeder.erje.net	1152	121835	4859	110324	6652	3%	94:57:52
3	feeder.eternal-september.org	5	31096	3187	27609	300	10%	24:37:38
4	news-out.aioe.org	3	24045	2490	21530	25	10%	24:09:46
5	news-out.mixmin.net	3	27821	2347	24598	876	8%	24:08:52
6	newsfeeds.xmission.com	1	23608	2096	21488	24	8%	21:38:04
7	newsfeed.neva.ru	147	42435	1830	39084	1521	4%	72:21:36
8	newsfeed.fu-berlin.de	55	27499	1252	25770	477	4%	34:52:18
9	news.redatomik.org	1	26577	1195	25226	156	4%	27:10:11
10	news.fcku.it	1	10893	376	10385	132	3%	23:59:58
11	xanadu-bbs.net	14	649	151	487	11	23%	24:02:21
12	news.freedyn.net	2	26982	32	26936	14	0%	23:59:02
13	news.swapon.de	3	1566	29	1534	3	1%	23:25:40
14	nntp.pasdenom.info	2	32979	4	32938	37	0%	23:59:46
15	news.bbs.geek.nz	2	22630	3	22627	0	0%	23:59:50
16	localhost	35	1	1	0	0	100%	00:00:00
TOTAL: 16		1429	472790	25151	436514	11125	5%	514:03:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	17.6 MB	23.1 KB	18.8 MB	36.4 MB	48%	3.2 KB
2	csiph-out.news.weretis.net	14.6 MB	2.6 KB	7.7 MB	22.3 MB	65%	3.7 KB
3	feeder.eternal-september.org	8.4 MB	1.0 KB	9.7 MB	18.1 MB	46%	5.3 KB
4	news-out.mixmin.net	8.1 MB	22.4 KB	1.9 MB	9.9 MB	81%	3.2 KB
5	news-out.aioe.org	5.9 MB	2.4 KB	51.1 KB	5.9 MB	99%	2.4 KB
6	newsfeeds.xmission.com	5.6 MB	0.0 KB	1.4 MB	7.0 MB	80%	3.4 KB
7	newsfeed.neva.ru	5.2 MB	4.8 KB	3.3 MB	8.4 MB	61%	2.6 KB
8	news.redatomik.org	3.3 MB	0.0 KB	1.9 MB	5.2 MB	63%	4.0 KB
9	newsfeed.fu-berlin.de	2.6 MB	2.0 KB	933.7 KB	3.5 MB	74%	2.1 KB
10	news.fcku.it	938.2 KB	0.0 KB	1.1 MB	2.0 MB	45%	4.1 KB
11	xanadu-bbs.net	377.2 KB	0.0 KB	23.3 KB	400.5 KB	94%	2.5 KB
12	news.freedyn.net	140.1 KB	0.0 KB	665.9 KB	805.9 KB	17%	17.5 KB
13	news.swapon.de	59.5 KB	0.0 KB	638.5 KB	698.0 KB	8%	21.8 KB
14	nntp.pasdenom.info	9.3 KB	0.0 KB	359.3 KB	368.6 KB	2%	9.0 KB
15	news.bbs.geek.nz	4.2 KB	0.0 KB	0.0 KB	4.2 KB	100%	1.4 KB
16	localhost	1.8 KB	0.0 KB	0.0 KB	1.8 KB	100%	1.8 KB
TOTAL: 16		72.6 MB	58.4 KB	48.4 MB	121.1 MB	59%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 13 04:15:00 - 04:59:59	305	1.2%	0.11	1.2 MB	1.6%	0.44
Oct 13 05:00:00 - 05:59:59	535	2.1%	0.15	2.2 MB	3.0%	0.62
Oct 13 06:00:00 - 06:59:59	806	3.2%	0.22	2.7 MB	3.8%	0.78
Oct 13 07:00:00 - 07:59:59	1059	4.2%	0.29	3.1 MB	4.3%	0.90
Oct 13 08:00:00 - 08:59:59	1026	4.1%	0.28	2.6 MB	3.6%	0.74
Oct 13 09:00:00 - 09:59:59	1303	5.2%	0.36	3.6 MB	4.9%	1.01
Oct 13 10:00:00 - 10:59:59	1256	5.0%	0.35	3.5 MB	4.8%	1.00
Oct 13 11:00:00 - 11:59:59	1152	4.6%	0.32	2.8 MB	3.8%	0.79
Oct 13 12:00:00 - 12:59:59	1164	4.6%	0.32	2.6 MB	3.6%	0.75
Oct 13 13:00:00 - 13:59:59	1317	5.2%	0.37	3.2 MB	4.4%	0.91
Oct 13 14:00:00 - 14:59:59	1413	5.6%	0.39	3.9 MB	5.4%	1.11
Oct 13 15:00:00 - 15:59:59	1519	6.0%	0.42	4.5 MB	6.2%	1.27
Oct 13 16:00:00 - 16:59:59	1637	6.5%	0.45	5.6 MB	7.6%	1.58
Oct 13 17:00:00 - 17:59:59	1505	6.0%	0.42	4.3 MB	5.9%	1.21
Oct 13 18:00:00 - 18:59:59	1281	5.1%	0.36	3.4 MB	4.7%	0.97
Oct 13 19:00:00 - 19:59:59	1277	5.1%	0.35	3.3 MB	4.5%	0.94
Oct 13 20:00:00 - 20:59:59	1113	4.4%	0.31	3.2 MB	4.4%	0.91
Oct 13 21:00:00 - 21:59:59	1133	4.5%	0.31	3.1 MB	4.2%	0.87
Oct 13 22:00:00 - 22:59:59	1058	4.2%	0.29	3.1 MB	4.3%	0.89
Oct 13 23:00:00 - 23:59:59	785	3.1%	0.22	2.6 MB	3.6%	0.74
Oct 14 00:00:00 - 00:59:59	739	2.9%	0.21	2.5 MB	3.5%	0.72
Oct 14 01:00:00 - 01:59:59	598	2.4%	0.17	1.9 MB	2.6%	0.53
Oct 14 02:00:00 - 02:59:59	506	2.0%	0.14	1.6 MB	2.2%	0.46
Oct 14 03:00:00 - 03:59:59	457	1.8%	0.13	1.6 MB	2.2%	0.46
Oct 14 04:00:00 - 04:15:00	164	0.7%	0.18	660.9 KB	0.9%	0.73
TOTAL: 24:00:00	25108	100.0%	0.29	72.6 MB	100.0%	0.86

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	6696	769	5	5922
2	newsfeed.neva.ru	1541	353	4	1184
3	csiph-out.news.weretis.net	970	560	3	407
4	news-out.mixmin.net	904	7	8	889
5	newsfeed.fu-berlin.de	827	19	2	806
6	feeder.eternal-september.org	370	64	2	304
7	news.redatomik.org	154	0	1	153
8	news.fcku.it	137	0	0	137
9	news-out.aioe.org	42	20	3	19
10	newsfeeds.xmission.com	37	2	1	34
11	nntp.pasdenom.info	35	1	1	33
12	news.freedyn.net	31	0	1	30
13	xanadu-bbs.net	11	0	0	11
14	news.swapon.de	5	0	0	5
15	news.bbs.geek.nz	1	0	1	0
TOTAL: 15		11761	1795	32	9934

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	658
lada.talk	167
fa.freebsd.cvs-all	96
ut.test	94
lada.auto	57
mozilla.dev.security.policy	42
lada.photo	40
fa.freebsd.bugs	37
alt.windows7.general	32
fa.openbsd.tech	25
mozilla.support.seamonkey	21
fa.openbsd.source-changes	20
fa.philos-l	20
lada.commerce	18
mpc.lists.nanog	17
cakewalk.audio	17
alt.survival	17
mozilla.dev.platform	14
mpc.lists.freebsd.wireless	14
fa.netbsd.bugs	14
TOTAL: 161	1795

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6663
EMP (phn path)	1460
EMP (phl)	584
EMP (phn nph)	136
Too many newsgroups	62
User-issued cancel	29
Binary Payload	20
Scoring filter (8)	19
Scoring filter (19)	19
Scoring filter (18)	19
Scoring filter (17)	19
HTML Multipart	17
Scoring filter (14)	8
Scoring filter (9)	8
Scoring filter (12)	8
Scoring filter (11)	8
Scoring filter (10)	4
HTML post	4
Scoring filter (16)	3
Scoring filter (21)	3
TOTAL: 28	9104

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	66	0	71	71
TOTAL: 1	66	0	71	71

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	26
	TOTAL: 1	26
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	feeder.eternal-september.org	106
	csiph-out.news.weretis.net	54
	newsfeeds.xmission.com	27
	news.freedyn.net	19
	news.redatomik.org	16
	news.fcku.it	16
	news-out.mixmin.net	7
	nntp.pasdenom.info	4
	news.swapon.de	3
	TOTAL: 9	252
Including strange strings
	newsfeed.fu-berlin.de	351
	feeder.eternal-september.org	52
	csiph-out.feeder.erje.net	35
	csiph-out.news.weretis.net	34
	news-out.mixmin.net	29
	newsfeed.neva.ru	19
	news-out.aioe.org	16
	news.redatomik.org	6
	newsfeeds.xmission.com	6
	news.swapon.de	2
	TOTAL: 10	550
TOTAL: 4		832

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	44526	3192	21015	43	1	7%	23:53:23
2	news.freedyn.net	25368	1044	23945	11	0	4%	23:52:14
3	xanadu-bbs	26987	813	23572	1	540	3%	23:57:02
4	fu-berlin.de	23487	658	22829	0	0	2%	23:50:06
5	usenet.pasdenom.info	27638	150	23897	1059	0	0%	23:59:55
6	news.redatomik.org	23772	39	23162	6	0	0%	23:56:52
7	xmission	24966	26	22036	3	0	0%	23:50:06
8	weretis.net	17670	14	17386	0	0	0%	23:50:06
9	aioe	21330	7	20466	4	0	0%	23:50:05
10	news.fcku.it	3559	3	3517	0	0	0%	22:42:52
11	news.mixmin.net	22382	3	21589	0	0	0%	23:59:16
12	neva.ru	23148	2	21971	13	0	0%	23:50:06
13	news.swapon.de	1678	1	1661	0	0	0%	23:46:32
14	erje	20150	1	20084	31	0	0%	23:58:57
15	eternal-september	21376	1	20186	0	0	0%	23:50:06
16	au2pb	0	0	0	0	24233	0%	23:50:03
17	bwh	0	0	0	0	24222	0%	23:50:03
TOTAL: 17		328037	5954	287316	1171	48996	1%	404:47:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	7.9 MB	158.4 KB	8.1 MB	0.1 KB/s	2.6 KB	23:53:23
2	news.freedyn.net	7.2 MB	40.6 KB	7.2 MB	0.1 KB/s	7.0 KB	23:52:14
3	xanadu-bbs	6.6 MB	37.9 KB	6.6 MB	0.1 KB/s	8.4 KB	23:57:02
4	fu-berlin.de	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	9.7 KB	23:50:06
5	usenet.pasdenom.info	392.8 KB	7.1 MB	7.4 MB	0.1 KB/s	6.3 KB	23:59:55
6	news.redatomik.org	99.0 KB	48.6 KB	147.6 KB	0.0 KB/s	3.3 KB	23:56:52
7	xmission	76.6 KB	7.9 KB	84.5 KB	0.0 KB/s	2.9 KB	23:50:06
8	weretis.net	45.3 KB	0.0 KB	45.3 KB	0.0 KB/s	3.2 KB	23:50:06
9	aioe	10.3 KB	13.5 KB	23.8 KB	0.0 KB/s	2.2 KB	23:50:05
10	news.mixmin.net	7.0 KB	0.0 KB	7.0 KB	0.0 KB/s	2.3 KB	23:59:16
11	news.fcku.it	5.5 KB	0.0 KB	5.5 KB	0.0 KB/s	1.8 KB	22:42:52
12	neva.ru	4.3 KB	30.9 KB	35.2 KB	0.0 KB/s	2.3 KB	23:50:06
13	news.swapon.de	1.8 KB	0.0 KB	1.8 KB	0.0 KB/s	1.8 KB	23:46:32
14	eternal-september	1.8 KB	0.0 KB	1.8 KB	0.0 KB/s	1.8 KB	23:50:06
15	erje	1.6 KB	107.6 KB	109.2 KB	0.0 KB/s	3.4 KB	23:58:57
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
TOTAL: 17		28.6 MB	7.5 MB	36.1 MB	0.0 KB/s	5.2 KB	404:47:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	c-73-195-247-24.hsd1.nj.comcast.net	7	6	8.9 KB	1	0	0	03:05:33
2	challenger.nielsenmedia.com	3	6	8.9 KB	1	0	0	00:32:11
3	tempe0.bbox.io	22	3	9.8 KB	1	0	0	04:39:58
4	1.127.48.38	2	2	2.0 KB	2	0	0	00:02:13
5	dhcp-089-099-203-254.chello.nl	58	2	3.6 KB	2	0	0	14:11:22
6	68-185-181-64.dhcp.mdsn.wi.charter.com	4	2	3.6 KB	2	1	1	01:34:24
TOTAL: 6		96	21	36.9 KB	9	1	1	46:36:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.hsd1.nj.comcast.net	7	6	8.9 KB	1	0	0	03:05:33
2	*.nielsenmedia.com	3	6	8.9 KB	1	0	0	00:32:11
3	*.bbox.io	22	3	9.8 KB	1	0	0	04:39:58
4	*.chello.nl	58	2	3.6 KB	2	0	0	14:11:22
5	*.dhcp.mdsn.wi.charter.com	4	2	3.6 KB	2	1	1	01:34:24
6	unresolved	51	2	2.0 KB	2	0	0	22:32:54
TOTAL: 6		145	21	36.9 KB	9	1	1	46:36:25

NNRP auth users [Top 20]:

User	Conn
moussa	2
ohlandl	1
TOTAL: 2	3

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	3.882	0.846	0.000	22:30:41
dhcp-089-099-203-254.chello.nl	4.900	1.224	0.000	14:11:22
tempe0.bbox.io	1.869	0.483	0.000	04:39:58
c-73-195-247-24.hsd1.nj.comcast.net	0.642	0.155	0.000	03:05:33
68-185-181-64.dhcp.mdsn.wi.charter.com	0.442	0.101	0.004	01:34:24
challenger.nielsenmedia.com	0.291	0.048	0.000	00:32:11
1.127.48.38	0.281	0.102	0.006	00:02:13
TOTAL: 7	12.307	2.959	0.010	46:36:25

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	49
TOTAL: 1	49

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	43	0
tempe0.bbox.io	9	0
c-73-195-247-24.hsd1.nj.comcast.net	6	0
68-185-181-64.dhcp.mdsn.wi.charter.com	3	0
challenger.nielsenmedia.com	1	0
TOTAL: 5	62	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	18	85.7%
2	muc	3	14.3%
TOTAL: 2		21	100.0%

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

Newsgroup		Count
1	comp.lang.awk	12
2	comp.sys.ibm.ps2.hardware	6
3	muc.lists.freebsd.stable	3
TOTAL: 3		21

Daily Usenet report for csiph.com

Oct 13 04:15:00 -- Oct 14 04:15:00