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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	52947	60.8%	5.6 MB	47.1%
inn	20598	23.7%	4.5 MB	37.9%
innd	10633	12.2%	1.3 MB	11.3%
innfeed	2850	3.3%	447.5 KB	3.7%
nocem	8	0.0%	0.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	87037	100.0%	11.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	266579	57.2%
Negative hits	178951	38.4%
Do not exist	19982	4.3%
Cache misses	336	0.1%
TOTAL: 4	465848	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.051	0.0%	43	1.000	47.698	279.000
article cleanup	00:00:00.167	0.0%	19710	0.000	0.008	0.041
article logging	00:00:00.516	0.0%	20212	0.000	0.026	0.353
article parse	00:00:02.866	0.0%	53560	0.000	0.054	0.788
article write	00:08:10.879	0.6%	16205	0.277	30.292	159.214
artlog/artcncl	00:00:00.002	0.0%	34	0.000	0.059	1.000
artlog/artparse	00:00:00.006	0.0%	351	0.000	0.017	0.500
data move	00:00:00.144	0.0%	439919	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.262	0.0%	43	0.000	6.093	23.444
hishave/artcncl	00:00:00.074	0.0%	43	0.000	1.721	7.556
hishave/artparse	00:00:00.001	0.0%	351	0.000	0.003	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:20.210	0.2%	467381	0.002	0.428	1.966
history sync	00:00:02.052	0.0%	371	0.000	5.531	175.000
history write	00:02:56.747	0.2%	19707	0.127	8.969	39.562
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:02.168	0.0%	351	0.000	6.177	240.500
idle	23:35:55.749	98.3%	423923	103.800	200.404	524.582
nntp read	00:00:06.421	0.0%	448819	0.002	0.014	0.529
overview write	00:08:44.147	0.6%	16205	1.240	32.345	147.154
perl filter	00:00:41.881	0.0%	19707	1.470	2.125	17.984
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.326	0.0%	32418	0.000	0.010	0.128
TOTAL: 24:01:02.659	24:00:06.669	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	17931	0.000	0.002	0.028
article prepare	00:00:00.002	0.0%	15300	0.000	0.000	0.062
article read	00:00:05.765	0.0%	1903	0.000	3.029	51.000
backlog stats	00:00:00.194	0.0%	298769	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.711	0.0%	228132	0.001	0.008	0.015
data write	00:00:01.383	0.0%	214053	0.002	0.006	0.016
idle	23:54:26.489	100.0%	298769	166.900	288.070	662.508
status file	00:00:01.093	0.0%	262	0.000	4.172	495.000
TOTAL: 23:54:47.044	23:54:36.675	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	837:50:07.926	97.3%	17033672	102.601	177.073	600081.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:04:32.843	0.0%	49165807	0.000	0.006	2.444
readart	22:49:33.204	2.7%	16901946	0.000	4.862	733.000
TOTAL: 860:59:46.747	860:44:13.973	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1439	84140	5918	77443	779	7%	118:41:05
2	csiph-out.news.weretis.net	4	32830	2500	30057	273	7%	47:16:53
3	feeder.eternal-september.org	3	17983	2075	15394	514	11%	23:27:06
4	news-out.aioe.org	2	15814	1360	14425	29	8%	24:09:58
5	newsfeed.fu-berlin.de	6	17148	1023	15931	194	5%	29:08:37
6	newsfeeds.xmission.com	2	15392	912	14463	17	5%	24:03:15
7	newsfeed.neva.ru	145	21657	849	19716	1092	3%	71:58:32
8	news-out.mixmin.net	2	17138	642	15738	758	3%	24:29:06
9	news.redatomik.org	3	15676	596	15033	47	3%	24:09:26
10	news.fcku.it	4	1610	103	1503	4	6%	24:05:05
11	nntp.pasdenom.info	2	14818	91	14686	41	0%	23:58:48
12	news.freedyn.net	2	17357	58	17207	92	0%	23:35:20
13	news.swapon.de	4	1418	42	1376	0	2%	23:14:49
14	localhost	32	6	6	0	0	100%	00:00:10
15	news.bbs.nz	1	16739	2	16737	0	0%	23:59:53
16	xanadu-bbs.net	19	584	0	584	0	0%	27:06:43
TOTAL: 16		1670	290310	16177	270293	3840	5%	533:24:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.2 MB	0.0 KB	11.3 MB	31.4 MB	64%	4.8 KB
2	csiph-out.news.weretis.net	7.8 MB	0.0 KB	5.7 MB	13.5 MB	57%	5.0 KB
3	feeder.eternal-september.org	5.8 MB	0.0 KB	7.3 MB	13.1 MB	44%	5.2 KB
4	news-out.aioe.org	3.6 MB	0.0 KB	206.8 KB	3.8 MB	94%	2.8 KB
5	newsfeeds.xmission.com	3.3 MB	0.0 KB	782.5 KB	4.0 MB	81%	4.5 KB
6	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	589.7 KB	3.2 MB	81%	2.7 KB
7	newsfeed.neva.ru	2.4 MB	8.1 KB	3.2 MB	5.7 MB	43%	3.0 KB
8	news-out.mixmin.net	2.2 MB	0.0 KB	1.6 MB	3.8 MB	57%	2.8 KB
9	news.redatomik.org	1.9 MB	0.0 KB	1.3 MB	3.2 MB	60%	5.0 KB
10	nntp.pasdenom.info	241.3 KB	8.0 KB	116.8 KB	366.2 KB	65%	2.8 KB
11	news.fcku.it	185.6 KB	0.0 KB	50.9 KB	236.5 KB	78%	2.2 KB
12	news.freedyn.net	172.9 KB	0.0 KB	437.6 KB	610.5 KB	28%	4.1 KB
13	news.swapon.de	124.7 KB	0.0 KB	0.0 KB	124.7 KB	100%	3.0 KB
14	localhost	18.2 KB	0.0 KB	0.0 KB	18.2 KB	100%	3.0 KB
15	news.bbs.nz	9.9 KB	0.0 KB	0.0 KB	9.9 KB	100%	4.9 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		50.5 MB	16.1 KB	32.4 MB	83.0 MB	60%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 13 04:15:00 - 04:59:59	254	1.6%	0.09	788.7 KB	1.5%	0.29
Oct 13 05:00:00 - 05:59:59	469	2.9%	0.13	1.5 MB	2.9%	0.42
Oct 13 06:00:00 - 06:59:59	484	3.0%	0.13	1.8 MB	3.6%	0.51
Oct 13 07:00:00 - 07:59:59	784	4.8%	0.22	3.0 MB	6.0%	0.86
Oct 13 08:00:00 - 08:59:59	687	4.2%	0.19	2.4 MB	4.7%	0.68
Oct 13 09:00:00 - 09:59:59	620	3.8%	0.17	1.9 MB	3.8%	0.54
Oct 13 10:00:00 - 10:59:59	748	4.6%	0.21	2.2 MB	4.4%	0.64
Oct 13 11:00:00 - 11:59:59	703	4.3%	0.20	2.2 MB	4.4%	0.64
Oct 13 12:00:00 - 12:59:59	771	4.8%	0.21	2.4 MB	4.7%	0.67
Oct 13 13:00:00 - 13:59:59	906	5.6%	0.25	3.7 MB	7.4%	1.06
Oct 13 14:00:00 - 14:59:59	735	4.5%	0.20	2.5 MB	4.9%	0.71
Oct 13 15:00:00 - 15:59:59	904	5.6%	0.25	2.6 MB	5.1%	0.74
Oct 13 16:00:00 - 16:59:59	979	6.0%	0.27	2.6 MB	5.2%	0.75
Oct 13 17:00:00 - 17:59:59	878	5.4%	0.24	2.3 MB	4.5%	0.65
Oct 13 18:00:00 - 18:59:59	945	5.8%	0.26	2.7 MB	5.3%	0.76
Oct 13 19:00:00 - 19:59:59	900	5.6%	0.25	2.7 MB	5.3%	0.76
Oct 13 20:00:00 - 20:59:59	840	5.2%	0.23	2.7 MB	5.4%	0.77
Oct 13 21:00:00 - 21:59:59	773	4.8%	0.21	2.1 MB	4.2%	0.61
Oct 13 22:00:00 - 22:59:59	563	3.5%	0.16	1.7 MB	3.3%	0.48
Oct 13 23:00:00 - 23:59:59	538	3.3%	0.15	1.5 MB	3.0%	0.43
Oct 14 00:00:00 - 00:59:59	491	3.0%	0.14	1.4 MB	2.7%	0.39
Oct 14 01:00:00 - 01:59:59	402	2.5%	0.11	1.1 MB	2.3%	0.33
Oct 14 02:00:00 - 02:59:59	376	2.3%	0.10	1.3 MB	2.5%	0.36
Oct 14 03:00:00 - 03:59:59	362	2.2%	0.10	1.1 MB	2.2%	0.31
Oct 14 04:00:00 - 04:15:00	99	0.6%	0.11	350.0 KB	0.7%	0.39
TOTAL: 24:00:00	16211	100.0%	0.19	50.5 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	1091	151	2	938
2	csiph-out.feeder.erje.net	796	98	0	698
3	news-out.mixmin.net	771	0	0	771
4	newsfeed.fu-berlin.de	639	19	0	620
5	feeder.eternal-september.org	537	16	0	521
6	csiph-out.news.weretis.net	265	56	0	209
7	news.freedyn.net	92	0	0	92
8	news.redatomik.org	50	0	0	50
9	nntp.pasdenom.info	43	37	1	5
10	news-out.aioe.org	33	24	0	9
11	newsfeeds.xmission.com	19	4	0	15
12	news.swapon.de	14	0	0	14
13	news.fcku.it	4	0	0	4
TOTAL: 13		4354	405	3	3946

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	117
local.spam.fr	36
it-alt.sport.calcio.juventus	19
lada.auto	19
gmane.comp.gis.openstreetmap.tagging	17
lada.commerce	15
gmane.linux.alsa.devel	15
alt.politics	14
alt.bbs.synchronet	13
alt.survival	12
oesterreich.tratsch	9
mozilla.support.thunderbird	5
spline.eisfair	5
it-alt.comp.software.mozilla	4
mozilla.dev.platform	4
alt.philosophy	4
biz.mlm	4
alt.2600.debate.winged-personifications	4
mozilla.support.calendar	3
mozilla.support.firefox	3
TOTAL: 88	405

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2346
EMP (phn path)	379
User-issued cancel	110
EMP (phl)	89
Too many newsgroups	69
EMP (phn nph)	33
HTML Multipart	19
Binary Payload	6
Scoring filter (8)	6
Scoring filter (10)	6
Scoring filter (12)	6
User-issued spam cancel	5
Scoring filter (11)	5
Binary Image: misplaced jpg	4
Binary: misplaced rar	4
Binary: misplaced par2	2
Binary: misplaced 122	1
Binary: misplaced 009	1
Binary: misplaced 002	1
TOTAL: 19	3092

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	187
	csiph-out.news.weretis.net	67
	feeder.eternal-september.org	64
	news.redatomik.org	13
	newsfeeds.xmission.com	12
	news-out.mixmin.net	6
	news.freedyn.net	3
	TOTAL: 7	352
