Daily Usenet report for csiph.com: Sep 11 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	21401	49.2%	5.4 MB	64.9%
innd	18391	42.3%	2.3 MB	27.6%
innfeed	3368	7.7%	601.3 KB	7.1%
nnrpd	239	0.5%	26.2 KB	0.3%
nocem	88	0.2%	8.8 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	43488	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	301716	57.1%
Negative hits	205437	38.9%
Do not exist	20610	3.9%
Cache misses	328	0.1%
TOTAL: 4	528091	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.402	0.0%	219	0.000	6.402	237.500
article cleanup	00:00:00.112	0.0%	20030	0.000	0.006	0.029
article logging	00:00:00.387	0.0%	20422	0.000	0.019	0.140
article parse	00:00:01.818	0.0%	83296	0.000	0.022	0.190
article write	00:02:06.704	0.1%	16103	0.265	7.868	65.016
artlog/artcncl	00:00:00.003	0.0%	179	0.000	0.017	0.333
artlog/artparse	00:00:00.011	0.0%	819	0.000	0.013	0.500
data move	00:00:00.372	0.0%	512765	0.000	0.001	0.042
hisgrep/artcncl	00:00:00.035	0.0%	205	0.000	0.171	3.125
hishave/artcncl	00:00:00.035	0.0%	219	0.000	0.160	3.250
hishave/artparse	00:00:00.002	0.0%	819	0.000	0.002	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:25.706	0.0%	532731	0.002	0.048	0.438
history sync	00:00:00.647	0.0%	368	0.000	1.758	18.500
history write	00:00:27.864	0.0%	20021	0.257	1.392	6.462
hiswrite/artcncl	00:00:00.020	0.0%	14	0.000	1.429	4.000
hiswrite/artparse	00:00:01.076	0.0%	814	0.000	1.322	14.000
idle	23:53:22.103	99.5%	515968	96.034	166.681	523.661
nntp read	00:00:02.247	0.0%	524349	0.001	0.004	0.010
overview write	00:03:03.911	0.2%	16103	0.656	11.421	1231.617
perl filter	00:00:36.369	0.0%	20021	1.341	1.817	4.324
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.294	0.0%	32289	0.000	0.009	0.038
TOTAL: 24:00:50.967	24:00:11.118	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	16275	0.000	0.003	0.023
article prepare	00:00:00.001	0.0%	13072	0.000	0.000	0.007
article read	00:00:01.532	0.0%	1022	0.000	1.499	26.429
backlog stats	00:00:00.209	0.0%	350826	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.502	0.0%	271583	0.001	0.006	0.011
data write	00:00:01.659	0.0%	252668	0.002	0.007	0.014
idle	23:54:51.734	100.0%	350826	140.714	245.397	646.067
status file	00:00:01.304	0.0%	275	0.000	4.742	158.000
TOTAL: 23:55:05.231	23:54:57.988	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	07:39:01.469	99.9%	184	60.000	149681.897	600069.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	454	0.000	0.013	0.167
readart	00:00:00.193	0.0%	21	0.000	9.190	50.000
TOTAL: 07:39:17.138	07:39:01.668	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	77683	3672	73625	386	4%	118:42:08
2	feeder.eternal-september.org	2	17655	2008	15570	77	11%	23:18:52
3	csiph-out.news.xs4all.nl	861	18583	1828	15265	1490	9%	71:26:17
4	news-out.aioe.org	3	15950	1379	14555	16	8%	24:09:50
5	news-out.mixmin.net	2	16496	1347	14873	276	8%	24:08:55
6	csiph-out.news.weretis.net	3	32215	1274	30703	238	3%	46:35:11
7	newsfeeds.xmission.com	1	14764	1154	13600	10	7%	21:48:41
8	news.freedyn.net	2	20008	1021	18127	860	5%	24:09:01
9	newsfeed.fu-berlin.de	16	15765	938	14762	65	5%	27:12:06
10	newsfeed.neva.ru	144	21703	850	19979	874	3%	71:48:58
11	csiph-out.feed.uzoreto.com	864	26113	247	25487	379	0%	71:12:08
12	news.fcku.it	9	1964	226	1734	4	11%	21:44:02
13	nntp.pasdenom.info	2	11246	57	11179	10	0%	23:58:14
14	news.swapon.de	4	1563	34	1516	13	2%	23:02:57
15	news.samoylyk.net	5	15621	19	15601	1	0%	23:59:23
16	news.bbs.nz	1	15274	7	15267	0	0%	23:59:52
17	xanadu-bbs.net	15	664	1	663	0	0%	23:47:02
18	localhost	34	1	1	0	0	100%	00:00:05
TOTAL: 18		3408	323268	16063	302506	4699	4%	665:03:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	15.3 MB	30.7 KB	12.1 MB	27.5 MB	55%	6.9 KB
2	csiph-out.news.xs4all.nl	5.5 MB	33.6 KB	13.8 MB	19.3 MB	28%	6.0 KB
3	feeder.eternal-september.org	5.2 MB	0.0 KB	760.4 KB	5.9 MB	87%	2.9 KB
4	csiph-out.news.weretis.net	4.1 MB	34.4 KB	1.9 MB	6.0 MB	67%	4.1 KB
5	news-out.mixmin.net	3.7 MB	0.0 KB	867.1 KB	4.5 MB	81%	2.9 KB
6	newsfeeds.xmission.com	3.5 MB	0.0 KB	448.0 KB	3.9 MB	88%	3.4 KB
7	news-out.aioe.org	3.2 MB	0.0 KB	30.0 KB	3.2 MB	99%	2.4 KB
8	news.freedyn.net	2.9 MB	32.5 KB	52.9 MB	55.8 MB	5%	30.4 KB
9	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	247.9 KB	2.5 MB	90%	2.5 KB
10	newsfeed.neva.ru	1.8 MB	0.0 KB	2.9 MB	4.7 MB	39%	2.8 KB
11	csiph-out.feed.uzoreto.com	1013.8 KB	15.4 KB	5.8 MB	6.8 MB	14%	11.1 KB
12	news.fcku.it	398.0 KB	0.0 KB	101.0 KB	499.0 KB	79%	2.2 KB
13	nntp.pasdenom.info	156.3 KB	0.0 KB	15.8 KB	172.1 KB	90%	2.6 KB
14	news.swapon.de	96.1 KB	0.0 KB	5.7 MB	5.8 MB	1%	127.1 KB
15	news.samoylyk.net	51.8 KB	0.0 KB	842.8 KB	894.6 KB	5%	44.7 KB
16	news.bbs.nz	18.8 KB	0.0 KB	0.0 KB	18.8 KB	100%	2.7 KB
17	xanadu-bbs.net	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
18	localhost	1.2 KB	0.0 KB	0.0 KB	1.2 KB	100%	1.2 KB
TOTAL: 18		49.0 MB	146.6 KB	98.3 MB	147.5 MB	33%	7.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 11 04:15:00 - 04:59:59	267	1.7%	0.10	704.4 KB	1.4%	0.26
Sep 11 05:00:00 - 05:59:59	429	2.7%	0.12	1.5 MB	3.0%	0.43
Sep 11 06:00:00 - 06:59:59	497	3.1%	0.14	2.2 MB	4.4%	0.61
Sep 11 07:00:00 - 07:59:59	623	3.9%	0.17	1.8 MB	3.7%	0.52
Sep 11 08:00:00 - 08:59:59	674	4.2%	0.19	2.0 MB	4.1%	0.57
Sep 11 09:00:00 - 09:59:59	758	4.7%	0.21	2.0 MB	4.1%	0.58
Sep 11 10:00:00 - 10:59:59	785	4.9%	0.22	2.5 MB	5.0%	0.70
Sep 11 11:00:00 - 11:59:59	796	4.9%	0.22	2.3 MB	4.6%	0.64
Sep 11 12:00:00 - 12:59:59	872	5.4%	0.24	2.4 MB	4.9%	0.69
Sep 11 13:00:00 - 13:59:59	812	5.0%	0.23	2.1 MB	4.2%	0.59
Sep 11 14:00:00 - 14:59:59	926	5.8%	0.26	3.2 MB	6.4%	0.90
Sep 11 15:00:00 - 15:59:59	943	5.9%	0.26	2.9 MB	5.9%	0.83
Sep 11 16:00:00 - 16:59:59	891	5.5%	0.25	2.4 MB	5.0%	0.70
Sep 11 17:00:00 - 17:59:59	949	5.9%	0.26	2.4 MB	4.8%	0.67
Sep 11 18:00:00 - 18:59:59	845	5.3%	0.23	3.1 MB	6.2%	0.87
Sep 11 19:00:00 - 19:59:59	813	5.1%	0.23	2.3 MB	4.6%	0.65
Sep 11 20:00:00 - 20:59:59	808	5.0%	0.22	2.4 MB	4.9%	0.68
Sep 11 21:00:00 - 21:59:59	712	4.4%	0.20	2.0 MB	4.0%	0.56
Sep 11 22:00:00 - 22:59:59	602	3.7%	0.17	2.0 MB	4.2%	0.58
Sep 11 23:00:00 - 23:59:59	482	3.0%	0.13	1.5 MB	3.0%	0.42
Sep 12 00:00:00 - 00:59:59	425	2.6%	0.12	1.2 MB	2.5%	0.35
Sep 12 01:00:00 - 01:59:59	355	2.2%	0.10	1.0 MB	2.1%	0.29
Sep 12 02:00:00 - 02:59:59	366	2.3%	0.10	1.5 MB	3.1%	0.43
Sep 12 03:00:00 - 03:59:59	347	2.2%	0.10	1.1 MB	2.3%	0.32
Sep 12 04:00:00 - 04:15:00	108	0.7%	0.12	820.3 KB	1.6%	0.91
TOTAL: 24:00:00	16085	100.0%	0.19	49.2 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1523	32	3	0	1488
2	newsfeed.neva.ru	881	221	0	1	659
3	news.freedyn.net	876	16	1	0	859
4	csiph-out.feeder.erje.net	389	56	2	0	331
5	csiph-out.feed.uzoreto.com	381	26	1	0	354
6	newsfeed.fu-berlin.de	378	26	0	0	352
7	news-out.mixmin.net	297	1	0	0	296
8	csiph-out.news.weretis.net	275	124	2	0	149
9	feeder.eternal-september.org	82	15	0	0	67
10	news.swapon.de	17	0	0	0	17
11	news-out.aioe.org	14	7	0	0	7
12	nntp.pasdenom.info	12	0	0	0	12
13	newsfeeds.xmission.com	10	2	0	0	8
14	news.fcku.it	4	2	0	0	2
15	news.samoylyk.net	1	0	0	0	1
TOTAL: 15		5140	528	9	1	4602

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	203
mozilla.mozillians	43
mozilla.support.thunderbird	21
spline.eisfair	20
mozilla.support.seamonkey	17
lada.auto	15
alt.windows7.general	12
mozilla.dev.security.policy	10
alt.politics	10
alt.binaries.pictures.purebeauty	10
alt.slack.goathead	9
alt.bbs.synchronet	8
mozilla.support.firefox	7
alt.job	7
eagle.support.eng	7
it-alt.sport.calcio.juventus	7
at.jobs	6
alt.survival	6
lada.commerce	6
att.jobs	5
TOTAL: 79	528

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
linux.debian.security	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2992
EMP (phn path)	163
User-issued cancel	78
EMP (phn nph)	40
EMP (phl)	24
Binary: misplaced rar	22
Too many newsgroups	18
HTML Multipart	10
Binary Payload	9
User-issued spam cancel	8
Scoring filter (11)	6
Scoring filter (9)	5
Scoring filter (10)	5
Scoring filter (8)	3
Scoring filter (12)	2
HTML Image Tags	1
Too many newsgroups (meow)	1
TOTAL: 17	3387

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	72	0	87	87
TOTAL: 1	72	10	87	87

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	450
	csiph-out.feeder.erje.net	221
	csiph-out.news.xs4all.nl	61
	csiph-out.feed.uzoreto.com	42
	csiph-out.news.weretis.net	19
	news.swapon.de	13
	newsfeeds.xmission.com	6
	news-out.mixmin.net	5
	feeder.eternal-september.org	4
	news.fcku.it	1
	TOTAL: 12	824
