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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	30044	57.7%	6.4 MB	71.0%
innd	14026	27.0%	1.7 MB	18.8%
nnrpd	4656	8.9%	411.3 KB	4.5%
innfeed	3192	6.1%	512.0 KB	5.6%
nocem	121	0.2%	13.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	52040	100.0%	9.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	329659	54.9%
Negative hits	241359	40.2%
Do not exist	29181	4.9%
Cache misses	438	0.1%
TOTAL: 4	600637	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.768	0.0%	286	0.000	9.678	80.000
article cleanup	00:00:00.205	0.0%	29027	0.000	0.007	0.042
article logging	00:00:08.165	0.0%	29533	0.000	0.276	3.163
article parse	00:00:05.133	0.0%	73724	0.000	0.070	0.966
article write	00:04:48.006	0.3%	20258	0.250	14.217	83.074
artlog/artcncl	00:00:00.074	0.0%	218	0.000	0.339	3.250
artlog/artparse	00:00:00.097	0.0%	311	0.000	0.312	4.833
data move	00:00:00.377	0.0%	574610	0.000	0.001	0.053
hisgrep/artcncl	00:00:00.658	0.0%	286	0.000	2.301	21.733
hishave/artcncl	00:00:00.174	0.0%	286	0.000	0.608	4.867
hishave/artparse	00:00:00.001	0.0%	310	0.000	0.003	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:52.334	0.2%	603138	0.002	0.286	1.782
history sync	00:00:03.803	0.0%	362	0.000	10.506	68.667
history write	00:04:52.087	0.3%	29019	2.087	10.065	62.775
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:04.070	0.0%	310	0.000	13.129	137.000
idle	23:36:01.904	98.3%	552766	86.610	153.703	441.311
nntp read	00:00:02.654	0.0%	586004	0.002	0.005	0.020
overview write	00:09:30.305	0.7%	20258	1.082	28.152	927.806
perl filter	00:01:01.726	0.1%	29018	1.484	2.127	20.523
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.394	0.0%	40629	0.000	0.010	0.035
TOTAL: 24:01:06.009	23:59:34.935	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.078	0.0%	20259	0.000	0.004	0.038
article prepare	00:00:00.001	0.0%	17424	0.000	0.000	0.012
article read	00:00:01.484	0.0%	2315	0.000	0.641	27.167
backlog stats	00:00:00.216	0.0%	374254	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.013	0.0%	290073	0.003	0.007	0.013
data write	00:00:01.668	0.0%	273111	0.001	0.006	0.011
idle	23:54:26.941	100.0%	374254	109.992	229.969	699.683
status file	00:00:00.584	0.0%	285	0.000	2.049	157.000
TOTAL: 23:54:41.390	23:54:32.985	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:06.598	0.0%	3607	1.504	1.829	2.161
idle	92:36:14.019	100.0%	8157	319.975	40869.685	600477.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.112	0.0%	16614	0.000	0.007	0.167
readart	00:00:00.328	0.0%	97	0.000	3.381	48.000
TOTAL: 92:37:08.914	92:36:21.057	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	92507	5205	83761	3541	5%	95:02:13
2	csiph-out.news.weretis.net	5	42288	4234	37449	605	10%	48:44:36
3	newsfeeds.xmission.com	2	19745	2542	17190	13	12%	24:38:53
4	feeder.eternal-september.org	2	24750	2534	20816	1400	10%	23:59:22
5	news-out.aioe.org	3	20171	1523	18629	19	7%	24:08:57
6	newsfeed.neva.ru	141	26089	1281	22554	2254	4%	71:38:31
7	newsfeed.fu-berlin.de	12	19867	1099	18512	256	5%	24:12:15
8	news.redatomik.org	1	16682	628	16052	2	3%	21:25:57
9	news.fcku.it	2	11347	587	10390	370	5%	23:12:45
10	news-out.mixmin.net	1	21631	446	20649	536	2%	23:59:46
11	nntp.pasdenom.info	2	19756	27	19728	1	0%	23:58:38
12	news.freedyn.net	2	22643	15	22603	25	0%	23:59:47
13	localhost	38	3	3	0	0	100%	00:00:00
14	news.swapon.de	2	109	2	107	0	1%	04:09:07
15	xanadu-bbs.net	15	810	1	809	0	0%	23:43:30
16	news.bbs.geek.nz	1	19733	1	19732	0	0%	23:59:49
TOTAL: 16		1381	358131	20128	328981	9022	5%	480:54:06

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	18.8 MB	17.2 KB	17.1 MB	35.9 MB	52%	4.2 KB
2	csiph-out.news.weretis.net	12.3 MB	0.0 KB	9.5 MB	21.8 MB	56%	4.6 KB
3	newsfeeds.xmission.com	7.1 MB	0.0 KB	633.7 KB	7.8 MB	92%	3.1 KB
4	feeder.eternal-september.org	6.6 MB	0.0 KB	6.0 MB	12.6 MB	52%	3.3 KB
5	newsfeed.neva.ru	3.1 MB	3.4 KB	9.6 MB	12.6 MB	24%	3.7 KB
6	news-out.aioe.org	2.8 MB	0.0 KB	35.4 KB	2.9 MB	98%	1.9 KB
7	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	712.7 KB	3.1 MB	77%	2.3 KB
8	news.redatomik.org	1.6 MB	0.0 KB	5.4 KB	1.6 MB	99%	2.5 KB
9	news-out.mixmin.net	1.5 MB	0.0 KB	1.1 MB	2.5 MB	57%	2.7 KB
10	news.fcku.it	1.3 MB	0.0 KB	2.4 MB	3.7 MB	35%	4.0 KB
11	news.freedyn.net	67.3 KB	0.0 KB	64.2 KB	131.5 KB	51%	3.3 KB
12	nntp.pasdenom.info	47.3 KB	0.0 KB	0.8 KB	48.1 KB	98%	1.7 KB
13	localhost	36.4 KB	0.0 KB	0.0 KB	36.4 KB	100%	12.1 KB
14	news.swapon.de	8.2 KB	0.0 KB	0.0 KB	8.2 KB	100%	4.1 KB
15	xanadu-bbs.net	3.6 KB	0.0 KB	0.0 KB	3.6 KB	100%	3.6 KB
16	news.bbs.geek.nz	1.2 KB	0.0 KB	0.0 KB	1.2 KB	100%	1.2 KB
TOTAL: 16		57.7 MB	20.6 KB	47.1 MB	104.8 MB	55%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 11 04:15:00 - 04:59:59	346	1.7%	0.13	1.4 MB	2.5%	0.54
Oct 11 05:00:00 - 05:59:59	445	2.2%	0.12	1.6 MB	2.9%	0.47
Oct 11 06:00:00 - 06:59:59	509	2.5%	0.14	1.5 MB	2.6%	0.43
Oct 11 07:00:00 - 07:59:59	896	4.4%	0.25	2.3 MB	4.0%	0.66
Oct 11 08:00:00 - 08:59:59	861	4.3%	0.24	2.2 MB	3.8%	0.63
Oct 11 09:00:00 - 09:59:59	992	4.9%	0.28	2.9 MB	4.9%	0.81
Oct 11 10:00:00 - 10:59:59	923	4.6%	0.26	2.5 MB	4.3%	0.70
Oct 11 11:00:00 - 11:59:59	830	4.1%	0.23	1.9 MB	3.3%	0.55
Oct 11 12:00:00 - 12:59:59	974	4.8%	0.27	2.6 MB	4.4%	0.73
Oct 11 13:00:00 - 13:59:59	998	4.9%	0.28	2.6 MB	4.4%	0.73
Oct 11 14:00:00 - 14:59:59	986	4.9%	0.27	2.7 MB	4.7%	0.77
Oct 11 15:00:00 - 15:59:59	1218	6.0%	0.34	3.5 MB	6.1%	1.01
Oct 11 16:00:00 - 16:59:59	1348	6.7%	0.37	4.5 MB	7.7%	1.27
Oct 11 17:00:00 - 17:59:59	1082	5.3%	0.30	2.8 MB	4.9%	0.80
Oct 11 18:00:00 - 18:59:59	1164	5.7%	0.32	4.4 MB	7.6%	1.24
Oct 11 19:00:00 - 19:59:59	1076	5.3%	0.30	2.9 MB	5.0%	0.82
Oct 11 20:00:00 - 20:59:59	1024	5.1%	0.28	2.7 MB	4.7%	0.77
Oct 11 21:00:00 - 21:59:59	929	4.6%	0.26	2.2 MB	3.9%	0.63
Oct 11 22:00:00 - 22:59:59	940	4.6%	0.26	2.9 MB	5.1%	0.83
Oct 11 23:00:00 - 23:59:59	674	3.3%	0.19	1.9 MB	3.4%	0.55
Oct 12 00:00:00 - 00:59:59	561	2.8%	0.16	1.5 MB	2.6%	0.43
Oct 12 01:00:00 - 01:59:59	473	2.3%	0.13	1.3 MB	2.2%	0.36
Oct 12 02:00:00 - 02:59:59	430	2.1%	0.12	1.3 MB	2.2%	0.37
Oct 12 03:00:00 - 03:59:59	446	2.2%	0.12	1.2 MB	2.1%	0.34
Oct 12 04:00:00 - 04:15:00	123	0.6%	0.14	432.0 KB	0.7%	0.48
TOTAL: 24:00:00	20248	100.0%	0.23	57.8 MB	100.0%	0.69

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	3578	325	8	1	3244
2	newsfeed.neva.ru	2247	386	1	0	1860
3	feeder.eternal-september.org	1405	60	0	0	1345
4	csiph-out.news.weretis.net	651	297	0	0	354
5	newsfeed.fu-berlin.de	643	32	0	0	611
6	news-out.mixmin.net	554	3	0	0	551
7	news.fcku.it	379	3	0	1	375
8	news.redatomik.org	45	0	0	0	45
9	news.freedyn.net	25	1	0	0	24
10	news-out.aioe.org	24	19	0	0	5
11	news.swapon.de	15	0	0	0	15
12	newsfeeds.xmission.com	13	4	0	0	9
13	nntp.pasdenom.info	1	0	0	0	1
TOTAL: 13		9580	1130	9	2	8439

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	202
lada.auto	136
alt.survival	100
ut.test	69
mpc.lists.freebsd.ports.bugs	40
it.annunci.commerciali	37
it.annunci.immobiliari	35
it.annunci.varie	33
mpc.lists.nanog	33
gmane.linux.alsa.devel	32
alt.computer.workshop	27
abg.test	27
mozilla.support.thunderbird	25
it-alt.sport.calcio.juventus	23
gmane.linux.usb.general	20
lada.commerce	19
free.it.religioni.scientology	14
cakewalk.audio	14
mozilla.general	12
alt.politics	11
TOTAL: 115	1130

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