Including strange strings
	newsfeed.fu-berlin.de	447
	csiph-out.feeder.erje.net	17
	news.swapon.de	14
	news.redatomik.org	7
	news-out.mixmin.net	6
	news-out.aioe.org	4
	newsfeeds.xmission.com	2
	newsfeed.neva.ru	2
	feeder.eternal-september.org	2
	csiph-out.news.weretis.net	1
	TOTAL: 10	502
TOTAL: 3		899

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	16823	661	15466	5	0	3%	23:56:18
2	xanadu-bbs	18353	591	15619	1	66	3%	23:59:54
3	fu-berlin.de	15037	560	14477	0	0	3%	23:50:03
4	erje	10670	140	9712	310	0	1%	23:50:03
5	news.bbs.nz	28507	117	15447	8	0	0%	23:50:03
6	usenet.pasdenom.info	17235	76	14842	1199	9	0%	23:59:54
7	weretis.net	12157	12	11893	3	0	0%	23:50:03
8	xmission	15986	10	14609	0	0	0%	23:59:54
9	news.mixmin.net	15180	10	14697	2	1584	0%	23:57:13
10	neva.ru	14680	9	14406	83	0	0%	23:50:31
11	news.redatomik.org	15833	8	15008	0	5	0%	23:52:38
12	eternal-september	14413	8	13374	0	92	0%	23:59:54
13	aioe	14271	7	13483	0	0	0%	23:50:03
14	news.swapon.de	1486	5	1469	0	0	0%	23:50:34
15	news.fcku.it	816	0	811	0	0	0%	21:26:10
TOTAL: 15		211447	2214	185313	1611	1756	1%	356:03:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.6 MB	46.0 KB	6.7 MB	0.1 KB/s	10.3 KB	23:56:18
2	xanadu-bbs	6.3 MB	23.0 KB	6.4 MB	0.1 KB/s	11.0 KB	23:59:54
3	fu-berlin.de	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	11.4 KB	23:50:03
4	erje	411.8 KB	1.1 MB	1.5 MB	0.0 KB/s	3.5 KB	23:50:03
5	usenet.pasdenom.info	334.0 KB	7.2 MB	7.6 MB	0.1 KB/s	6.1 KB	23:59:54
6	news.bbs.nz	312.9 KB	65.7 KB	378.5 KB	0.0 KB/s	3.0 KB	23:50:03
7	neva.ru	39.8 KB	281.7 KB	321.5 KB	0.0 KB/s	3.5 KB	23:50:31
8	weretis.net	34.8 KB	6.4 KB	41.2 KB	0.0 KB/s	2.7 KB	23:50:03
9	news.mixmin.net	32.7 KB	7.5 KB	40.1 KB	0.0 KB/s	3.3 KB	23:57:13
10	xmission	29.2 KB	0.0 KB	29.2 KB	0.0 KB/s	2.9 KB	23:59:54
11	eternal-september	24.1 KB	0.0 KB	24.1 KB	0.0 KB/s	3.0 KB	23:59:54
12	news.redatomik.org	21.2 KB	0.0 KB	21.2 KB	0.0 KB/s	2.7 KB	23:52:38
13	aioe	19.2 KB	0.0 KB	19.2 KB	0.0 KB/s	2.7 KB	23:50:03
14	news.swapon.de	16.6 KB	0.0 KB	16.6 KB	0.0 KB/s	3.3 KB	23:50:34
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:26:10
TOTAL: 15		20.4 MB	8.8 MB	29.2 MB	0.0 KB/s	7.8 KB	356:03:15

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-05-70-55-68-47.dsl.bell.ca	1066	7548969	19.4 GB	37871	0	0	845:02:11
2	167-179-154-5.a7b39a.mel.nbn.aussiebb.net	22	143	486.8 KB	39	0	0	00:25:44
3	ip72-222-200-117.ph.ph.cox.net	16	33	122.2 KB	7	1	1	06:46:48
4	a131033.upc-a.chello.nl	13	9	20.6 KB	3	0	0	01:35:25
5	24.178.46.151	21	7	17.5 KB	7	5	5	06:33:24
6	208-71-239-109.cust.centrio.cz	2	5	12.4 KB	4	0	0	00:05:07
TOTAL: 6		1140	7549166	19.4 GB	37931	6	6	860:59:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	1066	7548969	19.4 GB	37871	0	0	845:02:11
2	*.a7b39a.mel.nbn.aussiebb.net	22	143	486.8 KB	39	0	0	00:25:44
3	*.ph.ph.cox.net	16	33	122.2 KB	7	1	1	06:46:48
4	*.upc-a.chello.nl	13	9	20.6 KB	3	0	0	01:35:25
5	unresolved	21	7	17.5 KB	7	5	5	06:33:24
6	*.cust.centrio.cz	2	5	12.4 KB	4	0	0	00:05:07
7	*.nielsenmedia.com	1	0	0.0 KB	0	0	0	00:30:20
TOTAL: 7		1141	7549166	19.4 GB	37931	6	6	860:59:02

