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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	36719	64.1%	7.6 MB	75.1%
innd	16014	28.0%	1.8 MB	18.1%
innfeed	3178	5.6%	552.7 KB	5.3%
nnrpd	1252	2.2%	139.1 KB	1.3%
nocem	81	0.1%	8.7 KB	0.1%
TOTAL: 5	57244	100.0%	10.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	421439	54.7%
Negative hits	312142	40.5%
Do not exist	35792	4.6%
Cache misses	667	0.1%
TOTAL: 4	770040	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.161	0.0%	255	0.000	4.553	40.833
article cleanup	00:00:00.255	0.0%	35705	0.000	0.007	0.027
article logging	00:00:00.614	0.0%	36305	0.000	0.017	0.290
article parse	00:00:01.621	0.0%	101599	0.002	0.016	0.258
article write	00:05:35.476	0.4%	24201	0.652	13.862	55.203
artlog/artcncl	00:00:00.002	0.0%	209	0.000	0.010	0.125
artlog/artparse	00:00:00.002	0.0%	239	0.000	0.008	0.250
data move	00:00:00.201	0.0%	716518	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.128	0.0%	255	0.000	0.502	18.667
hishave/artcncl	00:00:00.003	0.0%	255	0.000	0.012	0.333
hishave/artparse	00:00:00.001	0.0%	239	0.000	0.004	0.125
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:17.080	0.0%	771801	0.001	0.022	0.404
history sync	00:00:02.035	0.0%	344	0.000	5.916	127.667
history write	00:00:39.647	0.0%	35667	0.075	1.112	18.764
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.500	0.0%	238	0.000	2.101	16.000
idle	23:50:28.496	99.3%	719240	64.455	119.332	344.182
nntp read	00:00:02.463	0.0%	732249	0.001	0.003	0.006
overview write	00:01:29.654	0.1%	24201	0.178	3.705	72.957
perl filter	00:01:14.547	0.1%	35573	1.566	2.096	6.314
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.431	0.0%	48480	0.000	0.009	0.025
TOTAL: 24:00:41.396	23:59:54.317	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.088	0.0%	24246	0.000	0.004	0.019
article prepare	00:00:00.008	0.0%	27580	0.000	0.000	0.010
article read	00:00:05.735	0.0%	4219	0.000	1.359	42.500
backlog stats	00:00:00.216	0.0%	441556	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.888	0.0%	341849	0.001	0.006	0.012
data write	00:00:02.421	0.0%	324710	0.003	0.007	0.014
idle	23:53:36.753	100.0%	441556	100.310	194.804	523.847
status file	00:00:01.110	0.0%	291	0.000	3.814	71.000
TOTAL: 23:53:57.356	23:53:48.219	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.023	0.0%	99	0.000	0.232	14.000
idle	70:21:11.077	99.8%	686	0.000	369199.821	900139.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.410	0.0%	1667	0.000	0.246	2.848
readart	00:00:11.606	0.0%	5174	0.571	2.243	55.000
TOTAL: 70:30:36.443	70:21:23.116	99.8%	-	-	-	-

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.news.weretis.net	4	52045	6271	45050	724	12%	47:43:16
2	csiph-out.feeder.erje.net	1152	119712	4947	108176	6589	4%	94:57:55
3	feeder.eternal-september.org	4	31397	3518	27382	497	11%	24:37:31
4	news-out.mixmin.net	3	27121	2268	24216	637	8%	24:08:54
5	news-out.aioe.org	3	23633	1583	22031	19	6%	24:09:52
6	newsfeed.neva.ru	146	38651	1552	34411	2688	4%	71:37:05
7	newsfeed.fu-berlin.de	50	23654	1194	22052	408	5%	28:42:01
8	news.redatomik.org	1	26524	1079	25422	23	4%	27:12:54
9	newsfeeds.xmission.com	1	22857	774	22056	27	3%	21:46:57
10	news.fcku.it	2	10215	550	9565	100	5%	23:59:37
11	xanadu-bbs.net	18	727	193	526	8	26%	21:51:11
12	nntp.pasdenom.info	3	33502	64	33417	21	0%	24:08:56
13	news.swapon.de	2	1882	56	1825	1	2%	23:27:39
14	news.freedyn.net	2	26470	42	26415	13	0%	24:02:39
15	news.bbs.geek.nz	2	20893	4	20889	0	0%	23:59:47
16	localhost	33	1	1	0	0	100%	00:00:00
TOTAL: 16		1426	459284	24096	423433	11755	5%	506:26:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	16.8 MB	42.9 KB	21.5 MB	38.3 MB	43%	3.4 KB
2	csiph-out.news.weretis.net	16.7 MB	3.1 KB	8.0 MB	24.7 MB	67%	3.6 KB
3	feeder.eternal-september.org	8.6 MB	0.0 KB	9.6 MB	18.2 MB	47%	4.6 KB
4	news-out.mixmin.net	7.8 MB	0.0 KB	1.8 MB	9.6 MB	81%	3.4 KB
5	newsfeed.neva.ru	4.2 MB	16.2 KB	11.2 MB	15.4 MB	27%	3.7 KB
6	news-out.aioe.org	3.3 MB	0.0 KB	29.2 KB	3.3 MB	99%	2.1 KB
7	news.redatomik.org	2.9 MB	2.2 KB	209.8 KB	3.1 MB	93%	2.9 KB
8	newsfeeds.xmission.com	2.6 MB	0.0 KB	2.0 MB	4.6 MB	56%	5.9 KB
9	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	866.2 KB	3.3 MB	74%	2.1 KB
10	news.fcku.it	1.1 MB	0.0 KB	1.2 MB	2.3 MB	46%	3.6 KB
11	xanadu-bbs.net	460.6 KB	0.0 KB	61.2 KB	521.9 KB	88%	2.6 KB
12	news.freedyn.net	142.1 KB	12.5 KB	154.7 KB	309.3 KB	45%	5.6 KB
13	news.swapon.de	140.7 KB	0.0 KB	20.7 KB	161.4 KB	87%	2.8 KB
14	nntp.pasdenom.info	132.6 KB	0.0 KB	378.3 KB	510.9 KB	25%	6.0 KB
15	news.bbs.geek.nz	4.9 KB	0.0 KB	0.0 KB	4.9 KB	100%	1.2 KB
16	localhost	2.0 KB	0.0 KB	0.0 KB	2.0 KB	100%	2.0 KB
TOTAL: 16		67.4 MB	76.9 KB	56.9 MB	124.3 MB	54%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 10 04:15:00 - 04:59:59	423	1.7%	0.16	1.5 MB	2.3%	0.58
Oct 10 05:00:00 - 05:59:59	569	2.4%	0.16	2.3 MB	3.4%	0.65
Oct 10 06:00:00 - 06:59:59	750	3.1%	0.21	2.7 MB	4.0%	0.77
Oct 10 07:00:00 - 07:59:59	964	4.0%	0.27	2.6 MB	3.9%	0.75
Oct 10 08:00:00 - 08:59:59	1136	4.7%	0.32	2.8 MB	4.1%	0.79
Oct 10 09:00:00 - 09:59:59	1130	4.7%	0.31	2.5 MB	3.7%	0.71
Oct 10 10:00:00 - 10:59:59	1097	4.5%	0.30	2.8 MB	4.2%	0.80
Oct 10 11:00:00 - 11:59:59	1116	4.6%	0.31	3.0 MB	4.4%	0.85
Oct 10 12:00:00 - 12:59:59	1196	4.9%	0.33	3.0 MB	4.4%	0.85
Oct 10 13:00:00 - 13:59:59	1360	5.6%	0.38	3.3 MB	5.0%	0.95
Oct 10 14:00:00 - 14:59:59	1533	6.3%	0.43	4.1 MB	6.1%	1.18
Oct 10 15:00:00 - 15:59:59	1452	6.0%	0.40	4.1 MB	6.0%	1.16
Oct 10 16:00:00 - 16:59:59	1418	5.9%	0.39	3.7 MB	5.5%	1.06
Oct 10 17:00:00 - 17:59:59	1440	6.0%	0.40	3.9 MB	5.7%	1.10
Oct 10 18:00:00 - 18:59:59	1279	5.3%	0.36	3.7 MB	5.5%	1.06
Oct 10 19:00:00 - 19:59:59	1259	5.2%	0.35	3.5 MB	5.2%	0.99
Oct 10 20:00:00 - 20:59:59	1272	5.3%	0.35	3.6 MB	5.4%	1.03
Oct 10 21:00:00 - 21:59:59	1101	4.6%	0.31	3.3 MB	4.9%	0.94
Oct 10 22:00:00 - 22:59:59	932	3.9%	0.26	3.0 MB	4.4%	0.84
Oct 10 23:00:00 - 23:59:59	655	2.7%	0.18	1.8 MB	2.7%	0.51
Oct 11 00:00:00 - 00:59:59	566	2.3%	0.16	1.5 MB	2.3%	0.44
Oct 11 01:00:00 - 01:59:59	494	2.0%	0.14	1.5 MB	2.2%	0.42
Oct 11 02:00:00 - 02:59:59	476	2.0%	0.13	1.5 MB	2.2%	0.42
Oct 11 03:00:00 - 03:59:59	473	2.0%	0.13	1.4 MB	2.0%	0.39
Oct 11 04:00:00 - 04:15:00	103	0.4%	0.11	372.7 KB	0.5%	0.41
TOTAL: 24:00:00	24194	100.0%	0.28	67.4 MB	100.0%	0.80

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	csiph-out.feeder.erje.net	6609	379	17	0	0	6213
2	newsfeed.neva.ru	2680	1029	6	0	0	1645
3	newsfeed.fu-berlin.de	827	59	0	0	0	768
4	csiph-out.news.weretis.net	745	271	2	0	0	472
5	news-out.mixmin.net	683	10	1	0	0	672
6	feeder.eternal-september.org	489	83	2	0	0	404
7	news.fcku.it	95	0	0	1	0	94
8	newsfeeds.xmission.com	46	0	0	0	0	46
9	news-out.aioe.org	40	18	0	0	0	22
10	news.redatomik.org	38	0	1	0	0	37
11	news.freedyn.net	35	0	1	0	0	34
12	nntp.pasdenom.info	20	0	0	0	1	19
13	xanadu-bbs.net	8	0	0	0	0	8
14	news.swapon.de	6	0	0	0	0	6
TOTAL: 14		12321	1849	30	1	1	10440

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	506
lada.talk	190
ut.test	156
lada.auto	95
fa.freebsd.cvs-all	88
alt.windows7.general	47
it-alt.sport.calcio.juventus	40
spline.eisfair	35
fa.openbsd.tech	34
mozilla.support.seamonkey	32
fa.openbsd.source-changes	25
cakewalk.audio	24
fa.philos-l	23
gmane.linux.alsa.devel	20
mozilla.dev.security.policy	19
mpc.lists.nanog	19
mozilla.dev.platform	19
it-alt.test	19
alt.survival	17
lada.commerce	17
TOTAL: 141	1849

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

