Daily Usenet report for csiph.com: Sep 6 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	46260	49.5%	4.7 MB	35.6%
inn	29882	32.0%	6.4 MB	48.0%
innd	14024	15.0%	1.7 MB	12.6%
innfeed	3211	3.4%	522.9 KB	3.8%
nocem	68	0.1%	6.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	93446	100.0%	13.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	318785	55.2%
Negative hits	226382	39.2%
Do not exist	29126	5.0%
Cache misses	2908	0.5%
TOTAL: 4	577201	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.224	0.0%	208	0.000	10.692	125.000
article cleanup	00:00:00.216	0.0%	28722	0.000	0.008	0.029
article logging	00:00:06.175	0.0%	29288	0.004	0.211	2.161
article parse	00:00:06.198	0.0%	80184	0.000	0.077	1.608
article write	00:04:11.711	0.3%	20508	0.222	12.274	53.641
artlog/artcncl	00:00:00.055	0.0%	147	0.000	0.374	7.500
artlog/artparse	00:00:00.103	0.0%	483	0.000	0.213	21.500
data move	00:00:00.510	0.0%	543175	0.000	0.001	0.078
hisgrep/artcncl	00:00:00.682	0.0%	205	0.000	3.327	94.000
hishave/artcncl	00:00:00.315	0.0%	208	0.000	1.514	11.273
hishave/artparse	00:00:00.004	0.0%	483	0.000	0.008	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:10.984	0.2%	577985	0.003	0.330	1.659
history sync	00:00:04.510	0.0%	361	0.000	12.493	78.333
history write	00:04:29.682	0.3%	28704	0.385	9.395	62.923
hiswrite/artcncl	00:00:00.055	0.0%	3	13.000	18.333	28.000
hiswrite/artparse	00:00:04.941	0.0%	482	0.000	10.251	105.200
idle	23:36:25.588	98.3%	522508	93.033	162.649	365.326
nntp read	00:00:02.926	0.0%	553026	0.001	0.005	0.058
overview write	00:09:40.085	0.7%	20508	1.192	28.286	1123.730
perl filter	00:01:03.831	0.1%	28703	1.568	2.224	16.394
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.355	0.0%	41078	0.000	0.009	0.027
TOTAL: 24:01:02.416	23:59:31.150	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.086	0.0%	25844	0.000	0.003	0.018
article prepare	00:00:00.003	0.0%	34188	0.000	0.000	0.004
article read	00:00:03.009	0.0%	6313	0.000	0.477	27.100
backlog stats	00:00:00.218	0.0%	353091	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.942	0.0%	279065	0.001	0.007	0.013
data write	00:00:01.635	0.0%	265445	0.002	0.006	0.011
idle	23:53:59.055	100.0%	353091	132.904	243.674	591.237
status file	00:00:00.532	0.0%	320	0.000	1.663	26.000
TOTAL: 23:54:15.323	23:54:06.480	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	28:19:46.573	99.8%	212261	82.836	480.477	600110.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.463	0.0%	424907	0.000	0.006	0.167
readart	00:01:35.589	0.1%	3508	0.000	27.249	224.000
TOTAL: 28:23:22.966	28:21:24.625	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	42006	5566	35857	583	13%	48:08:53
2	csiph-out.feeder.erje.net	1152	87391	4594	79379	3418	5%	94:58:26
3	news-out.mixmin.net	2	21627	3190	17660	777	14%	24:08:39
4	news-out.aioe.org	2	19616	2309	17271	36	11%	24:09:55
5	newsfeed.neva.ru	145	27339	1234	23435	2670	4%	72:07:15
6	newsfeed.fu-berlin.de	43	18869	1080	17553	236	5%	28:13:49
7	news.redatomik.org	1	22235	978	21251	6	4%	27:29:54
8	feeder.eternal-september.org	9	25038	812	23317	909	3%	18:21:31
9	newsfeeds.xmission.com	3	18327	454	17862	11	2%	22:10:52
10	news.fcku.it	1	9138	189	8841	108	2%	23:59:56
11	news.freedyn.net	2	22327	65	22240	22	0%	23:59:43
12	news.swapon.de	1	1885	34	1849	2	1%	23:24:34
13	nntp.pasdenom.info	2	19176	32	19142	2	0%	23:58:42
14	xanadu-bbs.net	5	291	8	283	0	2%	09:40:53
15	news.bbs.geek.nz	2	15914	8	15905	1	0%	23:59:33
16	localhost	35	0	0	0	0	0%	00:00:01
TOTAL: 16		1410	351179	20553	321845	8781	5%	488:52:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.2 MB	9.2 KB	19.5 MB	38.7 MB	49%	5.0 KB
2	csiph-out.news.weretis.net	16.8 MB	4.7 KB	20.3 MB	37.1 MB	45%	6.2 KB
3	news-out.mixmin.net	10.2 MB	2.7 KB	2.0 MB	12.1 MB	83%	3.1 KB
4	news-out.aioe.org	5.9 MB	5.4 KB	79.8 KB	6.0 MB	98%	2.6 KB
5	newsfeed.neva.ru	3.4 MB	0.0 KB	12.3 MB	15.7 MB	21%	4.1 KB
6	news.redatomik.org	3.1 MB	0.0 KB	29.8 KB	3.1 MB	99%	3.2 KB
7	newsfeed.fu-berlin.de	2.7 MB	12.8 KB	549.6 KB	3.2 MB	82%	2.5 KB
8	feeder.eternal-september.org	2.5 MB	8.3 KB	7.8 MB	10.3 MB	24%	6.1 KB
9	newsfeeds.xmission.com	1.4 MB	0.0 KB	943.8 KB	2.4 MB	60%	5.2 KB
10	news.fcku.it	402.0 KB	0.0 KB	560.7 KB	962.6 KB	41%	3.2 KB
11	news.freedyn.net	238.9 KB	4.8 KB	280.8 KB	524.5 KB	45%	6.0 KB
12	news.swapon.de	79.8 KB	4.8 KB	637.5 KB	722.1 KB	11%	20.1 KB
13	nntp.pasdenom.info	61.6 KB	0.0 KB	2.7 KB	64.3 KB	95%	1.9 KB
14	news.bbs.geek.nz	20.0 KB	1.2 KB	1.2 KB	22.3 KB	89%	2.5 KB
15	xanadu-bbs.net	16.3 KB	0.0 KB	0.0 KB	16.3 KB	100%	2.0 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		65.9 MB	53.7 KB	64.9 MB	130.8 MB	50%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 6 04:15:00 - 04:59:59	392	1.9%	0.15	1.4 MB	2.1%	0.53
Sep 6 05:00:00 - 05:59:59	560	2.7%	0.16	2.5 MB	3.9%	0.72
Sep 6 06:00:00 - 06:59:59	524	2.6%	0.15	1.9 MB	2.8%	0.53
Sep 6 07:00:00 - 07:59:59	746	3.6%	0.21	2.1 MB	3.2%	0.59
Sep 6 08:00:00 - 08:59:59	944	4.6%	0.26	2.6 MB	4.0%	0.75
Sep 6 09:00:00 - 09:59:59	870	4.2%	0.24	2.3 MB	3.6%	0.67
Sep 6 10:00:00 - 10:59:59	759	3.7%	0.21	2.2 MB	3.4%	0.62
Sep 6 11:00:00 - 11:59:59	829	4.0%	0.23	2.5 MB	3.9%	0.71
Sep 6 12:00:00 - 12:59:59	1094	5.3%	0.30	2.9 MB	4.4%	0.82
Sep 6 13:00:00 - 13:59:59	1022	5.0%	0.28	3.4 MB	5.2%	0.97
Sep 6 14:00:00 - 14:59:59	1081	5.3%	0.30	3.4 MB	5.3%	0.97
Sep 6 15:00:00 - 15:59:59	1224	6.0%	0.34	4.2 MB	6.5%	1.21
Sep 6 16:00:00 - 16:59:59	1244	6.1%	0.35	4.1 MB	6.4%	1.18
Sep 6 17:00:00 - 17:59:59	1020	5.0%	0.28	3.2 MB	4.9%	0.90
Sep 6 18:00:00 - 18:59:59	1250	6.1%	0.35	4.7 MB	7.2%	1.34
Sep 6 19:00:00 - 19:59:59	1152	5.6%	0.32	3.2 MB	4.9%	0.90
Sep 6 20:00:00 - 20:59:59	1012	4.9%	0.28	2.7 MB	4.2%	0.78
Sep 6 21:00:00 - 21:59:59	1018	5.0%	0.28	3.1 MB	4.7%	0.88
Sep 6 22:00:00 - 22:59:59	825	4.0%	0.23	2.8 MB	4.3%	0.80
Sep 6 23:00:00 - 23:59:59	693	3.4%	0.19	2.2 MB	3.4%	0.64
Sep 7 00:00:00 - 00:59:59	540	2.6%	0.15	1.8 MB	2.8%	0.52
Sep 7 01:00:00 - 01:59:59	545	2.7%	0.15	1.8 MB	2.7%	0.50
Sep 7 02:00:00 - 02:59:59	453	2.2%	0.13	1.9 MB	2.9%	0.53
Sep 7 03:00:00 - 03:59:59	530	2.6%	0.15	1.5 MB	2.3%	0.43
Sep 7 04:00:00 - 04:15:00	174	0.8%	0.19	603.1 KB	0.9%	0.67
TOTAL: 24:00:00	20501	100.0%	0.24	65.0 MB	100.0%	0.77

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	3468	345	2	7	3114
2	newsfeed.neva.ru	2628	251	0	0	2377
3	feeder.eternal-september.org	862	17	6	0	839
4	news-out.mixmin.net	846	1	2	0	843
5	newsfeed.fu-berlin.de	613	28	2	0	583
6	csiph-out.news.weretis.net	596	171	2	0	423
7	news.fcku.it	108	0	0	0	108
8	news-out.aioe.org	50	32	1	0	17
9	news.freedyn.net	22	0	1	0	21
10	newsfeeds.xmission.com	17	0	0	0	17
11	news.redatomik.org	16	0	1	0	15
12	news.swapon.de	11	0	1	0	10
13	nntp.pasdenom.info	2	1	0	0	1
TOTAL: 13		9239	846	18	7	8368

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	129
ut.test	55
lada.auto	40
mpc.lists.freebsd.ports.bugs	39
it.annunci.varie	38
it.annunci.commerciali	38
it.annunci.immobiliari	38
mozilla.dev.platform	33
gmane.linux.alsa.devel	32
it-alt.sport.calcio.juventus	27
spline.eisfair	24
lada.commerce	22
gmane.comp.gis.openstreetmap	15
alt.survival	15
gmane.comp.gis.openstreetmap.tagging	13
mpc.lists.freebsd.x11	13
oesterreich.tratsch	13
alt.windows7.general	12
alt.politics	12
mozilla.support.thunderbird	12
TOTAL: 114	846

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