NNRP auth users [Top 20]:

User	Conn
ohlandl	5
ctomczyk	2
kev009	1
TOTAL: 3	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp130-05-70-55-68-47.dsl.bell.ca	1105.360	1849.673	0.000	845:02:11
ip72-222-200-117.ph.ph.cox.net	1.462	0.257	0.003	06:46:48
24.178.46.151	1.930	0.275	0.019	06:33:24
a131033.upc-a.chello.nl	1.273	0.173	0.000	01:35:25
challenger.nielsenmedia.com	0.051	0.014	0.000	00:30:20
167-179-154-5.a7b39a.mel.nbn.aussiebb.net	2.160	0.561	0.000	00:25:44
208-71-239-109.cust.centrio.cz	0.237	0.031	0.664	00:05:07
TOTAL: 7	1112.473	1850.984	0.686	860:59:02

Curious NNRP server explorers [Top 20]:

System	Conn
challenger.nielsenmedia.com	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
mtrlpq2314w-lp130-05-70-55-68-47.dsl.bell.ca	46	46
24.178.46.151	10	0
ip72-222-200-117.ph.ph.cox.net	9	0
challenger.nielsenmedia.com	1	0
TOTAL: 4	66	46

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	1460855	42.7%
2	fr	970776	28.3%
3	rec	631319	18.4%
4	sci	361325	10.6%
5	comp	99	0.0%
6	muc	25	0.0%
7	uk	6	0.0%
8	perl	6	0.0%
9	qc	3	0.0%
10	pl	2	0.0%
11	aus	2	0.0%
TOTAL: 11		3424418	100.0%

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