Including strange strings
	newsfeed.fu-berlin.de	322
	csiph-out.news.xs4all.nl	30
	csiph-out.news.weretis.net	17
	news-out.mixmin.net	11
	news.swapon.de	4
	newsfeed.neva.ru	2
	csiph-out.feed.uzoreto.com	2
	news.freedyn.net	1
	news-out.aioe.org	1
	csiph-out.feeder.erje.net	1
	TOTAL: 10	391
TOTAL: 3		1260

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	19666	660	15423	0	0	152	3%	23:59:56
2	news.freedyn.net	15355	488	14531	26	0	0	3%	23:59:32
3	news.samoylyk.net	16800	479	15530	2	0	0	2%	23:52:01
4	fu-berlin.de	15010	449	14561	0	0	0	2%	23:59:43
5	news.bbs.nz	30046	247	15353	19	2	0	0%	23:59:43
6	usenet.pasdenom.info	16476	118	15389	523	0	14	0%	23:59:56
7	newsfeed.xs4all.nl	14561	37	13814	3	0	55	0%	24:00:04
8	neva.ru	15051	21	14466	71	0	0	0%	23:59:32
9	aioe	14351	15	13747	0	0	0	0%	24:06:39
10	erje	12559	11	12182	8	0	0	0%	23:54:28
11	news.uzoreto.com	15536	8	15241	58	0	0	0%	23:53:20
12	news.mixmin.net	14454	8	14040	0	0	0	0%	24:00:31
13	eternal-september	13812	3	13381	0	0	0	0%	23:59:48
14	xmission	17367	3	14412	0	0	0	0%	23:59:55
15	news.swapon.de	1722	2	1679	0	0	0	0%	23:48:22
16	weretis.net	15604	2	14258	0	0	0	0%	24:00:02
17	news.fcku.it	1605	0	1594	0	0	0	0%	21:38:29
18	news.redatomik.org	0	0	0	0	0	15632	0%	23:59:48
TOTAL: 18		249975	2551	219601	710	2	15853	1%	429:11:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	10.0 KB	23:59:56
2	news.freedyn.net	5.9 MB	55.9 KB	5.9 MB	0.1 KB/s	11.8 KB	23:59:32
3	news.samoylyk.net	5.8 MB	80.0 KB	5.8 MB	0.1 KB/s	12.4 KB	23:52:01
4	fu-berlin.de	5.8 MB	0.0 KB	5.8 MB	0.1 KB/s	13.1 KB	23:59:43
5	news.bbs.nz	615.5 KB	79.7 KB	695.2 KB	0.0 KB/s	2.6 KB	23:59:43
6	usenet.pasdenom.info	267.7 KB	6.2 MB	6.5 MB	0.1 KB/s	10.3 KB	23:59:56
7	newsfeed.xs4all.nl	191.6 KB	21.2 KB	212.8 KB	0.0 KB/s	5.3 KB	24:00:04
8	aioe	47.6 KB	0.0 KB	47.6 KB	0.0 KB/s	3.2 KB	24:06:39
9	neva.ru	36.6 KB	171.0 KB	207.6 KB	0.0 KB/s	2.3 KB	23:59:32
10	erje	24.5 KB	25.2 KB	49.7 KB	0.0 KB/s	2.6 KB	23:54:28
11	news.uzoreto.com	13.6 KB	421.3 KB	434.8 KB	0.0 KB/s	6.6 KB	23:53:20
12	news.mixmin.net	13.3 KB	0.0 KB	13.3 KB	0.0 KB/s	1.7 KB	24:00:31
13	eternal-september	6.4 KB	0.0 KB	6.4 KB	0.0 KB/s	2.1 KB	23:59:48
14	xmission	6.0 KB	0.0 KB	6.0 KB	0.0 KB/s	2.0 KB	23:59:55
15	weretis.net	3.7 KB	0.0 KB	3.7 KB	0.0 KB/s	1.9 KB	24:00:02
16	news.swapon.de	2.6 KB	0.0 KB	2.6 KB	0.0 KB/s	1.3 KB	23:48:22
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:38:29
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:48
TOTAL: 18		25.0 MB	7.0 MB	32.1 MB	0.0 KB/s	10.1 KB	429:11:49

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	208-71-239-109.cust.centrio.cz	2	10	23.0 KB	1	0	0	00:59:39
2	a131033.upc-a.chello.nl	5	7	11.6 KB	2	0	0	00:38:55
3	68-190-164-204.dhcp.eucl.wi.charter.com	14	2	3.1 KB	1	0	0	02:37:09
4	ip72-222-200-117.ph.ph.cox.net	9	2	2.1 KB	2	1	1	03:23:25
TOTAL: 4		30	21	39.8 KB	6	1	1	07:39:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cust.centrio.cz	2	10	23.0 KB	1	0	0	00:59:39
2	*.upc-a.chello.nl	5	7	11.6 KB	2	0	0	00:38:55
3	*.ph.ph.cox.net	9	2	2.1 KB	2	1	1	03:23:25
4	*.dhcp.eucl.wi.charter.com	14	2	3.1 KB	1	0	0	02:37:09
5	unresolved	2	0	0.0 KB	0	0	0	00:00:02
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:02
TOTAL: 6		33	21	39.8 KB	6	1	1	07:39:15

