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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45763	54.4%	8.5 MB	66.5%
innd	25396	30.2%	2.7 MB	21.3%
nnrpd	9633	11.5%	1.0 MB	8.1%
innfeed	3226	3.8%	528.4 KB	4.1%
nocem	77	0.1%	8.5 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	84096	100.0%	12.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	453758	55.0%
Negative hits	325544	39.4%
Do not exist	44697	5.4%
Cache misses	1248	0.2%
TOTAL: 4	825247	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.978	0.0%	499	0.000	1.960	20.000
article cleanup	00:00:00.265	0.0%	44741	0.000	0.006	0.030
article logging	00:00:02.346	0.0%	45365	0.000	0.052	0.465
article parse	00:00:02.314	0.0%	143167	0.000	0.016	0.100
article write	00:01:21.676	0.1%	24222	0.200	3.372	15.689
artlog/artcncl	00:00:00.004	0.0%	242	0.000	0.017	0.125
artlog/artparse	00:00:00.012	0.0%	182	0.000	0.066	5.000
data move	00:00:00.295	0.0%	736438	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.542	0.0%	465	0.000	1.166	18.000
hishave/artcncl	00:00:00.007	0.0%	499	0.000	0.014	0.333
hishave/artparse	00:00:00.003	0.0%	182	0.000	0.016	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:50.025	0.1%	826917	0.003	0.060	0.312
history sync	00:00:03.941	0.0%	343	0.000	11.490	124.500
history write	00:02:21.139	0.2%	44577	0.204	3.166	16.200
hiswrite/artcncl	00:00:00.030	0.0%	34	0.000	0.882	1.167
hiswrite/artparse	00:00:00.704	0.0%	181	0.000	3.890	23.400
idle	23:50:40.571	99.3%	737342	68.957	116.419	335.516
nntp read	00:00:03.552	0.0%	753944	0.002	0.005	0.009
overview write	00:02:34.205	0.2%	24222	0.727	6.366	58.467
perl filter	00:01:44.856	0.1%	44332	1.456	2.365	3.868
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.418	0.0%	48514	0.000	0.009	0.023
TOTAL: 24:00:49.076	23:59:47.883	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.044	0.0%	24248	0.000	0.002	0.022
article prepare	00:00:00.003	0.0%	41060	0.000	0.000	0.005
article read	00:00:02.336	0.0%	7444	0.042	0.314	7.568
backlog stats	00:00:00.239	0.0%	445506	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.221	0.0%	340218	0.002	0.007	0.012
data write	00:00:01.922	0.0%	327876	0.002	0.006	0.014
idle	23:53:52.245	100.0%	445506	108.643	193.111	806.064
status file	00:00:00.295	0.0%	308	0.000	0.958	16.600
TOTAL: 23:54:12.424	23:53:59.305	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:08.408	0.0%	9056	0.000	0.928	20.000
idle	85:42:13.825	99.8%	38801	82.272	7951.698	900235.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.488	0.0%	77945	0.000	0.006	0.671
readart	00:00:14.841	0.0%	6437	0.000	2.306	60.000
TOTAL: 85:52:13.252	85:42:37.562	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	135206	7455	116550	11201	5%	94:58:05
2	csiph-out.news.weretis.net	3	49300	4471	43749	1080	9%	45:16:48
3	news-out.aioe.org	3	23583	3453	20098	32	14%	24:09:47
4	feeder.eternal-september.org	2	40194	3195	32756	4243	7%	24:29:00
5	newsfeed.fu-berlin.de	74	25943	1335	24313	295	5%	39:08:25
6	newsfeed.neva.ru	138	54765	1295	50306	3164	2%	71:40:14
7	news-out.mixmin.net	3	25718	1254	24120	344	4%	24:08:53
8	news.redatomik.org	3	24543	882	23577	84	3%	23:47:35
9	newsfeeds.xmission.com	4	20753	397	20353	3	1%	20:55:07
10	news.freedyn.net	3	27493	251	27039	203	0%	24:09:51
11	news.swapon.de	4	4379	192	4182	5	4%	45:01:25
12	news.fcku.it	1	9981	87	9880	14	0%	23:59:57
13	xanadu-bbs.net	8	809	71	736	2	8%	23:37:38
14	nntp.pasdenom.info	3	38721	66	38620	35	0%	24:09:01
15	localhost	39	5	5	0	0	100%	00:00:00
16	news.bbs.geek.nz	2	19602	2	19600	0	0%	23:59:23
TOTAL: 16		1442	500995	24411	455879	20705	4%	533:31:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	24.7 MB	821.9 KB	63.0 MB	88.4 MB	27%	4.9 KB
2	csiph-out.news.weretis.net	13.8 MB	12.0 KB	12.4 MB	26.2 MB	52%	4.8 KB
3	news-out.aioe.org	9.3 MB	15.1 KB	66.4 KB	9.4 MB	99%	2.7 KB
4	feeder.eternal-september.org	8.4 MB	91.3 KB	56.4 MB	64.9 MB	12%	8.9 KB
5	news-out.mixmin.net	6.4 MB	7.0 KB	1.3 MB	7.7 MB	82%	5.0 KB
6	newsfeed.neva.ru	3.5 MB	829.7 KB	27.9 MB	32.2 MB	10%	7.4 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	636.8 KB	3.5 MB	82%	2.2 KB
8	news.redatomik.org	2.3 MB	11.0 KB	692.5 KB	3.0 MB	76%	3.2 KB
9	newsfeeds.xmission.com	1.2 MB	0.0 KB	154.4 KB	1.3 MB	88%	3.4 KB
10	news.freedyn.net	780.0 KB	26.2 KB	1.9 MB	2.7 MB	28%	6.1 KB
11	news.swapon.de	483.2 KB	0.0 KB	49.0 KB	532.3 KB	90%	2.7 KB
12	xanadu-bbs.net	172.5 KB	0.0 KB	29.4 KB	201.9 KB	85%	2.8 KB
13	news.fcku.it	136.2 KB	0.0 KB	289.4 KB	425.7 KB	32%	4.2 KB
14	nntp.pasdenom.info	129.7 KB	43.8 KB	111.9 KB	285.4 KB	45%	2.8 KB
15	localhost	11.3 KB	0.0 KB	0.0 KB	11.3 KB	100%	2.3 KB
16	news.bbs.geek.nz	4.8 KB	0.0 KB	0.0 KB	4.8 KB	100%	2.4 KB
TOTAL: 16		74.1 MB	1.8 MB	165.0 MB	240.8 MB	30%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 10 04:15:00 - 04:59:59	552	2.3%	0.20	1.5 MB	2.1%	0.58
Jan 10 05:00:00 - 05:59:59	672	2.8%	0.19	2.6 MB	3.5%	0.73
Jan 10 06:00:00 - 06:59:59	651	2.7%	0.18	2.7 MB	3.7%	0.77
Jan 10 07:00:00 - 07:59:59	641	2.6%	0.18	2.1 MB	2.9%	0.59
Jan 10 08:00:00 - 08:59:59	857	3.5%	0.24	2.5 MB	3.4%	0.71
Jan 10 09:00:00 - 09:59:59	1083	4.5%	0.30	3.2 MB	4.4%	0.92
Jan 10 10:00:00 - 10:59:59	1108	4.6%	0.31	3.1 MB	4.2%	0.87
Jan 10 11:00:00 - 11:59:59	1090	4.5%	0.30	3.2 MB	4.4%	0.92
Jan 10 12:00:00 - 12:59:59	1022	4.2%	0.28	2.8 MB	3.8%	0.79
Jan 10 13:00:00 - 13:59:59	1257	5.2%	0.35	3.4 MB	4.7%	0.97
Jan 10 14:00:00 - 14:59:59	1292	5.3%	0.36	3.4 MB	4.7%	0.97
Jan 10 15:00:00 - 15:59:59	1448	6.0%	0.40	4.3 MB	5.8%	1.21
Jan 10 16:00:00 - 16:59:59	1659	6.9%	0.46	5.2 MB	7.1%	1.48
Jan 10 17:00:00 - 17:59:59	1555	6.4%	0.43	5.1 MB	7.0%	1.46
Jan 10 18:00:00 - 18:59:59	1369	5.7%	0.38	4.2 MB	5.7%	1.19
Jan 10 19:00:00 - 19:59:59	1413	5.8%	0.39	4.0 MB	5.5%	1.14
Jan 10 20:00:00 - 20:59:59	1235	5.1%	0.34	3.3 MB	4.5%	0.93
Jan 10 21:00:00 - 21:59:59	1069	4.4%	0.30	3.0 MB	4.1%	0.85
Jan 10 22:00:00 - 22:59:59	1043	4.3%	0.29	2.9 MB	3.9%	0.82
Jan 10 23:00:00 - 23:59:59	890	3.7%	0.25	2.7 MB	3.7%	0.77
Jan 11 00:00:00 - 00:59:59	680	2.8%	0.19	2.0 MB	2.8%	0.57
Jan 11 01:00:00 - 01:59:59	594	2.5%	0.17	1.9 MB	2.6%	0.55
Jan 11 02:00:00 - 02:59:59	517	2.1%	0.14	2.4 MB	3.3%	0.70
Jan 11 03:00:00 - 03:59:59	414	1.7%	0.12	1.3 MB	1.8%	0.37
Jan 11 04:00:00 - 04:15:00	98	0.4%	0.11	220.2 KB	0.3%	0.24
TOTAL: 24:00:00	24209	100.0%	0.28	73.1 MB	100.0%	0.87

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	11252	478	74	10700
2	feeder.eternal-september.org	4248	77	9	4162
3	newsfeed.neva.ru	3196	422	49	2725
4	csiph-out.news.weretis.net	1053	190	1	862
5	newsfeed.fu-berlin.de	748	43	0	705
6	news-out.mixmin.net	360	1	2	357
7	news.freedyn.net	202	19	6	177
8	news.redatomik.org	106	0	4	102
9	news-out.aioe.org	71	26	2	43
10	nntp.pasdenom.info	35	0	17	18
11	news.swapon.de	22	0	0	22
12	news.fcku.it	13	0	0	13
13	newsfeeds.xmission.com	4	1	0	3
14	xanadu-bbs.net	2	0	0	2
TOTAL: 14		21312	1257	164	19891

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	258
ut.test	135
it.annunci.immobiliari	83
lada.auto	71
it.annunci.varie	54
it.annunci.commerciali	53
spline.eisfair	52
it-alt.sport.calcio.juventus	39
alt.windows7.general	33
alt.survival	29
gmane.linux.alsa.devel	25
alt.politics.scorched-earth	20
lada.movies	19
lada.commerce	18
gmane.comp.gis.openstreetmap.tagging	18
mozilla.support.thunderbird	18
alt.computer.workshop	15
alt.2600	14
mpc.lists.nanog	13
it-alt.comp.software.openoffice	12
TOTAL: 134	1257

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16389
EMP (phn path)	1661
EMP (phl)	477
EMP (phn nph)	135
Too many newsgroups	39
User-issued spam cancel	25
User-issued cancel	24
HTML Multipart	23
Binary Payload	14
Scoring filter (8)	13
Scoring filter (11)	8
Scoring filter (12)	4
HTML post	3
Binary: misplaced sfv	3
Scoring filter (14)	3
Too many newsgroups (low_xpost)	3
Binary: misplaced par2	3
Binary: misplaced nfo	3
Binary Image: misplaced jpg	2
Scoring filter (9)	1
TOTAL: 27	18840

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	243
	TOTAL: 1	243