Newsgroup		Count
1	fr.soc.politique	970776
2	alt.home.repair	770969
3	rec.food.cooking	631314
4	alt.atheism	553290
5	sci.math	242792
6	alt.global-warming	136511
7	sci.physics.relativity	118532
8	alt.comp.freeware	75
9	muc.lists.freebsd.current	25
10	comp.sys.ibm.ps2.hardware	24
11	comp.arch	24
12	comp.ai.philosophy	12
13	comp.os.vms	12
14	comp.arch.embedded	8
15	uk.legal.moderated	6
16	rec.drugs.cannabis	5
17	alt.comp.os.windows-10	5
18	alt.os.linux.ubuntu	4
19	comp.os.linux.advocacy	3
20	qc.politique	3
21	perl.cpan.uploads	3
22	comp.lang.javascript	3
23	perl.perl5.porters	2
24	comp.sys.ibm.pc.soundcard.music	2
25	pl.comp.lang.javascript	2
26	comp.ai.neural-nets	2
27	aus.cars	2
28	perl.daily-build.reports	1
29	comp.ai.edu	1
30	comp.os.cpm	1
31	sci.physics	1
32	comp.mobile.android	1
33	comp.sys.ibm.as400.misc	1
34	alt.usenet.kooks	1
35	comp.ai.fuzzy	1
36	comp.ai.genetic	1
37	comp.ai.shells	1
38	comp.arch.fpga	1
39	comp.sys.ibm.pc.games.action	1
TOTAL: 39		3424418

Daily Usenet report for csiph.com

Oct 13 04:15:00 -- Oct 14 04:15:00