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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Mar 10 12:30:04 csiph controlchan[26125]: Multiple control headers in article <tjrQf-3VY-47@gated-at.bofh.it>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	51205	58.9%	9.3 MB	70.2%
innd	31413	36.1%	3.3 MB	25.2%
innfeed	3166	3.6%	501.4 KB	3.7%
nnrpd	1096	1.3%	124.8 KB	0.9%
nocem	57	0.1%	6.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	86938	100.0%	13.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	439303	54.5%
Negative hits	316403	39.2%
Do not exist	50217	6.2%
Cache misses	831	0.1%
TOTAL: 4	806754	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.659	0.0%	280	0.000	9.496	77.667
article cleanup	00:00:00.264	0.0%	42233	0.000	0.006	0.024
article logging	00:00:06.491	0.0%	42858	0.000	0.151	1.348
article parse	00:01:55.717	0.1%	1151764	0.005	0.100	0.439
article write	00:04:48.395	0.3%	24282	0.178	11.877	59.486
artlog/artcncl	00:00:00.094	0.0%	249	0.000	0.378	4.556
artlog/artparse	00:00:03.980	0.0%	8342	0.000	0.477	3.857
data move	00:00:01.108	0.0%	1741011	0.000	0.001	0.005
hisgrep/artcncl	00:00:00.452	0.0%	226	0.000	2.000	18.889
hishave/artcncl	00:00:00.675	0.0%	280	0.000	2.411	25.889
hishave/artparse	00:00:00.040	0.0%	8342	0.000	0.005	0.056
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:25.854	0.2%	802441	0.002	0.257	1.111
history sync	00:00:06.936	0.0%	351	0.000	19.761	173.500
history write	00:05:32.924	0.4%	42146	0.125	7.899	57.143
hiswrite/artcncl	00:00:00.466	0.0%	54	0.000	8.630	10.594
hiswrite/artparse	00:01:36.457	0.1%	8342	0.000	11.563	39.600
idle	23:33:58.231	98.1%	1679766	4.496	50.506	350.640
nntp read	00:00:07.474	0.0%	1731651	0.002	0.004	0.007
overview write	00:07:14.115	0.5%	24282	0.692	17.878	288.711
perl filter	00:01:43.016	0.1%	42074	1.500	2.448	3.565
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.473	0.0%	48616	0.000	0.010	0.025
TOTAL: 24:00:47.746	24:00:45.821	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.071	0.0%	24226	0.000	0.003	0.020
article prepare	00:00:00.004	0.0%	41896	0.000	0.000	0.011
article read	00:00:02.156	0.0%	8267	0.014	0.261	13.706
backlog stats	00:00:00.201	0.0%	431416	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.223	0.0%	338767	0.002	0.007	0.012
data write	00:00:01.896	0.0%	324438	0.002	0.006	0.012
idle	23:53:21.210	100.0%	431416	130.716	199.346	667.128
status file	00:00:00.689	0.0%	293	0.000	2.352	192.000
TOTAL: 23:53:38.873	23:53:28.450	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.413	0.0%	94	0.000	4.394	68.000
idle	65:47:38.074	99.8%	632	0.000	374775.434	900176.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.684	0.0%	1373	0.000	0.498	15.667
readart	00:00:00.820	0.0%	48	0.000	17.083	61.000
TOTAL: 65:56:22.867	65:47:39.991	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	4	54475	6311	46630	1534	11%	50:34:59
2	csiph-out.feeder.erje.net	1152	130563	6241	115321	9001	4%	95:02:43
3	feeder.eternal-september.org	32	46593	2981	32247	11365	6%	32:33:44
4	news-out.aioe.org	2	23637	2323	21269	45	9%	24:09:56
5	news-out.mixmin.net	3	25697	2150	23033	514	8%	24:08:43
6	newsfeed.neva.ru	154	51264	1560	47097	2607	3%	71:39:35
7	newsfeed.fu-berlin.de	55	26612	1251	25116	245	4%	35:07:32
8	newsfeeds.xmission.com	3	17850	564	17276	10	3%	18:43:57
9	news.redatomik.org	1	21737	553	21179	5	2%	23:04:35
10	news.freedyn.net	4	27576	147	27288	141	0%	24:08:57
11	news.fcku.it	2	10570	122	10299	149	1%	23:59:54
12	news.swapon.de	4	1912	105	1806	1	5%	24:56:21
13	nntp.pasdenom.info	2	22951	74	22871	6	0%	23:58:42
14	xanadu-bbs.net	13	710	35	669	6	4%	23:49:02
15	localhost	40	7	7	0	0	100%	00:00:02
16	news.bbs.geek.nz	1	16935	1	16934	0	0%	23:59:48
TOTAL: 16		1472	479089	24425	429035	25629	5%	519:58:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.8 MB	628.8 KB	62.1 MB	82.5 MB	24%	5.5 KB
2	csiph-out.news.weretis.net	17.1 MB	0.0 KB	15.4 MB	32.5 MB	52%	4.2 KB
3	news-out.mixmin.net	7.8 MB	15.3 KB	1.4 MB	9.2 MB	84%	3.6 KB
4	feeder.eternal-september.org	7.8 MB	28.9 KB	1.7 GB	1.7 GB	0%	122.0 KB
5	news-out.aioe.org	5.7 MB	4.1 KB	59.7 KB	5.7 MB	98%	2.5 KB
6	newsfeed.neva.ru	3.7 MB	94.7 KB	26.3 MB	30.1 MB	12%	7.4 KB
7	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	550.8 KB	3.3 MB	83%	2.2 KB
8	news.redatomik.org	1.7 MB	9.0 KB	457.2 KB	2.1 MB	78%	3.9 KB
9	newsfeeds.xmission.com	1.4 MB	0.0 KB	613.7 KB	2.0 MB	69%	3.5 KB
10	news.freedyn.net	636.7 KB	6.3 KB	2.5 MB	3.2 MB	19%	11.2 KB
11	news.swapon.de	268.3 KB	0.0 KB	24.5 KB	292.8 KB	91%	2.8 KB
12	nntp.pasdenom.info	162.2 KB	4.8 KB	29.9 KB	196.8 KB	82%	2.5 KB
13	news.fcku.it	156.8 KB	0.0 KB	715.3 KB	872.1 KB	17%	3.2 KB
14	xanadu-bbs.net	98.0 KB	0.0 KB	67.3 KB	165.3 KB	59%	4.0 KB
15	localhost	21.9 KB	0.0 KB	0.0 KB	21.9 KB	100%	3.1 KB
16	news.bbs.geek.nz	1.9 KB	0.0 KB	0.0 KB	1.9 KB	100%	1.9 KB
TOTAL: 16		69.1 MB	791.9 KB	1.8 GB	1.8 GB	3%	38.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 10 04:15:00 - 04:59:59	429	1.8%	0.16	1.2 MB	1.8%	0.46
Mar 10 05:00:00 - 05:59:59	638	2.6%	0.18	2.3 MB	3.4%	0.65
Mar 10 06:00:00 - 06:59:59	721	3.0%	0.20	3.2 MB	4.7%	0.91
Mar 10 07:00:00 - 07:59:59	680	2.8%	0.19	1.8 MB	2.7%	0.52
Mar 10 08:00:00 - 08:59:59	977	4.0%	0.27	2.4 MB	3.6%	0.69
Mar 10 09:00:00 - 09:59:59	1286	5.3%	0.36	3.6 MB	5.3%	1.03
Mar 10 10:00:00 - 10:59:59	1186	4.9%	0.33	3.4 MB	4.9%	0.95
Mar 10 11:00:00 - 11:59:59	1196	4.9%	0.33	3.1 MB	4.5%	0.87
Mar 10 12:00:00 - 12:59:59	1300	5.4%	0.36	3.3 MB	4.8%	0.94
Mar 10 13:00:00 - 13:59:59	1405	5.8%	0.39	3.7 MB	5.4%	1.06
Mar 10 14:00:00 - 14:59:59	1357	5.6%	0.38	3.5 MB	5.1%	1.00
Mar 10 15:00:00 - 15:59:59	1316	5.4%	0.37	3.6 MB	5.3%	1.04
Mar 10 16:00:00 - 16:59:59	1369	5.6%	0.38	4.0 MB	5.9%	1.15
Mar 10 17:00:00 - 17:59:59	1190	4.9%	0.33	3.2 MB	4.7%	0.92
Mar 10 18:00:00 - 18:59:59	1335	5.5%	0.37	3.4 MB	5.0%	0.98
Mar 10 19:00:00 - 19:59:59	1128	4.7%	0.31	3.0 MB	4.4%	0.86
Mar 10 20:00:00 - 20:59:59	1218	5.0%	0.34	3.3 MB	4.8%	0.94
Mar 10 21:00:00 - 21:59:59	1200	5.0%	0.33	3.2 MB	4.6%	0.90
Mar 10 22:00:00 - 22:59:59	1082	4.5%	0.30	2.9 MB	4.2%	0.82
Mar 10 23:00:00 - 23:59:59	894	3.7%	0.25	2.9 MB	4.2%	0.82
Mar 11 00:00:00 - 00:59:59	744	3.1%	0.21	2.3 MB	3.3%	0.65
Mar 11 01:00:00 - 01:59:59	542	2.2%	0.15	1.6 MB	2.4%	0.46
Mar 11 02:00:00 - 02:59:59	536	2.2%	0.15	1.5 MB	2.3%	0.44
Mar 11 03:00:00 - 03:59:59	420	1.7%	0.12	1.4 MB	2.0%	0.40
Mar 11 04:00:00 - 04:15:00	88	0.4%	0.10	476.7 KB	0.7%	0.53
TOTAL: 24:00:00	24237	100.0%	0.28	68.5 MB	100.0%	0.81

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	feeder.eternal-september.org	11957	63	5	0	11889
2	csiph-out.feeder.erje.net	9046	323	62	0	8661
3	newsfeed.neva.ru	2559	301	9	0	2249
4	csiph-out.news.weretis.net	1522	276	0	0	1246
5	newsfeed.fu-berlin.de	702	23	0	0	679
6	news-out.mixmin.net	543	1	5	0	537
7	news.fcku.it	151	0	0	0	151
8	news.freedyn.net	134	12	1	0	121
9	news-out.aioe.org	53	42	1	0	10
10	newsfeeds.xmission.com	16	0	0	0	16
11	news.swapon.de	15	0	0	0	15
12	xanadu-bbs.net	8	0	0	0	8
13	news.redatomik.org	7	0	2	0	5
14	nntp.pasdenom.info	6	1	2	1	2
TOTAL: 14		26719	1042	87	1	25589

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	139
ut.test	121
it-alt.sport.calcio.juventus	109
lada.auto	64
alt.courts.pretrial-services	56
mozilla.support.thunderbird	49
gmane.comp.gis.openstreetmap.tagging	42
spline.eisfair.dev	29
mozilla.dev.planning	21
mozilla.dev.platform	21
cakewalk.audio	19
mpc.lists.freebsd.x11	18
gmane.comp.bacula.user	17
mozilla.support.firefox	16
lada.commerce	16
mozilla.general	13
mozilla.dev.security.policy	13
mozilla.support.seamonkey	13
mpc.lists.nanog	10
alt.windows7.general	10
TOTAL: 127	1042

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14596
EMP (phn path)	1604
Too many newsgroups (meow)	174
EMP (phn nph)	103
Too many newsgroups	60
EMP (phl)	54
HTML Multipart	35
Binary Payload	25
User-issued cancel	24
Scoring filter (8)	14
Scoring filter (15)	13
Scoring filter (14)	10
Binary Image: misplaced jpg	8
Scoring filter (9)	5
HTML post	4
Too many newsgroups (low_xpost)	4
Binary: misplaced nzb	1
Angle-bracket bot	1
Binary: misplaced db	1
Binary: misplaced pdf	1
TOTAL: 20	16737

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	51	0	61	61
TOTAL: 1	51	0	61	61

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	71
	TOTAL: 1	71
