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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45412	58.2%	8.7 MB	70.6%
innd	23058	29.6%	2.5 MB	20.1%
nnrpd	6241	8.0%	643.9 KB	5.1%
innfeed	3232	4.1%	530.4 KB	4.2%
nocem	53	0.1%	5.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	77997	100.0%	12.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	470193	54.7%
Negative hits	344014	40.0%
Do not exist	44298	5.2%
Cache misses	844	0.1%
TOTAL: 4	859349	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.131	0.0%	196	0.000	5.770	35.500
article cleanup	00:00:00.284	0.0%	44225	0.000	0.006	0.020
article logging	00:00:00.730	0.0%	44853	0.000	0.016	0.284
article parse	00:00:01.929	0.0%	157944	0.002	0.012	0.066
article write	00:05:52.244	0.4%	25943	0.259	13.578	59.632
artlog/artcncl	00:00:00.000	0.0%	164	0.000	0.000	0.000
artlog/artparse	00:00:00.010	0.0%	411	0.000	0.024	0.500
data move	00:00:00.332	0.0%	813326	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.122	0.0%	195	0.000	0.626	12.000
hishave/artcncl	00:00:00.001	0.0%	196	0.000	0.005	0.056
hishave/artparse	00:00:00.001	0.0%	411	0.000	0.002	0.026
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:13.836	0.0%	864633	0.002	0.016	0.196
history sync	00:00:01.921	0.0%	355	0.000	5.411	45.000
history write	00:00:53.038	0.1%	44167	0.101	1.201	12.982
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.363	0.0%	411	0.000	0.883	5.500
idle	23:49:11.067	99.2%	821003	56.589	104.447	323.597
nntp read	00:00:02.858	0.0%	837216	0.001	0.003	0.006
overview write	00:01:38.415	0.1%	25943	0.244	3.794	73.192
perl filter	00:01:58.482	0.1%	44121	1.647	2.685	3.739
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.447	0.0%	51937	0.000	0.009	0.025
TOTAL: 24:00:49.984	23:59:57.211	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.086	0.0%	26358	0.000	0.003	0.017
article prepare	00:00:00.005	0.0%	36828	0.000	0.000	0.036
article read	00:00:05.127	0.0%	6310	0.000	0.813	11.250
backlog stats	00:00:00.233	0.0%	496148	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.013	0.0%	375527	0.002	0.005	0.009
data write	00:00:02.538	0.0%	356488	0.002	0.007	0.014
idle	23:54:08.933	100.0%	496148	109.348	173.434	549.282
status file	00:00:00.579	0.0%	311	0.000	1.862	89.000
TOTAL: 23:54:30.356	23:54:19.514	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.031	0.0%	192	0.000	0.161	8.667
idle	39:08:41.985	96.7%	21573	0.000	6532.331	600151.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:01:16.533	0.1%	143589	0.000	0.533	0.957
readart	01:18:36.229	3.2%	1086225	0.000	4.342	73.000
TOTAL: 40:29:41.185	40:28:34.778	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	138916	7322	122504	9090	5%	94:58:06
2	csiph-out.news.weretis.net	4	53885	4273	48351	1261	7%	45:19:25
3	feeder.eternal-september.org	3	39846	3505	35588	753	8%	24:26:56
4	news-out.mixmin.net	3	27999	2577	24738	684	9%	24:18:02
5	news-out.aioe.org	3	24826	2180	22612	34	8%	24:12:03
6	newsfeed.neva.ru	144	54305	2028	45729	6548	3%	71:58:34
7	newsfeed.fu-berlin.de	48	27531	1401	25971	159	5%	33:38:31
8	newsfeeds.xmission.com	1	27036	1005	26009	22	3%	26:28:44
9	news.fcku.it	1	10972	806	10128	38	7%	23:59:58
10	news.redatomik.org	1	21305	676	20618	11	3%	21:45:18
11	xanadu-bbs.net	12	877	172	699	6	19%	22:56:12
12	news.swapon.de	2	2257	90	2158	9	3%	22:39:49
13	news.freedyn.net	2	28807	32	28761	14	0%	24:01:34
14	nntp.pasdenom.info	5	30470	6	30451	13	0%	23:42:20
15	news.bbs.geek.nz	1	19571	1	19569	1	0%	23:59:51
16	localhost	35	0	0	0	0	0%	00:00:00
TOTAL: 16		1417	508603	26074	463886	18643	5%	508:25:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	26.2 MB	357.0 KB	48.9 MB	75.4 MB	34%	4.7 KB
2	csiph-out.news.weretis.net	13.1 MB	2.7 KB	23.6 MB	36.7 MB	35%	6.8 KB
3	news-out.mixmin.net	9.2 MB	0.9 KB	2.0 MB	11.2 MB	81%	3.5 KB
4	feeder.eternal-september.org	8.9 MB	0.9 KB	12.3 MB	21.2 MB	41%	5.1 KB
5	newsfeed.neva.ru	7.4 MB	192.8 KB	60.4 MB	67.9 MB	10%	8.1 KB
6	news-out.aioe.org	5.4 MB	4.0 KB	62.8 KB	5.5 MB	98%	2.5 KB
7	newsfeed.fu-berlin.de	3.1 MB	0.0 KB	419.2 KB	3.5 MB	88%	2.3 KB
8	newsfeeds.xmission.com	3.0 MB	0.0 KB	1.6 MB	4.6 MB	64%	4.6 KB
9	news.fcku.it	1.9 MB	0.0 KB	732.5 KB	2.7 MB	73%	3.2 KB
10	news.redatomik.org	1.8 MB	0.0 KB	430.8 KB	2.3 MB	81%	3.4 KB
11	xanadu-bbs.net	509.2 KB	0.0 KB	94.4 KB	603.6 KB	84%	3.4 KB
12	news.swapon.de	233.9 KB	0.0 KB	4.7 MB	4.9 MB	4%	50.7 KB
13	news.freedyn.net	130.3 KB	1.0 KB	165.0 KB	296.2 KB	43%	6.4 KB
14	nntp.pasdenom.info	11.6 KB	0.0 KB	52.2 KB	63.8 KB	18%	3.4 KB
15	news.bbs.geek.nz	3.0 KB	0.0 KB	1.5 KB	4.5 KB	66%	2.3 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		81.0 MB	559.4 KB	155.4 MB	236.9 MB	34%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 3 04:15:00 - 04:59:59	346	1.3%	0.13	1.1 MB	1.4%	0.43
Oct 3 05:00:00 - 05:59:59	491	1.9%	0.14	1.8 MB	2.2%	0.50
Oct 3 06:00:00 - 06:59:59	636	2.5%	0.18	2.1 MB	2.6%	0.59
Oct 3 07:00:00 - 07:59:59	982	3.8%	0.27	2.4 MB	2.9%	0.67
Oct 3 08:00:00 - 08:59:59	1142	4.4%	0.32	2.8 MB	3.5%	0.80
Oct 3 09:00:00 - 09:59:59	1186	4.6%	0.33	2.8 MB	3.4%	0.79
Oct 3 10:00:00 - 10:59:59	1139	4.4%	0.32	3.2 MB	4.0%	0.92
Oct 3 11:00:00 - 11:59:59	1071	4.1%	0.30	2.8 MB	3.4%	0.78
Oct 3 12:00:00 - 12:59:59	1132	4.4%	0.31	3.1 MB	3.8%	0.87
Oct 3 13:00:00 - 13:59:59	1258	4.8%	0.35	3.3 MB	4.1%	0.93
Oct 3 14:00:00 - 14:59:59	1404	5.4%	0.39	3.7 MB	4.7%	1.07
Oct 3 15:00:00 - 15:59:59	1440	5.5%	0.40	4.3 MB	5.3%	1.22
Oct 3 16:00:00 - 16:59:59	1535	5.9%	0.43	4.6 MB	5.8%	1.32
Oct 3 17:00:00 - 17:59:59	1521	5.9%	0.42	5.0 MB	6.2%	1.41
Oct 3 18:00:00 - 18:59:59	1437	5.5%	0.40	4.2 MB	5.2%	1.18
Oct 3 19:00:00 - 19:59:59	1299	5.0%	0.36	3.5 MB	4.4%	1.00
Oct 3 20:00:00 - 20:59:59	1205	4.6%	0.33	3.3 MB	4.1%	0.95
Oct 3 21:00:00 - 21:59:59	984	3.8%	0.27	2.8 MB	3.5%	0.80
Oct 3 22:00:00 - 22:59:59	969	3.7%	0.27	3.1 MB	3.9%	0.89
Oct 3 23:00:00 - 23:59:59	778	3.0%	0.22	2.5 MB	3.1%	0.71
Oct 4 00:00:00 - 00:59:59	687	2.6%	0.19	2.3 MB	2.8%	0.65
Oct 4 01:00:00 - 01:59:59	1568	6.0%	0.44	8.5 MB	10.6%	2.42
Oct 4 02:00:00 - 02:59:59	1132	4.4%	0.31	5.6 MB	7.0%	1.60
Oct 4 03:00:00 - 03:59:59	451	1.7%	0.13	1.3 MB	1.7%	0.38
Oct 4 04:00:00 - 04:15:00	154	0.6%	0.17	502.0 KB	0.6%	0.56
TOTAL: 24:00:00	25947	100.0%	0.30	80.5 MB	100.0%	0.95

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	9123	456	35	8632
2	newsfeed.neva.ru	6568	862	18	5688
3	csiph-out.news.weretis.net	1331	326	1	1004
4	feeder.eternal-september.org	796	67	3	726
5	news-out.mixmin.net	715	7	1	707
6	newsfeed.fu-berlin.de	579	47	0	532
7	news-out.aioe.org	48	30	0	18
8	news.fcku.it	38	0	0	38
9	news.redatomik.org	35	0	0	35
10	newsfeeds.xmission.com	22	1	0	21
11	news.freedyn.net	14	0	0	14
12	news.swapon.de	13	0	0	13
13	nntp.pasdenom.info	13	1	0	12
14	xanadu-bbs.net	8	0	0	8
TOTAL: 14		19303	1797	58	17448

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	481
lada.talk	130
alt.survival	121
ut.test	115
it-alt.sport.calcio.juventus	67
fa.freebsd.cvs-all	55
lada.auto	54
lada.photo	47
mozilla.support.seamonkey	34
mpc.lists.nanog	33
fa.openbsd.source-changes	32
gmane.linux.alsa.devel	32
fa.philos-l	26
fa.openbsd.tech	20
lada.commerce	20
alt.windows7.general	20
alt.politics.scorched-earth	20
mozilla.support.thunderbird	19
eagle.support.eng	18
gmane.comp.gis.openstreetmap.tagging	15
TOTAL: 166	1797

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13212
EMP (phn path)	2144
EMP (phl)	720
EMP (phn nph)	62
User-issued cancel	49
Too many newsgroups	26
Binary Payload	24
Scoring filter (8)	20
Scoring filter (9)	15
Too many newsgroups (meow)	14
Scoring filter (10)	14
User-issued spam cancel	11
Too many newsgroups (low_xpost)	9
HTML Multipart	9
Scoring filter (16)	6
Scoring filter (13)	5
Scoring filter (11)	4
Scoring filter (14)	3
Scoring filter (20)	3
HTML post	3
TOTAL: 24	16360

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	44	0	45	45
TOTAL: 1	44	0	45	45

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	csiph-out.news.weretis.net	214
	feeder.eternal-september.org	136
	newsfeeds.xmission.com	21
	news-out.mixmin.net	15
	news.swapon.de	8
	news.fcku.it	8
	news.redatomik.org	5
	newsfeed.fu-berlin.de	2
	xanadu-bbs.net	2
	news.freedyn.net	1
	TOTAL: 10	412
