Daily Usenet report for csiph.com: Jul 30 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	23315	50.9%	5.1 MB	65.6%
innd	11640	25.4%	1.5 MB	18.9%
nnrpd	7941	17.3%	733.8 KB	9.2%
innfeed	2891	6.3%	496.4 KB	6.2%
nocem	43	0.1%	4.7 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	45831	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	329786	58.3%
Negative hits	213251	37.7%
Do not exist	22467	4.0%
Cache misses	231	0.0%
TOTAL: 4	565735	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.099	0.0%	83	0.000	1.193	15.000
article cleanup	00:00:00.157	0.0%	22264	0.000	0.007	0.039
article logging	00:00:00.347	0.0%	23020	0.000	0.015	0.067
article parse	00:00:00.875	0.0%	60007	0.000	0.015	0.053
article write	00:00:16.681	0.0%	18824	0.177	0.886	10.265
artlog/artcncl	00:00:00.000	0.0%	70	0.000	0.000	0.000
artlog/artparse	00:00:00.002	0.0%	258	0.000	0.008	1.000
data move	00:00:00.157	0.0%	538223	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.003	0.0%	83	0.000	0.036	1.000
hishave/artcncl	00:00:00.004	0.0%	83	0.000	0.048	0.500
hishave/artparse	00:00:00.000	0.0%	258	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:07.651	0.0%	566686	0.002	0.014	1.103
history sync	00:00:00.733	0.0%	371	0.000	1.976	5.500
history write	00:00:10.011	0.0%	22260	0.051	0.450	0.740
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.138	0.0%	258	0.000	0.535	3.000
idle	23:58:42.778	99.8%	542304	86.984	159.178	451.293
nntp read	00:00:02.913	0.0%	543901	0.002	0.005	0.038
overview write	00:00:16.204	0.0%	18824	0.138	0.861	59.000
perl filter	00:00:43.580	0.1%	22259	1.384	1.958	5.071
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.356	0.0%	37687	0.000	0.009	0.035
TOTAL: 24:01:02.128	24:00:22.689	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.052	0.0%	18881	0.000	0.003	0.023
article prepare	00:00:00.004	0.0%	16700	0.000	0.000	0.021
article read	00:00:00.400	0.0%	1799	0.000	0.222	3.364
backlog stats	00:00:00.226	0.0%	361670	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.926	0.0%	273329	0.002	0.007	0.015
data write	00:00:01.757	0.0%	256379	0.002	0.007	0.015
idle	23:54:16.870	100.0%	361670	126.695	237.943	679.188
status file	00:00:00.219	0.0%	271	0.000	0.808	16.500
TOTAL: 23:54:34.093	23:54:21.454	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.003	0.0%	3	1.000	1.000	1.000
idle	22:45:37.068	98.6%	9879	137.333	8294.065	600099.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:06:31.310	0.5%	2495959	0.000	0.157	1020.818
readart	00:01:03.398	0.1%	1893	0.000	33.491	111.000
TOTAL: 23:04:59.643	22:53:11.779	99.1%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	37629	4471	32856	302	11%	47:40:54
2	csiph-out.feeder.erje.net	1728	115194	2722	110994	1478	2%	143:24:15
3	feeder.eternal-september.org	6	20100	2553	17067	480	12%	23:49:13
4	news.redatomik.org	4	17822	2199	15499	124	12%	24:17:12
5	news-out.aioe.org	4	18220	1770	16381	69	9%	24:09:40
6	newsfeeds.xmission.com	3	19250	1382	17860	8	7%	27:52:02
7	newsfeed.fu-berlin.de	9	15988	1172	14477	339	7%	21:41:15
8	news-out.mixmin.net	2	19526	1120	18041	365	5%	24:08:44
9	newsfeed.neva.ru	144	20488	723	19379	386	3%	71:50:58
10	news.fcku.it	2	8332	427	7809	96	5%	23:59:48
11	news.swapon.de	5	2114	85	2026	3	4%	23:24:25
12	nntp.pasdenom.info	2	17451	61	17374	16	0%	23:59:12
13	news.freedyn.net	1	20465	8	20451	6	0%	25:03:13
14	localhost	31	1	1	0	0	100%	00:00:00
15	news.bbs.nz	2	16703	0	16703	0	0%	23:59:51
16	xanadu-bbs.net	11	716	0	716	0	0%	23:18:21
TOTAL: 16		1959	349999	18694	327633	3672	5%	552:39:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	14.4 MB	0.0 KB	7.9 MB	22.2 MB	64%	4.8 KB
2	csiph-out.feeder.erje.net	14.2 MB	0.0 KB	11.3 MB	25.6 MB	55%	6.2 KB
3	feeder.eternal-september.org	6.9 MB	2.2 KB	2.4 MB	9.3 MB	74%	3.1 KB
4	news.redatomik.org	5.7 MB	2.1 KB	770.4 KB	6.5 MB	88%	2.8 KB
5	news-out.aioe.org	4.5 MB	0.0 KB	377.8 KB	4.8 MB	92%	2.7 KB
6	newsfeeds.xmission.com	4.0 MB	0.0 KB	614.1 KB	4.5 MB	86%	3.4 KB
7	news-out.mixmin.net	3.8 MB	2.1 KB	870.7 KB	4.7 MB	81%	3.2 KB
8	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	633.2 KB	3.2 MB	80%	2.2 KB
9	newsfeed.neva.ru	2.0 MB	0.0 KB	1.5 MB	3.5 MB	56%	3.2 KB
10	news.fcku.it	1.2 MB	0.0 KB	606.9 KB	1.7 MB	66%	3.4 KB
11	news.swapon.de	234.1 KB	0.0 KB	47.1 KB	281.2 KB	83%	3.2 KB
12	nntp.pasdenom.info	147.2 KB	0.0 KB	32.0 KB	179.3 KB	82%	2.3 KB
13	news.freedyn.net	17.1 KB	2.2 KB	8.1 KB	27.4 KB	62%	2.0 KB
14	localhost	2.0 KB	0.0 KB	0.0 KB	2.0 KB	100%	2.0 KB
15	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
16	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		59.5 MB	8.6 KB	27.0 MB	86.5 MB	68%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 30 04:15:00 - 04:59:59	330	1.8%	0.12	1.5 MB	2.5%	0.58
Jul 30 05:00:00 - 05:59:59	456	2.4%	0.13	1.7 MB	2.9%	0.50
Jul 30 06:00:00 - 06:59:59	515	2.7%	0.14	2.0 MB	3.3%	0.56
Jul 30 07:00:00 - 07:59:59	735	3.9%	0.20	3.2 MB	5.3%	0.90
Jul 30 08:00:00 - 08:59:59	754	4.0%	0.21	2.4 MB	4.0%	0.67
Jul 30 09:00:00 - 09:59:59	798	4.2%	0.22	2.3 MB	3.8%	0.66
Jul 30 10:00:00 - 10:59:59	837	4.5%	0.23	2.4 MB	4.0%	0.69
Jul 30 11:00:00 - 11:59:59	868	4.6%	0.24	2.3 MB	3.8%	0.64
Jul 30 12:00:00 - 12:59:59	940	5.0%	0.26	2.4 MB	4.0%	0.68
Jul 30 13:00:00 - 13:59:59	1065	5.7%	0.30	3.2 MB	5.3%	0.91
Jul 30 14:00:00 - 14:59:59	1151	6.1%	0.32	3.5 MB	5.9%	1.00
Jul 30 15:00:00 - 15:59:59	1220	6.5%	0.34	4.4 MB	7.4%	1.26
Jul 30 16:00:00 - 16:59:59	997	5.3%	0.28	3.0 MB	5.0%	0.85
Jul 30 17:00:00 - 17:59:59	1134	6.0%	0.32	3.3 MB	5.5%	0.93
Jul 30 18:00:00 - 18:59:59	1034	5.5%	0.29	3.2 MB	5.3%	0.90
Jul 30 19:00:00 - 19:59:59	958	5.1%	0.27	2.7 MB	4.5%	0.77
Jul 30 20:00:00 - 20:59:59	951	5.1%	0.26	3.2 MB	5.3%	0.90
Jul 30 21:00:00 - 21:59:59	881	4.7%	0.24	2.7 MB	4.5%	0.77
Jul 30 22:00:00 - 22:59:59	570	3.0%	0.16	1.9 MB	3.2%	0.54
Jul 30 23:00:00 - 23:59:59	623	3.3%	0.17	1.8 MB	3.0%	0.51
Jul 31 00:00:00 - 00:59:59	529	2.8%	0.15	1.7 MB	2.8%	0.48
Jul 31 01:00:00 - 01:59:59	457	2.4%	0.13	1.7 MB	2.9%	0.49
Jul 31 02:00:00 - 02:59:59	466	2.5%	0.13	1.8 MB	3.0%	0.51
Jul 31 03:00:00 - 03:59:59	442	2.4%	0.12	1.4 MB	2.3%	0.39
Jul 31 04:00:00 - 04:15:00	97	0.5%	0.11	426.7 KB	0.7%	0.47
TOTAL: 24:00:00	18808	100.0%	0.22	59.9 MB	100.0%	0.71

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	1491	100	0	1391
2	newsfeed.fu-berlin.de	960	49	0	911
3	feeder.eternal-september.org	505	35	1	469
4	newsfeed.neva.ru	388	108	0	280
5	news-out.mixmin.net	374	3	1	370
6	csiph-out.news.weretis.net	304	140	0	164
7	news.redatomik.org	177	0	1	176
8	news.fcku.it	102	2	0	100
9	news-out.aioe.org	77	69	0	8
10	news.swapon.de	28	0	0	28
11	nntp.pasdenom.info	16	6	0	10
12	newsfeeds.xmission.com	9	0	0	9
13	news.freedyn.net	6	0	1	5
14	news.bbs.nz	3	3	0	0
TOTAL: 14		4440	515	4	3921