Groups	Count
nz.politics.announce	6
uk.legal.moderated	1
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6467
EMP (phn path)	406
EMP (phn nph)	122
Too many newsgroups	88
Scoring filter (10)	63
Binary Payload	36
Scoring filter (8)	23
EMP (phl)	15
Scoring filter (9)	14
Too many newsgroups (low_xpost)	14
Scoring filter (12)	13
HTML Multipart	10
Scoring filter (11)	8
Scoring filter (13)	7
HTML post	7
User-issued cancel	5
Binary: misplaced 018	3
Scoring filter (16)	2
HTML Image Tags	2
Binary: misplaced 023	2
TOTAL: 29	7317

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	csiph-out.news.weretis.net	222
	csiph-out.feeder.erje.net	175
	feeder.eternal-september.org	51
	newsfeeds.xmission.com	17
	news-out.mixmin.net	10
	news.fcku.it	3
	news.freedyn.net	3
	newsfeed.fu-berlin.de	1
	news.swapon.de	1
	TOTAL: 9	483
Including strange strings
	newsfeed.fu-berlin.de	375
	news-out.mixmin.net	81
	csiph-out.feeder.erje.net	64
	news-out.aioe.org	14
	news.redatomik.org	11
	news.swapon.de	9
	csiph-out.news.weretis.net	9
	news.fcku.it	2
	newsfeed.neva.ru	1
	feeder.eternal-september.org	1
	TOTAL: 10	567
