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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 13 18:10:02 csiph controlchan[63439]: Multiple control headers in article <AZwUh-1Dz-5@gated-at.bofh.it>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19125	48.6%	4.9 MB	63.4%
innd	15912	40.4%	2.1 MB	27.2%
innfeed	3541	9.0%	650.3 KB	8.3%
nnrpd	768	2.0%	85.4 KB	1.1%
nocem	6	0.0%	0.6 KB	0.0%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	39354	100.0%	7.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	315506	57.4%
Negative hits	215068	39.1%
Do not exist	18600	3.4%
Cache misses	301	0.1%
TOTAL: 4	549475	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.334	0.0%	33	0.000	10.121	24.143
article cleanup	00:00:00.108	0.0%	18164	0.000	0.006	0.032
article logging	00:00:00.280	0.0%	18622	0.000	0.015	0.190
article parse	00:00:01.539	0.0%	63560	0.000	0.024	0.913
article write	00:00:09.303	0.0%	15145	0.145	0.614	22.385
artlog/artcncl	00:00:00.001	0.0%	33	0.000	0.030	0.143
artlog/artparse	00:00:00.008	0.0%	509	0.000	0.016	0.250
data move	00:00:00.198	0.0%	523581	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.054	0.0%	32	0.000	1.688	18.000
hishave/artcncl	00:00:00.052	0.0%	33	0.000	1.576	7.143
hishave/artparse	00:00:00.001	0.0%	502	0.000	0.002	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:13.701	0.0%	550277	0.001	0.025	0.578
history sync	00:00:00.888	0.0%	382	0.000	2.325	23.000
history write	00:00:18.156	0.0%	18162	0.067	1.000	12.589
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.952	0.0%	502	0.000	1.896	69.750
idle	23:58:19.690	99.8%	529437	83.289	163.003	418.229
nntp read	00:00:01.980	0.0%	531033	0.001	0.004	0.008
overview write	00:00:59.380	0.1%	15145	0.132	3.921	271.086
perl filter	00:00:25.596	0.0%	18155	1.074	1.410	2.070
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.236	0.0%	30296	0.000	0.008	0.038
TOTAL: 24:01:07.782	24:00:32.457	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.032	0.0%	15268	0.000	0.002	0.020
article prepare	00:00:00.000	0.0%	12516	0.000	0.000	0.000
article read	00:00:00.265	0.0%	1561	0.000	0.170	2.222
backlog stats	00:00:00.187	0.0%	348678	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.233	0.0%	277586	0.000	0.004	0.011
data write	00:00:01.477	0.0%	264106	0.001	0.006	0.015
idle	23:54:57.872	100.0%	348678	145.966	246.927	658.770
status file	00:00:00.215	0.0%	291	0.000	0.739	2.000
TOTAL: 23:55:08.866	23:55:01.281	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	41:02:54.469	99.8%	440	7732.000	335851.066	600112.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	964	0.000	0.006	0.286
readart	00:00:00.042	0.0%	1	42.000	42.000	42.000
TOTAL: 41:07:42.281	41:02:54.517	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	139
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	159

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	14307	3450	10809	48	24%	24:07:52
2	news-out.aioe.org	3	14384	3131	11133	120	21%	24:09:55
3	csiph-out.news.xs4all.nl	496	17919	2519	13521	1879	14%	68:54:12
4	feeder.eternal-september.org	4	16894	2109	14683	102	12%	24:07:35
5	newsfeed.fu-berlin.de	13	16063	775	15191	97	4%	28:59:20
6	newsfeed.neva.ru	150	18110	656	17167	287	3%	72:04:21
7	csiph-out.feeder.erje.net	1440	74171	630	73422	119	0%	118:46:42
8	news-out.mixmin.net	1	16072	601	15374	97	3%	23:59:52
9	csiph-out.news.weretis.net	3	28879	427	28166	286	1%	44:03:58
10	news.fcku.it	15	1703	358	1341	4	21%	21:21:46
11	news-out.neodome.net	2	16533	196	16330	7	1%	24:01:57
12	csiph-out.feed.uzoreto.com	864	23638	94	23341	203	0%	71:11:38
13	news.swapon.de	10	1455	76	1379	0	5%	24:06:52
14	nntp.pasdenom.info	2	10015	47	9959	9	0%	23:58:46
15	news.freedyn.net	2	18422	19	18265	138	0%	23:59:57
16	csiph-out.news.tnetconsulting.net	2	14935	18	14904	13	0%	23:59:49
17	news.samoylyk.net	1	14582	3	14579	0	0%	23:59:30
18	localhost	26	0	0	0	0	0%	00:00:05
19	xanadu-bbs.net	35	395	0	395	0	0%	20:26:29
20	news.bbs.nz	1	14257	0	14257	0	0%	23:59:53
TOTAL: 20		3072	332734	15109	314216	3409	4%	710:20:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	10.5 MB	0.0 KB	2.9 MB	13.4 MB	78%	3.9 KB
2	news-out.aioe.org	7.2 MB	0.0 KB	353.0 KB	7.5 MB	95%	2.4 KB
3	csiph-out.news.xs4all.nl	6.2 MB	0.0 KB	16.6 MB	22.7 MB	27%	5.3 KB
4	feeder.eternal-september.org	5.8 MB	6.9 KB	3.0 MB	8.8 MB	65%	4.1 KB
5	csiph-out.feeder.erje.net	4.6 MB	0.0 KB	2.4 MB	7.0 MB	66%	9.6 KB
6	news-out.mixmin.net	1.9 MB	0.0 KB	289.4 KB	2.2 MB	86%	3.2 KB
7	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	425.7 KB	2.3 MB	81%	2.7 KB
8	newsfeed.neva.ru	1.7 MB	0.0 KB	510.5 KB	2.2 MB	76%	2.3 KB
9	csiph-out.news.weretis.net	1.1 MB	1.2 KB	2.4 MB	3.6 MB	31%	5.1 KB
10	news.fcku.it	542.2 KB	1.5 KB	5.8 KB	549.5 KB	98%	1.5 KB
11	news-out.neodome.net	490.1 KB	1.2 KB	58.7 KB	549.9 KB	89%	2.7 KB
12	csiph-out.feed.uzoreto.com	373.7 KB	0.0 KB	7.3 MB	7.6 MB	4%	26.3 KB
13	news.freedyn.net	269.2 KB	1.2 KB	17.6 MB	17.8 MB	1%	116.3 KB
14	news.swapon.de	170.9 KB	0.0 KB	0.0 KB	170.9 KB	100%	2.2 KB
15	nntp.pasdenom.info	110.4 KB	0.0 KB	11.5 KB	122.0 KB	90%	2.2 KB
16	csiph-out.news.tnetconsulting.net	72.7 KB	0.0 KB	35.5 KB	108.2 KB	67%	3.5 KB
17	news.samoylyk.net	5.4 KB	0.0 KB	0.0 KB	5.4 KB	100%	1.8 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		42.7 MB	12.0 KB	53.8 MB	96.5 MB	44%	5.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 13 04:15:00 - 04:59:59	317	2.1%	0.12	1.6 MB	3.8%	0.61
Oct 13 05:00:00 - 05:59:59	367	2.4%	0.10	1.1 MB	2.7%	0.32
Oct 13 06:00:00 - 06:59:59	494	3.3%	0.14	1.3 MB	3.0%	0.36
Oct 13 07:00:00 - 07:59:59	532	3.5%	0.15	1.4 MB	3.2%	0.39
Oct 13 08:00:00 - 08:59:59	758	5.0%	0.21	1.9 MB	4.5%	0.54
Oct 13 09:00:00 - 09:59:59	698	4.6%	0.19	1.8 MB	4.1%	0.50
Oct 13 10:00:00 - 10:59:59	742	4.9%	0.21	2.1 MB	4.9%	0.59
Oct 13 11:00:00 - 11:59:59	726	4.8%	0.20	1.7 MB	3.9%	0.47
Oct 13 12:00:00 - 12:59:59	733	4.8%	0.20	1.8 MB	4.3%	0.52
Oct 13 13:00:00 - 13:59:59	792	5.2%	0.22	2.0 MB	4.7%	0.57
Oct 13 14:00:00 - 14:59:59	939	6.2%	0.26	2.4 MB	5.5%	0.67
Oct 13 15:00:00 - 15:59:59	910	6.0%	0.25	2.3 MB	5.4%	0.65
Oct 13 16:00:00 - 16:59:59	877	5.8%	0.24	3.1 MB	7.4%	0.90
Oct 13 17:00:00 - 17:59:59	741	4.9%	0.21	2.0 MB	4.6%	0.56
Oct 13 18:00:00 - 18:59:59	856	5.7%	0.24	2.4 MB	5.6%	0.68
Oct 13 19:00:00 - 19:59:59	887	5.9%	0.25	2.3 MB	5.3%	0.65
Oct 13 20:00:00 - 20:59:59	675	4.5%	0.19	2.0 MB	4.7%	0.57
Oct 13 21:00:00 - 21:59:59	672	4.4%	0.19	1.8 MB	4.3%	0.52
Oct 13 22:00:00 - 22:59:59	560	3.7%	0.16	1.7 MB	4.0%	0.49
Oct 13 23:00:00 - 23:59:59	458	3.0%	0.13	1.4 MB	3.2%	0.39
Oct 14 00:00:00 - 00:59:59	334	2.2%	0.09	1.1 MB	2.6%	0.31
Oct 14 01:00:00 - 01:59:59	377	2.5%	0.10	1.2 MB	2.9%	0.35
Oct 14 02:00:00 - 02:59:59	286	1.9%	0.08	897.4 KB	2.1%	0.25
Oct 14 03:00:00 - 03:59:59	308	2.0%	0.09	1.1 MB	2.6%	0.31
Oct 14 04:00:00 - 04:15:00	87	0.6%	0.10	373.2 KB	0.9%	0.41
TOTAL: 24:00:00	15126	100.0%	0.18	42.7 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1867	76	0	1791
2	newsfeed.fu-berlin.de	430	32	0	398
3	csiph-out.news.weretis.net	375	29	1	345
4	newsfeed.neva.ru	294	95	0	199
5	csiph-out.feed.uzoreto.com	202	7	0	195
6	news-out.aioe.org	192	112	0	80
7	news.freedyn.net	141	0	1	140
8	csiph-out.feeder.erje.net	120	44	0	76
9	news-out.mixmin.net	106	7	0	99
10	feeder.eternal-september.org	105	35	3	67
11	newsfeeds.xmission.com	60	2	0	58
12	news.swapon.de	28	0	0	28
13	csiph-out.news.tnetconsulting.net	28	16	2	10
14	nntp.pasdenom.info	9	0	0	9
15	news-out.neodome.net	7	3	1	3
16	news.fcku.it	4	3	1	0
TOTAL: 16		3968	461	9	3498

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	68
alt.bbs.synchronet	59
alt.computer.workshop	53
scinet.stats	25
it-alt.sport.calcio.juventus	23
lada.auto	23
alt.windows7.general	17
spline.eisfair	17
alt.binaries.pictures.purebeauty	12
alt.politics	9
mozilla.support.seamonkey	9
cakewalk.audio	9
free.it.religioni.scientology	8
alt.survival	7
alt.politics.usa	6
oesterreich.tratsch	6
at.jobs	5
alt.binaries.kindle	5
alt.philosophy	4
alt.misanthropy	4
TOTAL: 80	461

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1761
EMP (phn path)	343
EMP (phl)	203
Too many newsgroups	77
EMP (phn nph)	62
HTML Multipart	23
Binary Payload	23
0	20
Binary: misplaced binary	7
Too many newsgroups (meow)	6
Scoring filter	5
HTML post	1
TOTAL: 12	2531

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	70
	csiph-out.feed.uzoreto.com	5
	csiph-out.news.xs4all.nl	5
	TOTAL: 3	80