Huge articles
	feeder.eternal-september.org	8034
	csiph-out.news.weretis.net	60
	news.freedyn.net	21
	newsfeeds.xmission.com	14
	news.fcku.it	12
	news-out.mixmin.net	11
	newsfeed.fu-berlin.de	3
	news.redatomik.org	1
	xanadu-bbs.net	1
	TOTAL: 9	8157
Including strange strings
	newsfeed.fu-berlin.de	450
	csiph-out.news.weretis.net	62
	csiph-out.feeder.erje.net	40
	news-out.mixmin.net	30
	news.swapon.de	14
	newsfeed.neva.ru	8
	news-out.aioe.org	8
	feeder.eternal-september.org	7
	xanadu-bbs.net	2
	news.redatomik.org	2
	TOTAL: 11	624
TOTAL: 3		8852

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	39767	6564	16841	179	0	16%	23:50:00
2	news.freedyn.net	25000	768	23258	1	0	3%	23:50:00
3	xanadu-bbs	26381	609	23550	0	14	2%	23:55:31
4	fu-berlin.de	22699	501	22198	0	0	2%	23:51:42
5	usenet.pasdenom.info	26388	164	22530	1451	15	0%	23:57:29
6	xmission	25572	54	22802	1	0	0%	23:50:00
7	aioe	21017	35	19658	6	0	0%	23:50:05
8	weretis.net	17283	31	16691	0	0	0%	23:50:00
9	news.redatomik.org	24001	29	22959	10	0	0%	23:50:00
10	eternal-september	21278	17	19901	0	0	0%	23:53:52
11	news.mixmin.net	21741	8	20895	0	0	0%	23:52:19
12	neva.ru	22540	6	21687	12	0	0%	23:50:00
13	news.swapon.de	1885	6	1845	2	0	0%	23:44:24
14	erje	18008	1	17849	17	0	0%	23:50:00
15	bwh	0	0	0	0	23579	0%	23:50:08
16	news.fcku.it	3519	0	3386	0	0	0%	22:54:08
17	au2pb	0	0	0	0	23594	0%	23:50:08
TOTAL: 17		317079	8793	276050	1679	47202	2%	404:29:46

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	16.6 MB	496.8 KB	17.1 MB	0.2 KB/s	2.6 KB	23:50:00
2	news.freedyn.net	5.0 MB	1.3 KB	5.0 MB	0.1 KB/s	6.6 KB	23:50:00
3	xanadu-bbs	4.5 MB	0.0 KB	4.5 MB	0.1 KB/s	7.6 KB	23:55:31
4	fu-berlin.de	4.3 MB	0.0 KB	4.3 MB	0.1 KB/s	8.7 KB	23:51:42
5	usenet.pasdenom.info	440.6 KB	5.9 MB	6.3 MB	0.1 KB/s	4.0 KB	23:57:29
6	xmission	191.6 KB	1.1 KB	192.7 KB	0.0 KB/s	3.5 KB	23:50:00
7	aioe	101.0 KB	12.2 KB	113.2 KB	0.0 KB/s	2.8 KB	23:50:05
8	weretis.net	91.6 KB	0.0 KB	91.6 KB	0.0 KB/s	3.0 KB	23:50:00
9	news.redatomik.org	68.5 KB	22.4 KB	90.8 KB	0.0 KB/s	2.3 KB	23:50:00
10	eternal-september	47.8 KB	0.0 KB	47.8 KB	0.0 KB/s	2.8 KB	23:53:52
11	news.mixmin.net	23.9 KB	0.0 KB	23.9 KB	0.0 KB/s	3.0 KB	23:52:19
12	news.swapon.de	10.0 KB	16.3 KB	26.3 KB	0.0 KB/s	3.3 KB	23:44:24
13	neva.ru	9.8 KB	36.5 KB	46.4 KB	0.0 KB/s	2.6 KB	23:50:00
14	erje	2.1 KB	117.1 KB	119.2 KB	0.0 KB/s	6.6 KB	23:50:00
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:08
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:54:08
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:08
TOTAL: 17		31.3 MB	6.5 MB	37.9 MB	0.0 KB/s	3.7 KB	404:29:46

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	22	21	38.4 KB	11	1	0	05:38:48
2	68-185-181-64.dhcp.mdsn.wi.charter.com	30	13	44.6 KB	10	7	7	10:35:42
3	dhcp-089-099-203-254.chello.nl	17	11	40.6 KB	5	0	0	02:52:16
4	ess-p-144-138-90-254.mega.tmns.net.au	3	3	4.0 KB	1	0	0	00:06:54
TOTAL: 4		72	48	127.6 KB	27	8	7	65:56:21

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	22	21	38.4 KB	11	1	0	05:38:48
2	*.dhcp.mdsn.wi.charter.com	30	13	44.6 KB	10	7	7	10:35:42
3	*.chello.nl	17	11	40.6 KB	5	0	0	02:52:16
4	*.mega.tmns.net.au	3	3	4.0 KB	1	0	0	00:06:54
5	unresolved	94	0	0.0 KB	0	0	0	46:08:34
6	*.cust.centrio.cz	3	0	0.0 KB	0	0	0	00:34:04
TOTAL: 6		169	48	127.6 KB	27	8	7	65:56:21

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
ctomczyk	2
moussa	2
TOTAL: 3	11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	6.829	1.651	0.000	46:08:34
68-185-181-64.dhcp.mdsn.wi.charter.com	2.963	0.610	0.027	10:35:42
tempe0.bbox.io	2.070	0.965	0.004	05:38:48
dhcp-089-099-203-254.chello.nl	1.566	0.322	0.000	02:52:16
204-73-239-109.cust.centrio.cz	0.153	0.039	0.009	00:34:04
ess-p-144-138-90-254.mega.tmns.net.au	0.312	0.065	0.009	00:06:54
TOTAL: 6	13.893	3.652	0.049	65:56:21

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	94
204-73-239-109.cust.centrio.cz	3
TOTAL: 2	97

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	90	0
68-185-181-64.dhcp.mdsn.wi.charter.com	12	0
tempe0.bbox.io	9	0
204-73-239-109.cust.centrio.cz	1	0
TOTAL: 4	112	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	28
2	comp.sys.m68k	6
3	comp.sys.next.marketplace	3
4	comp.sys.sgi.hardware	2
5	comp.sys.sun.hardware	2
6	comp.sys.tandem	2
7	comp.sys.powerpc.advocacy	1
8	comp.sys.super	1
9	comp.unix.aix	1
10	comp.sys.sun.wanted	1
11	comp.sys.powerpc.tech	1
TOTAL: 11		48

Daily Usenet report for csiph.com

Mar 10 04:15:00 -- Mar 11 04:15:00