TOTAL: 3		1054

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	33283	4627	15182	41	0	13%	23:52:04
2	news.freedyn.net	21461	784	19541	12	0	3%	23:50:08
3	xanadu-bbs	21899	735	19735	0	48	3%	23:59:54
4	fu-berlin.de	19058	545	18509	0	0	2%	23:50:08
5	usenet.pasdenom.info	20931	93	18869	1506	8	0%	23:59:53
6	aioe	16854	49	16319	1	0	0%	23:50:08
7	erje	16093	15	15716	4	0	0%	23:54:07
8	xmission	20871	11	19424	0	0	0%	23:59:54
9	news.redatomik.org	19511	8	19017	3	0	0%	23:56:31
10	weretis.net	13551	8	13159	4	0	0%	23:50:08
11	neva.ru	18695	2	18407	92	0	0%	23:59:54
12	eternal-september	19269	2	18934	1	5341	0%	23:59:54
13	news.mixmin.net	16724	1	16475	0	0	0%	23:53:36
14	news.swapon.de	1908	1	1882	0	0	0%	23:49:25
15	au2pb	0	0	0	0	19852	0%	23:50:07
16	bwh	0	0	0	0	19834	0%	23:50:07
17	news.fcku.it	2306	0	2260	0	0	0%	22:41:57
TOTAL: 17		262414	6881	233429	1664	45083	2%	405:07:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	12.9 MB	181.0 KB	13.1 MB	0.2 KB/s	2.9 KB	23:52:04
2	news.freedyn.net	8.0 MB	32.9 KB	8.1 MB	0.1 KB/s	10.4 KB	23:50:08
3	xanadu-bbs	7.9 MB	0.0 KB	7.9 MB	0.1 KB/s	10.9 KB	23:59:54
4	fu-berlin.de	7.4 MB	0.0 KB	7.4 MB	0.1 KB/s	13.8 KB	23:50:08
5	usenet.pasdenom.info	296.6 KB	9.3 MB	9.5 MB	0.1 KB/s	6.1 KB	23:59:53
6	aioe	132.5 KB	4.4 KB	136.9 KB	0.0 KB/s	2.7 KB	23:50:08
7	erje	32.7 KB	5.4 KB	38.1 KB	0.0 KB/s	2.0 KB	23:54:07
8	xmission	26.1 KB	0.0 KB	26.1 KB	0.0 KB/s	2.4 KB	23:59:54
9	news.redatomik.org	23.9 KB	22.6 KB	46.6 KB	0.0 KB/s	4.2 KB	23:56:31
10	eternal-september	16.7 KB	4.8 KB	21.4 KB	0.0 KB/s	7.1 KB	23:59:54
11	weretis.net	13.3 KB	28.2 KB	41.5 KB	0.0 KB/s	3.5 KB	23:50:08
12	neva.ru	12.3 KB	201.4 KB	213.7 KB	0.0 KB/s	2.3 KB	23:59:54
13	news.mixmin.net	1.4 KB	0.0 KB	1.4 KB	0.0 KB/s	1.4 KB	23:53:36
14	news.swapon.de	1.1 KB	0.0 KB	1.1 KB	0.0 KB/s	1.1 KB	23:49:25
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:41:57
TOTAL: 17		36.7 MB	9.7 MB	46.4 MB	0.0 KB/s	5.6 KB	405:07:55

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	144.138.90.254	47	33	133.9 KB	28	00:34:52
2	mtrlpq2314w-lp130-01-70-49-69-223.dsl.bell.ca	250	33	96.6 KB	11	02:08:01
3	bas2-montrealak-70-49-69-223.dsl.bell.ca	652	28	79.0 KB	20	08:43:55
TOTAL: 3		949	94	309.5 KB	59	28:23:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.dsl.bell.ca	902	61	175.6 KB	31	10:51:56
2	unresolved	47	33	133.9 KB	28	00:34:52
3	*.dhcp.ftbg.wi.charter.com	5	0	0.0 KB	0	01:35:28
4	*.upc-a.chello.nl	52	0	0.0 KB	0	08:33:17
5	*.bbox.io	10	0	0.0 KB	0	05:04:10
6	*.nielsenmedia.com	3	0	0.0 KB	0	01:30:10
7	*.19-1.cable.virginm.net	1	0	0.0 KB	0	00:07:56
8	*.cust.centrio.cz	2	0	0.0 KB	0	00:05:19
TOTAL: 8		1022	94	309.5 KB	59	28:23:11