NNRP auth users [Top 20]:

User	Conn
ctomczyk	2
kev009	1
TOTAL: 2	3

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ip72-222-200-117.ph.ph.cox.net	0.774	0.156	0.003	03:23:25
68-190-164-204.dhcp.eucl.wi.charter.com	0.967	0.229	0.000	02:37:09
208-71-239-109.cust.centrio.cz	0.230	0.042	0.007	00:59:39
a131033.upc-a.chello.nl	0.492	0.047	0.000	00:38:55
scan-141.security.ipip.net	0.065	0.016	0.000	00:00:02
94.162.99.220	0.074	0.008	0.000	00:00:01
5.87.93.182	0.065	0.016	0.000	00:00:01
TOTAL: 7	2.667	0.514	0.010	07:39:15

Curious NNRP server explorers [Top 20]:

System	Conn
scan-141.security.ipip.net	1
5.87.93.182	1
94.162.99.220	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
Host: 69.160.37.58:119	1
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
GET / HTTP/1.1	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	5	0
68-190-164-204.dhcp.eucl.wi.charter.com	2	0
208-71-239-109.cust.centrio.cz	1	0
TOTAL: 3	8	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	11
2	comp.lang.javascript	10
TOTAL: 2		21

Daily Usenet report for csiph.com

Sep 11 04:15:00 -- Sep 12 04:15:00