Unwanted newsgroups [Top 20]:

Newsgroup	Count
spline.eisfair	54
gmane.linux.alsa.devel	52
lada.auto	39
lada.talk	37
gmane.linux.kernel	28
gmane.comp.gis.openstreetmap.tagging	23
it-alt.sport.calcio.juventus	19
alt.survival	18
alt.politics.usa	17
alt.windows7.general	17
alt.politics	15
mozilla.support.thunderbird	11
alt.support.domestic-violence	11
oesterreich.tratsch	10
mozilla.dev.platform	8
alt.binaries.ebooks	7
alt.slack.goathead	7
local.spam.fr	6
mozilla.dev.apps.thunderbird	6
lada.commerce	6
TOTAL: 100	515

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2278
EMP (phn path)	291
EMP (phl)	146
Too many newsgroups	82
HTML Multipart	26
Scoring filter (10)	16
User-issued cancel	12
Binary Payload	11
User-issued spam cancel	10
Scoring filter (9)	7
Scoring filter (13)	6
Binary: misplaced rar	6
Binary: misplaced par2	5
Scoring filter (11)	5
Scoring filter (12)	1
Binary: misplaced 026	1
Binary: misplaced 002	1
U2 violation - invalid distribution	1
Binary Image: misplaced jpg	1
Binary: misplaced 004	1
TOTAL: 22	2909

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	24	0	26	26
TOTAL: 1	24	15	26	26

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	78
	TOTAL: 1	78