NNRP auth users [Top 20]:

User	Conn
moussa	47
ctomczyk	2
TOTAL: 2	49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
bas2-montrealak-70-49-69-223.dsl.bell.ca	55.870	12.504	0.000	08:43:55
a131033.upc-a.chello.nl	3.724	0.816	0.000	08:33:17
tempe0.bbox.io	0.880	0.126	0.000	05:04:10
mtrlpq2314w-lp130-01-70-49-69-223.dsl.bell.ca	23.230	4.541	0.000	02:08:01
71-90-73-201.dhcp.ftbg.wi.charter.com	0.357	0.080	0.000	01:35:28
challenger.nielsenmedia.com	0.238	0.025	0.000	01:30:10
144.138.90.254	4.478	0.985	0.185	00:34:52
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	0.048	0.014	0.000	00:07:56
194-73-239-109.cust.centrio.cz	0.164	0.039	0.007	00:05:19
TOTAL: 9	88.989	19.130	0.192	28:23:11

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	52
tempe0.bbox.io	10
71-90-73-201.dhcp.ftbg.wi.charter.com	5
challenger.nielsenmedia.com	3
194-73-239-109.cust.centrio.cz	2
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	1
TOTAL: 6	73

NNRP client timeouts [Top 20]:

System	Conn	Peer
bas2-montrealak-70-49-69-223.dsl.bell.ca	50	50
tempe0.bbox.io	10	0
challenger.nielsenmedia.com	3	0
71-90-73-201.dhcp.ftbg.wi.charter.com	2	0
TOTAL: 4	65	50

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	41	43.6%
2	alt	21	22.3%
3	linux	12	12.8%
4	uk	8	8.5%
5	aus	7	7.4%
6	rec	4	4.3%
7	sci	1	1.1%
TOTAL: 7		94	100.0%

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

Newsgroup		Count
1	linux.kernel	12
2	alt.usage.english	11
3	comp.arch	11
4	comp.arch.embedded	8
5	comp.os.vms	8
6	aus.cars	7
7	alt.comp.freeware	6
8	comp.lang.python	6
9	uk.legal	4
10	rec.photo.digital	3
11	comp.arch.fpga	2
12	uk.d-i-y	2
13	alt.sports.football.pro.sd-chargers	2
14	comp.os.os2.apps	2
15	comp.os.os2.announce	2
16	comp.misc	1
17	uk.net.news.moderation	1
18	alt.home.repair	1
19	rec.arts.tv	1
20	sci.electronics.design	1
21	alt.russian.z1	1
22	comp.lang.fortran	1
23	uk.rec.sheds	1
TOTAL: 23		94

Daily Usenet report for csiph.com

Sep 6 04:15:00 -- Sep 7 04:15:00