Groups	Count
alt.culture.china	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7101
EMP (phn path)	1341
EMP (phl)	636
EMP (phn nph)	105
Binary Payload	58
Too many newsgroups	54
EMP (fsl)	34
Scoring filter (10)	31
Scoring filter (8)	28
User-issued cancel	27
Scoring filter (18)	13
Scoring filter (9)	13
HTML post	10
HTML Multipart	9
Scoring filter (17)	7
Scoring filter (13)	5
Scoring filter (19)	4
Scoring filter (16)	3
Scoring filter (11)	3
Scoring filter (15)	3
TOTAL: 31	9501

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	91
	TOTAL: 1	91
Huge articles
	feeder.eternal-september.org	76
	csiph-out.news.weretis.net	55
	newsfeeds.xmission.com	41
	news.freedyn.net	25
	news.fcku.it	16
	news-out.mixmin.net	14
	news.redatomik.org	6
	nntp.pasdenom.info	5
	xanadu-bbs.net	1
	TOTAL: 9	239
Including strange strings
	newsfeed.fu-berlin.de	401
	news-out.mixmin.net	57
	csiph-out.news.weretis.net	40
	csiph-out.feeder.erje.net	32
	news-out.aioe.org	21
	feeder.eternal-september.org	19
	newsfeed.neva.ru	10
	news.redatomik.org	10
	news.swapon.de	5
	newsfeeds.xmission.com	3
	TOTAL: 11	599