Groups	Count
alt.culture.china	1
nz.politics.announce	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6711
EMP (phn path)	403
EMP (phn nph)	231
HTML Multipart	46
Too many newsgroups	41
Scoring filter (10)	35
Scoring filter (12)	25
User-issued cancel	18
Binary: misplaced par2	17
Scoring filter (8)	16
Too many newsgroups (low_xpost)	14
Scoring filter (13)	13
Binary Payload	12
Scoring filter (9)	9
HTML post	6
Scoring filter (16)	4
Binary: misplaced sfv	3
Binary: misplaced 053	2
Binary Image: misplaced png	2
Too many newsgroups (meow)	1
TOTAL: 32	7621

NoCeM on Spool:

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

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.feeder.erje.net	156
	csiph-out.news.weretis.net	110
	feeder.eternal-september.org	21
	news.fcku.it	13
	newsfeeds.xmission.com	8
	newsfeed.fu-berlin.de	2
	news-out.mixmin.net	2
	news.redatomik.org	1
	TOTAL: 8	313
Including strange strings
	newsfeed.fu-berlin.de	394
	csiph-out.news.weretis.net	37
	csiph-out.feeder.erje.net	35
	news.swapon.de	15
	news.redatomik.org	13
	news-out.mixmin.net	4
	news-out.aioe.org	4
	feeder.eternal-september.org	2
	TOTAL: 8	504
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		822

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	21485	798	19364	4	0	3%	23:50:10
2	news.bbs.geek.nz	35906	689	18963	51	0	1%	23:57:39
3	xanadu-bbs	23370	592	19655	0	0	2%	23:59:50
4	fu-berlin.de	18847	500	18347	0	0	2%	23:50:12
5	usenet.pasdenom.info	20778	104	18784	1333	8	0%	23:59:55
6	aioe	19193	73	17481	1	0	0%	23:50:58
7	erje	15372	46	14846	5	17	0%	23:55:26
8	news.redatomik.org	20617	28	18940	3	24	0%	23:57:15
9	eternal-september	18039	16	16957	0	0	0%	23:57:40
10	neva.ru	18230	14	17988	75	0	0%	23:50:51
11	weretis.net	15875	10	15126	0	0	0%	23:50:12
12	xmission	18076	8	17105	0	0	0%	23:58:42
13	news.swapon.de	1909	6	1871	0	0	0%	23:49:49
14	news.mixmin.net	19342	4	18777	0	0	0%	23:53:19
15	news.fcku.it	1924	0	1913	0	0	0%	23:10:44
16	bwh	0	0	0	0	19658	0%	23:50:11
17	au2pb	0	0	0	0	19665	0%	23:50:11
TOTAL: 17		268963	2888	236117	1472	39372	1%	405:33:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.5 MB	21.3 KB	7.5 MB	0.1 KB/s	9.5 KB	23:50:10
2	xanadu-bbs	7.0 MB	0.0 KB	7.0 MB	0.1 KB/s	12.0 KB	23:59:50
3	fu-berlin.de	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	13.6 KB	23:50:12
4	news.bbs.geek.nz	1.8 MB	153.9 KB	1.9 MB	0.0 KB/s	2.7 KB	23:57:39
5	usenet.pasdenom.info	361.6 KB	8.0 MB	8.4 MB	0.1 KB/s	6.0 KB	23:59:55
6	aioe	196.4 KB	3.5 KB	199.9 KB	0.0 KB/s	2.7 KB	23:50:58
7	erje	170.2 KB	17.2 KB	187.4 KB	0.0 KB/s	3.7 KB	23:55:26
8	news.redatomik.org	96.4 KB	7.8 KB	104.2 KB	0.0 KB/s	3.4 KB	23:57:15
9	eternal-september	67.8 KB	0.0 KB	67.8 KB	0.0 KB/s	4.2 KB	23:57:40
10	weretis.net	49.7 KB	0.0 KB	49.7 KB	0.0 KB/s	5.0 KB	23:50:12
11	xmission	44.4 KB	0.0 KB	44.4 KB	0.0 KB/s	5.6 KB	23:58:42
12	news.swapon.de	42.5 KB	0.0 KB	42.5 KB	0.0 KB/s	7.1 KB	23:49:49
13	news.mixmin.net	38.6 KB	0.0 KB	38.6 KB	0.0 KB/s	9.7 KB	23:53:19
14	neva.ru	21.8 KB	215.9 KB	237.6 KB	0.0 KB/s	2.7 KB	23:50:51
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:10:44
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
TOTAL: 17		23.9 MB	8.5 MB	32.4 MB	0.0 KB/s	7.6 KB	405:33:04

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	34	88	245.3 KB	58	0	0	00:26:49
2	a131033.upc-a.chello.nl	78	3	36.4 KB	2	0	0	14:50:56
3	tempe0.bbox.io	19	3	36.4 KB	3	0	0	05:58:45
4	96-42-18-93.dhcp.mdsn.wi.charter.com	15	3	36.4 KB	3	3	2	03:25:16
TOTAL: 4		146	97	354.5 KB	66	3	2	92:37:07

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	38	88	245.3 KB	58	0	0	64:20:20
2	*.upc-a.chello.nl	78	3	36.4 KB	2	0	0	14:50:56
3	*.bbox.io	19	3	36.4 KB	3	0	0	05:58:45
4	*.dhcp.mdsn.wi.charter.com	15	3	36.4 KB	3	3	2	03:25:16
5	*.nwrknj.fios.verizon.net	1	0	0.0 KB	0	0	0	01:01:09
6	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	01:00:02
7	*.cust.centrio.cz	4	0	0.0 KB	0	0	0	02:00:35
TOTAL: 7		156	97	354.5 KB	66	3	2	92:37:07