Huge articles
	feeder.eternal-september.org	91
	csiph-out.news.weretis.net	54
	news-out.mixmin.net	14
	news.freedyn.net	13
	news.redatomik.org	5
	newsfeeds.xmission.com	2
	news.fcku.it	2
	newsfeed.fu-berlin.de	1
	TOTAL: 8	182
Including strange strings
	newsfeed.fu-berlin.de	458
	csiph-out.feeder.erje.net	49
	news-out.aioe.org	39
	csiph-out.news.weretis.net	27
	news.swapon.de	18
	feeder.eternal-september.org	16
	news-out.mixmin.net	10
	news.redatomik.org	4
	newsfeed.neva.ru	2
	newsfeeds.xmission.com	1
	TOTAL: 10	624
TOTAL: 3		1049

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	40912	5478	17968	92	0	13%	23:50:05
2	xanadu-bbs	27842	692	23431	1	3	2%	23:59:51
3	news.freedyn.net	24498	677	23229	3	0	2%	23:50:05
4	erje	17475	599	15861	47	0	3%	23:50:05
5	fu-berlin.de	22515	581	21934	0	0	2%	23:50:05
6	xmission	28011	119	22914	11	60	0%	23:59:51
7	usenet.pasdenom.info	27842	99	22342	1678	0	0%	23:59:51
8	weretis.net	18285	54	17837	1	0	0%	23:59:45
9	aioe	19339	26	18349	1	0	0%	23:50:04
10	news.redatomik.org	23349	23	22596	4	6	0%	23:54:39
11	eternal-september	21924	10	20089	1	0	0%	23:59:49
12	news.mixmin.net	21425	8	20623	0	0	0%	23:55:54
13	news.fcku.it	2957	6	2878	1	0	0%	22:34:31
14	news.swapon.de	2205	5	2165	4	0	0%	23:55:11
15	neva.ru	22997	1	21842	32	0	0%	23:59:49
16	bwh	0	0	0	0	23535	0%	23:50:05
17	au2pb	0	0	0	0	23542	0%	23:50:04
TOTAL: 17		321576	8378	274058	1876	47146	2%	405:09:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	14.7 MB	706.3 KB	15.4 MB	0.2 KB/s	2.8 KB	23:50:05
2	xanadu-bbs	6.0 MB	3.6 KB	6.0 MB	0.1 KB/s	8.8 KB	23:59:51
3	news.freedyn.net	6.0 MB	14.4 KB	6.0 MB	0.1 KB/s	9.0 KB	23:50:05
4	fu-berlin.de	5.7 MB	0.0 KB	5.7 MB	0.1 KB/s	10.0 KB	23:50:05
5	erje	2.7 MB	162.5 KB	2.8 MB	0.0 KB/s	4.5 KB	23:50:05
6	xmission	359.1 KB	24.4 KB	383.5 KB	0.0 KB/s	3.0 KB	23:59:51
7	usenet.pasdenom.info	291.8 KB	7.7 MB	8.0 MB	0.1 KB/s	4.6 KB	23:59:51
8	weretis.net	165.3 KB	29.0 KB	194.4 KB	0.0 KB/s	3.5 KB	23:59:45
9	news.redatomik.org	70.2 KB	8.8 KB	78.9 KB	0.0 KB/s	2.9 KB	23:54:39
10	aioe	68.0 KB	2.3 KB	70.3 KB	0.0 KB/s	2.6 KB	23:50:04
11	eternal-september	24.0 KB	4.9 KB	29.0 KB	0.0 KB/s	2.6 KB	23:59:49
12	news.mixmin.net	21.2 KB	0.0 KB	21.2 KB	0.0 KB/s	2.7 KB	23:55:54
13	news.swapon.de	12.9 KB	8.0 KB	20.9 KB	0.0 KB/s	2.3 KB	23:55:11
14	news.fcku.it	12.8 KB	1.4 KB	14.2 KB	0.0 KB/s	2.0 KB	22:34:31
15	neva.ru	2.6 KB	221.3 KB	223.9 KB	0.0 KB/s	6.8 KB	23:59:49
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
TOTAL: 17		36.0 MB	8.9 MB	44.9 MB	0.0 KB/s	4.5 KB	405:09:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-05-70-55-56-27.dsl.bell.ca	180	121	368.4 KB	87	0	0	00:48:18
2	68-185-181-64.dhcp.mdsn.wi.charter.com	18	11	22.1 KB	6	5	5	05:29:39
3	dhcp-089-099-203-254.chello.nl	54	11	22.1 KB	5	0	0	15:22:40
4	tempe0.bbox.io	26	10	47.8 KB	2	0	0	06:51:04
5	194-73-239-109.cust.centrio.cz	11	5	14.5 KB	3	0	0	02:06:00
TOTAL: 5		289	158	474.9 KB	103	5	5	85:52:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	180	121	368.4 KB	87	0	0	00:48:18
2	*.chello.nl	54	11	22.1 KB	5	0	0	15:22:40
3	*.dhcp.mdsn.wi.charter.com	18	11	22.1 KB	6	5	5	05:29:39
4	*.bbox.io	26	10	47.8 KB	2	0	0	06:51:04
5	*.cust.centrio.cz	11	5	14.5 KB	3	0	0	02:06:00
6	unresolved	101	0	0.0 KB	0	0	0	49:02:46
7	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
8	*.251.91.75.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	05:41:39
TOTAL: 8		392	158	474.9 KB	103	5	5	85:52:09