Huge articles
	csiph-out.news.xs4all.nl	178
	news.freedyn.net	112
	csiph-out.feed.uzoreto.com	93
	newsfeeds.xmission.com	53
	csiph-out.feeder.erje.net	37
	csiph-out.news.weretis.net	17
	feeder.eternal-september.org	7
	news-out.mixmin.net	2
	newsfeed.fu-berlin.de	2
	TOTAL: 9	501
Including strange strings
	newsfeed.fu-berlin.de	337
	news-out.aioe.org	60
	news.swapon.de	28
	csiph-out.news.xs4all.nl	11
	news-out.mixmin.net	10
	newsfeeds.xmission.com	5
	csiph-out.news.weretis.net	4
	feeder.eternal-september.org	2
	news-out.neodome.net	1
	TOTAL: 9	458
No colon-space in header
	csiph-out.news.xs4all.nl	7
	TOTAL: 1	7
TOTAL: 4		1046

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19138	1126	13948	52	48	5%	23:59:54
2	news.bbs.nz	28544	499	14117	24	0	1%	23:59:54
3	fu-berlin.de	14234	451	13783	0	1	3%	23:59:49
4	news.samoylyk.net	15573	435	14680	8	0	2%	23:59:27
5	tnet	16506	129	14528	1	0	0%	23:59:54
6	neva.ru	13530	110	13264	47	0	0%	23:59:54
7	news.freedyn.net	14718	35	14411	0	0	0%	23:59:54
8	newsfeed.xs4all.nl	12896	28	12505	0	0	0%	23:59:40
9	weretis.net	14578	17	14162	1	0	0%	23:59:49
10	usenet.pasdenom.info	15238	13	14589	5	15	0%	23:59:54
11	news.uzoreto.com	14544	12	14258	88	0	0%	23:59:54
12	xmission	12766	11	11091	2	0	0%	23:59:54
13	neodome.net	15260	8	14885	0	103	0%	23:59:54
14	news.mixmin.net	14162	7	13909	0	0	0%	23:59:54
15	aioe	11016	4	10595	1	0	0%	23:59:50
16	erje	14554	2	14258	34	0	0%	23:59:54
17	eternal-september	12624	0	12318	0	0	0%	23:59:28
18	news.fcku.it	2173	0	1309	0	0	0%	20:44:17
19	news.swapon.de	1402	0	1394	0	0	0%	23:07:19
TOTAL: 19		263456	2887	234004	263	167	1%	451:48:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.1 MB	195.7 KB	6.2 MB	0.1 KB/s	5.4 KB	23:59:54
2	fu-berlin.de	4.2 MB	0.0 KB	4.2 MB	0.0 KB/s	9.5 KB	23:59:49
3	news.samoylyk.net	4.1 MB	49.8 KB	4.2 MB	0.0 KB/s	9.7 KB	23:59:27
4	news.bbs.nz	1.3 MB	79.1 KB	1.4 MB	0.0 KB/s	2.7 KB	23:59:54
5	tnet	320.7 KB	1.2 KB	322.0 KB	0.0 KB/s	2.5 KB	23:59:54
6	news.freedyn.net	260.3 KB	0.0 KB	260.3 KB	0.0 KB/s	7.4 KB	23:59:54
7	neva.ru	220.8 KB	88.8 KB	309.6 KB	0.0 KB/s	2.0 KB	23:59:54
8	newsfeed.xs4all.nl	59.2 KB	0.0 KB	59.2 KB	0.0 KB/s	2.1 KB	23:59:40
9	usenet.pasdenom.info	23.4 KB	20.9 KB	44.3 KB	0.0 KB/s	2.5 KB	23:59:54
10	weretis.net	20.9 KB	1.2 KB	22.1 KB	0.0 KB/s	1.2 KB	23:59:49
11	xmission	20.6 KB	2.3 KB	22.9 KB	0.0 KB/s	1.8 KB	23:59:54
12	news.uzoreto.com	19.4 KB	179.3 KB	198.7 KB	0.0 KB/s	2.0 KB	23:59:54
13	neodome.net	14.1 KB	0.0 KB	14.1 KB	0.0 KB/s	1.8 KB	23:59:54
14	news.mixmin.net	9.1 KB	0.0 KB	9.1 KB	0.0 KB/s	1.3 KB	23:59:54
15	aioe	9.0 KB	1.0 KB	10.0 KB	0.0 KB/s	2.0 KB	23:59:50
16	erje	7.1 KB	141.2 KB	148.3 KB	0.0 KB/s	4.1 KB	23:59:54
17	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:28
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:44:17
19	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:07:19
TOTAL: 19		16.7 MB	760.6 KB	17.4 MB	0.0 KB/s	5.7 KB	451:48:33

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	48	1	2.9 KB	1	0	0	14:08:17
TOTAL: 1		48	1	2.9 KB	1	0	0	41:07:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.upc-a.chello.nl	48	1	2.9 KB	1	14:08:17
2	unresolved	73	0	0.0 KB	0	23:59:52
3	*.res.spectrum.com	4	0	0.0 KB	0	01:28:32
4	*.ph.ph.cox.net	3	0	0.0 KB	0	01:30:58
TOTAL: 4		128	1	2.9 KB	1	41:07:41

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	4.517	1.099	23:59:42
a131033.upc-a.chello.nl	3.057	0.689	14:08:17
ip72-222-200-117.ph.ph.cox.net	0.275	0.048	01:30:58
024-178-046-028.res.spectrum.com	0.260	0.047	01:28:32
192.241.239.112	0.059	0.022	00:00:09
TOTAL: 5	8.168	1.905	41:07:41

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
024-178-046-028.res.spectrum.com	4
ip72-222-200-117.ph.ph.cox.net	3
192.241.239.112	1
TOTAL: 4	80

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	3	0
024-178-046-028.res.spectrum.com	2	0
TOTAL: 2	5	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	1
TOTAL: 1		1

Daily Usenet report for csiph.com

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