NNRP auth users [Top 20]:

User	Conn
moussa	34
ctomczyk	4
ohlandl	2
TOTAL: 3	40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.182	0.478	0.492	0.000	63:53:31
a131033.upc-a.chello.nl	6.002	1.214	0.000	14:50:56
tempe0.bbox.io	1.521	0.290	0.000	05:58:45
96-42-18-93.dhcp.mdsn.wi.charter.com	1.211	0.230	0.008	03:25:16
194-73-239-109.cust.centrio.cz	0.276	0.063	0.016	02:00:35
pool-74-102-9-20.nwrknj.fios.verizon.net	0.121	0.044	0.000	01:01:09
c-67-160-62-214.hsd1.wa.comcast.net	0.129	0.014	0.000	01:00:02
141.168.117.108	4.131	0.887	0.128	00:26:49
TOTAL: 8	13.869	3.234	0.152	92:37:07

Curious NNRP server explorers [Top 20]:

System	Conn
194-73-239-109.cust.centrio.cz	4
216.239.174.182	4
c-67-160-62-214.hsd1.wa.comcast.net	1
pool-74-102-9-20.nwrknj.fios.verizon.net	1
TOTAL: 4	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	9	0
194-73-239-109.cust.centrio.cz	4	0
96-42-18-93.dhcp.mdsn.wi.charter.com	3	0
pool-74-102-9-20.nwrknj.fios.verizon.net	2	0
c-67-160-62-214.hsd1.wa.comcast.net	2	0
TOTAL: 5	20	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	97	100.0%
TOTAL: 1		97	100.0%

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

Newsgroup		Count
1	comp.os.vms	40
2	comp.arch	16
3	comp.sys.ibm.ps2.hardware	12
4	comp.arch.embedded	10
5	comp.sys.ibm.pc.games.action	5
6	comp.ai.neural-nets	3
7	comp.arch.fpga	2
8	comp.os.os2.misc	2
9	comp.os.cpm	2
10	comp.sys.ibm.as400.misc	2
11	comp.os.os2.apps	1
12	comp.os.qnx	1
13	comp.ai.philosophy	1
TOTAL: 13		97

Daily Usenet report for csiph.com

Oct 11 04:15:00 -- Oct 12 04:15:00