Including strange strings
	newsfeed.fu-berlin.de	416
	feeder.eternal-september.org	37
	csiph-out.feeder.erje.net	37
	news-out.mixmin.net	34
	csiph-out.news.weretis.net	32
	newsfeed.neva.ru	27
	news.redatomik.org	23
	news-out.aioe.org	16
	news.swapon.de	4
	xanadu-bbs.net	2
	TOTAL: 12	630
TOTAL: 4		1091

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	44761	4906	20154	110	0	10%	23:50:03
2	news.freedyn.net	28205	1170	24618	10	0	4%	23:50:03
3	xanadu-bbs	27759	872	24862	27	376	3%	23:59:23
4	fu-berlin.de	24134	584	23550	0	0	2%	23:50:03
5	usenet.pasdenom.info	29807	250	24697	994	102	0%	23:59:53
6	news.redatomik.org	26827	56	24397	32	0	0%	23:56:02
7	xmission	27986	25	24344	1	0	0%	23:59:53
8	weretis.net	20245	19	19768	0	0	0%	23:50:03
9	aioe	23202	16	21846	25	0	0%	23:56:44
10	news.fcku.it	3621	6	3424	0	0	0%	23:07:42
11	eternal-september	24705	3	21401	0	0	0%	23:59:53
12	neva.ru	24873	2	22251	39	568	0%	23:59:53
13	news.mixmin.net	23086	1	22249	2	0	0%	23:55:07
14	news.swapon.de	2438	0	2322	1	0	0%	23:54:27
15	au2pb	0	0	0	0	25176	0%	23:50:03
16	erje	18579	0	18422	48	0	0%	23:50:03
17	bwh	0	0	0	0	25159	0%	23:50:02
TOTAL: 17		350228	7910	298305	1289	51381	2%	405:39:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	12.9 MB	415.5 KB	13.3 MB	0.2 KB/s	2.7 KB	23:50:03
2	news.freedyn.net	7.5 MB	41.4 KB	7.5 MB	0.1 KB/s	6.5 KB	23:50:03
3	xanadu-bbs	6.6 MB	57.5 KB	6.7 MB	0.1 KB/s	7.6 KB	23:59:23
4	fu-berlin.de	5.9 MB	0.0 KB	5.9 MB	0.1 KB/s	10.3 KB	23:50:03
5	usenet.pasdenom.info	761.0 KB	6.8 MB	7.5 MB	0.1 KB/s	6.2 KB	23:59:53
6	news.redatomik.org	163.2 KB	160.3 KB	323.5 KB	0.0 KB/s	3.7 KB	23:56:02
7	xmission	95.3 KB	2.4 KB	97.7 KB	0.0 KB/s	3.8 KB	23:59:53
8	weretis.net	57.0 KB	0.0 KB	57.0 KB	0.0 KB/s	3.0 KB	23:50:03
9	aioe	49.3 KB	130.9 KB	180.2 KB	0.0 KB/s	4.4 KB	23:56:44
10	news.fcku.it	13.1 KB	0.0 KB	13.1 KB	0.0 KB/s	2.2 KB	23:07:42
11	eternal-september	6.8 KB	0.0 KB	6.8 KB	0.0 KB/s	2.3 KB	23:59:53
12	neva.ru	3.9 KB	122.9 KB	126.8 KB	0.0 KB/s	3.1 KB	23:59:53
13	news.mixmin.net	1.2 KB	2.6 KB	3.7 KB	0.0 KB/s	1.2 KB	23:55:07
14	news.swapon.de	0.0 KB	1.9 KB	1.9 KB	0.0 KB/s	1.9 KB	23:54:27
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
16	erje	0.0 KB	193.8 KB	193.8 KB	0.0 KB/s	4.0 KB	23:50:03
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:02
TOTAL: 17		33.9 MB	7.9 MB	41.8 MB	0.0 KB/s	4.7 KB	405:39:17

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	20	30	68.5 KB	7	03:12:56
2	challenger.nielsenmedia.com	16	19	61.6 KB	6	04:38:56
3	tempe0.bbox.io	38	5	37.2 KB	4	06:02:39
4	92-108-162-83.dynamic.upc.nl	2	2	4.5 KB	1	00:05:29
5	dhcp-089-099-203-254.chello.nl	67	2	2.7 KB	2	20:28:34
6	68-185-181-64.dhcp.mdsn.wi.charter.com	6	1	1.4 KB	1	01:19:50
7	47.192.187.101	3	1	3.3 KB	1	01:07:56
TOTAL: 7		152	60	179.2 KB	22	40:29:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.19-1.cable.virginm.net	20	30	68.5 KB	7	03:12:56
2	*.nielsenmedia.com	16	19	61.6 KB	6	04:38:56
3	*.bbox.io	38	5	37.2 KB	4	06:02:39
4	*.chello.nl	67	2	2.7 KB	2	20:28:34
5	*.dynamic.upc.nl	2	2	4.5 KB	1	00:05:29
6	*.dhcp.mdsn.wi.charter.com	6	1	1.4 KB	1	01:19:50
7	unresolved	25	1	3.3 KB	1	01:14:18
8	*.dynamic.mts-nn.ru	6	0	0.0 KB	0	03:26:55
TOTAL: 8		180	60	179.2 KB	22	40:29:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	5.650	1.537	20:28:34
tempe0.bbox.io	3.477	0.742	06:02:39
challenger.nielsenmedia.com	1.677	0.347	04:38:56
95-37-30-81.dynamic.mts-nn.ru	50.021	114.231	03:26:55
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	2.002	0.537	03:12:56
68-185-181-64.dhcp.mdsn.wi.charter.com	0.553	0.132	01:19:50
47.192.187.101	0.266	0.082	01:07:56
5.51.176.30	1.377	0.314	00:06:21
92-108-162-83.dynamic.upc.nl	0.209	0.039	00:05:29
TOTAL: 9	65.232	117.961	40:29:39

Curious NNRP server explorers [Top 20]:

System	Conn
5.51.176.30	21
95-37-30-81.dynamic.mts-nn.ru	6
216.246.166.122	1
TOTAL: 3	28

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	11	0
challenger.nielsenmedia.com	8	0
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	4	0
47.192.187.101	2	0
95-37-30-81.dynamic.mts-nn.ru	2	0
TOTAL: 5	27	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	57	95.0%
2	muc	3	5.0%
TOTAL: 2		60	100.0%

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

Newsgroup		Count
1	comp.lang.awk	20
2	comp.lang.pl1	17
3	comp.lang.rexx	13
4	comp.sys.ibm.ps2.hardware	7
5	muc.lists.freebsd.stable	2
6	muc.lists.freebsd.hackers	1
TOTAL: 6		60

Daily Usenet report for csiph.com

Oct 3 04:15:00 -- Oct 4 04:15:00