Huge articles
	csiph-out.feeder.erje.net	157
	csiph-out.news.weretis.net	80
	newsfeeds.xmission.com	7
	feeder.eternal-september.org	5
	news.redatomik.org	3
	news-out.mixmin.net	3
	news.fcku.it	1
	TOTAL: 7	256
Including strange strings
	newsfeed.fu-berlin.de	629
	news.redatomik.org	58
	news.swapon.de	25
	csiph-out.feeder.erje.net	15
	news-out.mixmin.net	9
	csiph-out.news.weretis.net	8
	news-out.aioe.org	7
	newsfeeds.xmission.com	2
	newsfeed.neva.ru	2
	TOTAL: 9	755
TOTAL: 3		1089

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	20215	788	18007	0	0	3%	23:58:40
2	xanadu-bbs	20782	758	18047	2	95	3%	23:59:51
3	fu-berlin.de	17269	657	16612	0	0	3%	23:54:38
4	usenet.pasdenom.info	20407	208	17188	1353	9	1%	23:59:40
5	news.bbs.nz	35475	181	17868	10	0	0%	23:51:51
6	erje	17042	19	15977	5	0	0%	23:50:06
7	weretis.net	13749	13	13140	6	0	0%	23:57:47
8	neva.ru	17416	9	16984	174	0	0%	23:50:06
9	aioe	16196	8	15460	0	0	0%	23:56:29
10	news.mixmin.net	17376	3	16874	0	0	0%	23:57:00
11	xmission	20559	3	16683	0	0	0%	23:50:06
12	eternal-september	16192	3	15229	2	0	0%	23:50:06
13	news.fcku.it	1631	2	1620	0	0	0%	22:02:47
14	news.swapon.de	2141	1	2114	0	0	0%	23:48:53
15	news.redatomik.org	16585	1	15818	0	9	0%	23:52:50
TOTAL: 15		253035	2654	217621	1552	113	1%	356:40:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.9 MB	0.0 KB	7.9 MB	0.1 KB/s	10.2 KB	23:58:40
2	xanadu-bbs	7.8 MB	4.5 KB	7.8 MB	0.1 KB/s	10.5 KB	23:59:51
3	fu-berlin.de	7.4 MB	0.0 KB	7.4 MB	0.1 KB/s	11.5 KB	23:54:38
4	usenet.pasdenom.info	563.1 KB	8.6 MB	9.2 MB	0.1 KB/s	6.0 KB	23:59:40
5	news.bbs.nz	453.1 KB	37.1 KB	490.2 KB	0.0 KB/s	2.6 KB	23:51:51
6	erje	56.7 KB	112.4 KB	169.1 KB	0.0 KB/s	7.0 KB	23:50:06
7	weretis.net	42.9 KB	13.0 KB	55.9 KB	0.0 KB/s	2.9 KB	23:57:47
8	eternal-september	37.6 KB	16.4 KB	54.0 KB	0.0 KB/s	10.8 KB	23:50:06
9	news.swapon.de	35.6 KB	0.0 KB	35.6 KB	0.0 KB/s	35.6 KB	23:48:53
10	neva.ru	17.4 KB	325.7 KB	343.1 KB	0.0 KB/s	1.9 KB	23:50:06
11	aioe	14.8 KB	0.0 KB	14.8 KB	0.0 KB/s	1.9 KB	23:56:29
12	news.mixmin.net	7.8 KB	0.0 KB	7.8 KB	0.0 KB/s	2.6 KB	23:57:00
13	xmission	7.5 KB	0.0 KB	7.5 KB	0.0 KB/s	2.5 KB	23:50:06
14	news.fcku.it	2.5 KB	0.0 KB	2.5 KB	0.0 KB/s	1.2 KB	22:02:47
15	news.redatomik.org	2.0 KB	0.0 KB	2.0 KB	0.0 KB/s	2.0 KB	23:52:50
TOTAL: 15		24.2 MB	9.1 MB	33.4 MB	0.0 KB/s	8.1 KB	356:40:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-02-70-24-221-125.dsl.bell.ca	127	1697	4.6 MB	856	0	0	03:10:07
2	141.168.117.108	48	169	481.6 KB	72	0	3	00:47:08
3	ip72-222-200-117.ph.ph.cox.net	19	20	25.9 KB	6	0	0	05:03:14
4	208-71-239-109.cust.centrio.cz	12	4	6.3 KB	3	0	0	04:47:35
5	71-90-72-157.dhcp.ftbg.wi.charter.com	4	2	3.7 KB	2	1	1	01:06:43
6	a131033.upc-a.chello.nl	41	1	1.6 KB	1	0	0	06:39:27
TOTAL: 6		251	1893	5.1 MB	940	1	4	23:04:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	127	1697	4.6 MB	856	0	0	03:10:07
2	unresolved	49	169	481.6 KB	72	0	3	00:47:18
3	*.ph.ph.cox.net	19	20	25.9 KB	6	0	0	05:03:14
4	*.cust.centrio.cz	12	4	6.3 KB	3	0	0	04:47:35
5	*.dhcp.ftbg.wi.charter.com	4	2	3.7 KB	2	1	1	01:06:43
6	*.upc-a.chello.nl	41	1	1.6 KB	1	0	0	06:39:27
7	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:11
8	*.nielsenmedia.com	2	0	0.0 KB	0	0	0	01:00:18
TOTAL: 8		255	1893	5.1 MB	940	1	4	23:04:57