TOTAL: 3		929

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	42958	2674	20734	36	0	6%	23:50:06
2	news.freedyn.net	24704	1077	23003	0	0	4%	23:50:06
3	xanadu-bbs	26568	762	23228	3	98	2%	23:59:54
4	fu-berlin.de	22545	607	21938	0	0	2%	23:50:10
5	usenet.pasdenom.info	26703	253	22650	1228	0	0%	23:59:48
6	erje	20460	78	18951	21	0	0%	23:55:53
7	news.redatomik.org	23330	52	22440	12	0	0%	23:57:24
8	xmission	25194	25	22573	6	0	0%	23:59:54
9	aioe	21508	15	20549	1	0	0%	23:50:06
10	weretis.net	16332	11	16150	1	0	0%	23:50:06
11	eternal-september	20982	4	19591	0	0	0%	23:59:54
12	news.mixmin.net	21607	3	20946	0	0	0%	23:52:38
13	news.swapon.de	1929	1	1900	1	0	0%	23:43:15
14	news.fcku.it	3465	1	3369	0	0	0%	22:56:58
15	neva.ru	22534	0	21508	31	0	0%	23:59:54
16	bwh	0	0	0	0	23423	0%	23:50:03
17	au2pb	0	0	0	0	23458	0%	23:50:04
TOTAL: 17		320819	5563	279530	1340	46979	1%	405:16:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	6.8 MB	128.1 KB	6.9 MB	0.1 KB/s	2.6 KB	23:50:06
2	news.freedyn.net	6.8 MB	0.0 KB	6.8 MB	0.1 KB/s	6.5 KB	23:50:06
3	xanadu-bbs	6.2 MB	6.1 KB	6.2 MB	0.1 KB/s	8.3 KB	23:59:54
4	fu-berlin.de	5.6 MB	0.0 KB	5.6 MB	0.1 KB/s	9.5 KB	23:50:10
5	usenet.pasdenom.info	597.5 KB	6.7 MB	7.3 MB	0.1 KB/s	5.0 KB	23:59:48
6	erje	474.4 KB	85.4 KB	559.8 KB	0.0 KB/s	5.7 KB	23:55:53
7	news.redatomik.org	136.9 KB	58.4 KB	195.3 KB	0.0 KB/s	3.1 KB	23:57:24
8	xmission	73.2 KB	18.1 KB	91.3 KB	0.0 KB/s	2.9 KB	23:59:54
9	aioe	40.8 KB	1.1 KB	41.9 KB	0.0 KB/s	2.6 KB	23:50:06
10	weretis.net	39.3 KB	2.0 KB	41.3 KB	0.0 KB/s	3.4 KB	23:50:06
11	eternal-september	10.3 KB	0.0 KB	10.3 KB	0.0 KB/s	2.6 KB	23:59:54
12	news.mixmin.net	9.4 KB	0.0 KB	9.4 KB	0.0 KB/s	3.1 KB	23:52:38
13	news.swapon.de	2.0 KB	2.2 KB	4.2 KB	0.0 KB/s	2.1 KB	23:43:15
14	news.fcku.it	1.6 KB	0.0 KB	1.6 KB	0.0 KB/s	1.6 KB	22:56:58
15	neva.ru	0.0 KB	82.4 KB	82.4 KB	0.0 KB/s	2.7 KB	23:59:54
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
TOTAL: 17		26.8 MB	7.1 MB	33.8 MB	0.0 KB/s	5.0 KB	405:16:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	5	55	121.6 KB	3	1	1	02:12:55
2	tempe0.bbox.io	38	14	65.6 KB	5	0	0	06:11:52
TOTAL: 2		43	69	187.2 KB	8	1	1	70:30:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.19-1.cable.virginm.net	5	55	121.6 KB	3	1	1	02:12:55
2	*.bbox.io	38	14	65.6 KB	5	0	0	06:11:52
3	*.hsd1.nj.comcast.net	6	0	0.0 KB	0	0	0	02:31:58
4	*.dynamic.mts-nn.ru	1	0	0.0 KB	0	0	0	00:00:01
5	*.chello.nl	50	0	0.0 KB	0	0	0	08:02:40
6	unresolved	100	0	0.0 KB	0	0	0	48:42:05
7	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
8	*.dhcp.mdsn.wi.charter.com	6	0	0.0 KB	0	0	0	02:19:00
TOTAL: 8		207	69	187.2 KB	8	1	1	70:30:34