NNRP auth users [Top 20]:

User	Conn
ctomczyk	7
ohlandl	5
TOTAL: 2	12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.479	1.452	0.000	49:02:43
dhcp-089-099-203-254.chello.nl	4.326	0.866	0.000	15:22:40
tempe0.bbox.io	2.079	0.480	0.000	06:51:04
h23.251.91.75.dynamic.ip.windstream.net	0.422	0.608	0.000	05:41:39
68-185-181-64.dhcp.mdsn.wi.charter.com	1.575	0.339	0.018	05:29:39
194-73-239-109.cust.centrio.cz	0.988	0.132	0.101	02:06:00
mtrlpq2314w-lp140-05-70-55-56-27.dsl.bell.ca	17.944	3.677	0.000	00:48:18
c-174-61-130-222.hsd1.wa.comcast.net	0.049	0.016	0.000	00:30:00
185.100.87.191	0.176	0.032	0.000	00:00:02
TOTAL: 9	35.038	7.602	0.119	85:52:09

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	99
185.100.87.191	2
c-174-61-130-222.hsd1.wa.comcast.net	1
h23.251.91.75.dynamic.ip.windstream.net	1
TOTAL: 4	103

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	96	0
tempe0.bbox.io	11	0
68-185-181-64.dhcp.mdsn.wi.charter.com	6	0
194-73-239-109.cust.centrio.cz	4	0
h23.251.91.75.dynamic.ip.windstream.net	2	0
c-174-61-130-222.hsd1.wa.comcast.net	1	0
mtrlpq2314w-lp140-05-70-55-56-27.dsl.bell.ca	1	1
TOTAL: 7	121	1

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	52	32.9%
2	uk	37	23.4%
3	comp	32	20.3%
4	rec	11	7.0%
5	muc	10	6.3%
6	linux	8	5.1%
7	soc	3	1.9%
8	talk	2	1.3%
9	relcom	1	0.6%
10	can	1	0.6%
11	sci	1	0.6%
TOTAL: 11		158	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	22
2	alt.home.repair	17
3	uk.legal	13
4	muc.lists.freebsd.current	10
5	alt.atheism	8
6	linux.kernel	8
7	uk.d-i-y	6
8	alt.politics.economics	6
9	comp.lang.python	5
10	comp.lang.javascript	5
11	alt.comp.freeware	5
12	alt.california	4
13	uk.media.radio.archers	4
14	rec.boats	4
15	uk.politics.misc	4
16	alt.christnet.christianlife	4
17	uk.rec.drugs.cannabis	3
18	alt.sports.hockey.nhl.sj-sharks	2
19	uk.tech.digital-tv	2
20	soc.culture.usa	2
21	uk.sport.cricket	2
22	rec.arts.tv.uk.coronation-st	2
23	alt.happy.valley	2
24	alt.society.liberalism	2
25	rec.sport.tennis	1
26	talk.origins	1
27	alt.os.linux	1
28	uk.rec.cycling	1
29	rec.arts.tv	1
30	talk.politics.misc	1
31	alt.os.linux.ubuntu	1
32	uk.radio.amateur	1
33	sci.physics	1
34	can.politics	1
35	uk.rec.motorcycles	1
36	rec.food.cooking	1
37	soc.culture.polish	1
38	relcom.wheels	1
39	rec.crafts.metalworking	1
40	rec.sport.football.college	1
TOTAL: 40		158

Daily Usenet report for csiph.com

Jan 10 04:15:00 -- Jan 11 04:15:00