NNRP auth users [Top 20]:

User	Conn
ctomczyk	12
ohlandl	1
TOTAL: 2	13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.127	0.477	0.000	06:39:27
ip72-222-200-117.ph.ph.cox.net	1.590	0.293	0.000	05:03:14
208-71-239-109.cust.centrio.cz	1.115	0.152	0.039	04:47:35
mtrlpq2314w-lp130-02-70-24-221-125.dsl.bell.ca	40.808	102.133	0.000	03:10:07
71-90-72-157.dhcp.ftbg.wi.charter.com	0.434	0.063	0.003	01:06:43
challenger.nielsenmedia.com	0.171	0.032	0.000	01:00:18
141.168.117.108	5.683	0.990	0.000	00:47:08
c-67-160-62-214.hsd1.wa.comcast.net	0.088	0.015	0.000	00:30:11
107.170.225.202	0.072	0.032	0.000	00:00:09
TOTAL: 9	53.088	104.187	0.042	23:04:57

Curious NNRP server explorers [Top 20]:

System	Conn
challenger.nielsenmedia.com	2
c-67-160-62-214.hsd1.wa.comcast.net	1
107.170.225.202	1
TOTAL: 3	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
mtrlpq2314w-lp130-02-70-24-221-125.dsl.bell.ca	31	31
208-71-239-109.cust.centrio.cz	9	0
ip72-222-200-117.ph.ph.cox.net	6	0
71-90-72-157.dhcp.ftbg.wi.charter.com	2	0
challenger.nielsenmedia.com	2	0
c-67-160-62-214.hsd1.wa.comcast.net	1	0
TOTAL: 6	51	31

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	2131	94.2%
2	comp	127	5.6%
3	pl	4	0.2%
TOTAL: 3		2262	100.0%

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

Newsgroup		Count
1	alt.appalachian	1098
2	alt.activism.death-penalty	537
3	alt.abortion	431
4	comp.os.vms	65
5	alt.comp.freeware	65
6	comp.sys.ibm.ps2.hardware	25
7	comp.arch	15
8	comp.ai.philosophy	11
9	comp.os.os2.advocacy	4
10	pl.comp.lang.javascript	4
11	comp.ai.neural-nets	2
12	comp.sys.ibm.pc.hardware.storage	1
13	comp.arch.embedded	1
14	comp.sys.ibm.pc.games.war-historical	1
15	comp.os.msdos.programmer	1
16	comp.sys.ibm.pc.games.action	1
TOTAL: 16		2262

Daily Usenet report for csiph.com

Jul 30 04:15:00 -- Jul 31 04:15:00