NNRP auth users [Top 20]:

User	Conn
jwood	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.918	2.015	0.000	48:41:47
dhcp-089-099-203-254.chello.nl	4.133	1.073	0.000	08:02:40
tempe0.bbox.io	3.444	0.979	0.000	06:11:52
c-73-195-247-24.hsd1.nj.comcast.net	0.422	0.113	0.000	02:31:58
68-185-181-64.dhcp.mdsn.wi.charter.com	0.536	0.121	0.000	02:19:00
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	0.646	0.162	0.003	02:12:55
c-174-61-130-222.hsd1.wa.comcast.net	0.093	0.017	0.000	00:30:00
196.52.16.30	0.252	0.412	0.000	00:00:17
95-37-30-81.dynamic.mts-nn.ru	0.072	0.008	0.000	00:00:01
TOTAL: 9	17.516	4.900	0.003	70:30:34

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	99
dhcp-089-099-203-254.chello.nl	50
68-185-181-64.dhcp.mdsn.wi.charter.com	6
c-73-195-247-24.hsd1.nj.comcast.net	6
c-174-61-130-222.hsd1.wa.comcast.net	1
196.52.16.30	1
95-37-30-81.dynamic.mts-nn.ru	1
TOTAL: 7	164

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	95	0
tempe0.bbox.io	10	0
c-73-195-247-24.hsd1.nj.comcast.net	5	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	4	0
c-174-61-130-222.hsd1.wa.comcast.net	1	0
TOTAL: 6	119	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	56	81.2%
2	muc	10	14.5%
3	news	3	4.3%
TOTAL: 3		69	100.0%

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

Newsgroup		Count
1	comp.lang.pl1	55
2	muc.lists.freebsd.stable	10
3	news.software.nntp	3
4	comp.sys.ibm.as400.misc	1
TOTAL: 4		69

Daily Usenet report for csiph.com

Oct 10 04:15:00 -- Oct 11 04:15:00