Daily Usenet report for csiph.com: Aug 20 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18842	46.7%	4.9 MB	62.1%
innd	17207	42.6%	2.3 MB	28.9%
innfeed	3505	8.7%	634.0 KB	7.9%
nnrpd	764	1.9%	81.5 KB	1.0%
nocem	38	0.1%	4.1 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	40357	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	345116	60.2%
Negative hits	209683	36.6%
Do not exist	18327	3.2%
Cache misses	314	0.1%
TOTAL: 4	573440	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.397	0.0%	35	0.000	11.343	32.600
article cleanup	00:00:00.122	0.0%	17832	0.000	0.007	0.033
article logging	00:00:00.321	0.0%	18264	0.000	0.018	0.442
article parse	00:00:00.940	0.0%	59688	0.000	0.016	0.052
article write	00:00:07.993	0.0%	15184	0.167	0.526	10.915
artlog/artcncl	00:00:00.000	0.0%	35	0.000	0.000	0.000
artlog/artparse	00:00:00.012	0.0%	564	0.000	0.021	0.500
data move	00:00:00.188	0.0%	540928	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.016	0.0%	35	0.000	0.457	2.000
hishave/artcncl	00:00:00.010	0.0%	35	0.000	0.286	0.800
hishave/artparse	00:00:00.002	0.0%	559	0.000	0.004	0.056
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.193	0.0%	574885	0.001	0.007	0.084
history sync	00:00:00.758	0.0%	387	0.000	1.959	5.333
history write	00:00:09.095	0.0%	17830	0.047	0.510	4.371
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.349	0.0%	559	0.000	0.624	4.500
idle	23:56:32.973	99.7%	545709	96.018	157.947	401.158
nntp read	00:00:02.037	0.0%	546266	0.001	0.004	0.006
overview write	00:03:07.397	0.2%	15184	0.125	12.342	2688.288
perl filter	00:00:24.804	0.0%	17825	1.088	1.392	2.123
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.238	0.0%	30406	0.000	0.008	0.042
TOTAL: 24:01:08.558	24:00:31.845	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	15698	0.000	0.003	0.026
article prepare	00:00:00.002	0.0%	14332	0.000	0.000	0.007
article read	00:00:00.290	0.0%	1902	0.000	0.152	11.000
backlog stats	00:00:00.169	0.0%	351567	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.265	0.0%	277116	0.001	0.005	0.009
data write	00:00:01.561	0.0%	264504	0.002	0.006	0.013
idle	23:54:36.316	100.0%	351567	144.500	244.836	563.722
status file	00:00:00.192	0.0%	265	0.000	0.725	2.500
TOTAL: 23:54:47.316	23:54:39.841	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	33:23:08.026	99.8%	508	482.143	236590.602	450415.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.638	0.0%	1246	0.000	0.512	12.233
readart	00:00:00.336	0.0%	30	0.000	11.200	42.000
TOTAL: 33:27:59.026	33:23:09.000	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	3	14769	3891	10781	97	26%	24:30:37
2	news-out.mixmin.net	4	15844	3122	12126	596	19%	24:11:55
3	feeder.eternal-september.org	4	17424	2351	14884	189	13%	24:53:15
4	news-out.aioe.org	2	14753	1097	13598	58	7%	23:59:57
5	csiph-out.news.weretis.net	6	45876	1093	44455	328	2%	71:59:23
6	newsfeed.neva.ru	149	33604	844	32475	285	2%	72:20:51
7	newsfeed.fu-berlin.de	6	16156	819	15143	194	5%	28:16:05
8	csiph-out.feeder.erje.net	1440	74999	791	73851	357	1%	118:45:36
9	csiph-out.news.xs4all.nl	861	16727	325	15993	409	1%	71:25:14
10	news.fcku.it	14	1421	212	1206	3	14%	22:15:26
11	news-out.neodome.net	2	16171	181	15903	87	1%	23:59:54
12	csiph-out.feed.uzoreto.com	864	23642	180	22791	671	0%	71:21:06
13	nntp.pasdenom.info	2	9945	160	9776	9	1%	23:59:37
14	news.freedyn.net	2	18280	69	18119	92	0%	23:59:56
15	news.swapon.de	5	1444	45	1399	0	3%	23:52:32
16	csiph-out.news.tnetconsulting.net	1	14521	22	14478	21	0%	23:59:54
17	news.samoylyk.net	2	14899	17	14882	0	0%	24:54:33
18	news.bbs.nz	2	14007	6	14001	0	0%	23:59:45
19	localhost	29	3	3	0	0	100%	00:00:04
20	xanadu-bbs.net	33	320	0	320	0	0%	19:23:33
TOTAL: 20		3431	364805	15228	346181	3396	4%	742:09:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	12.6 MB	0.0 KB	7.6 MB	20.2 MB	62%	5.2 KB
2	news-out.mixmin.net	6.4 MB	0.0 KB	1.2 MB	7.6 MB	84%	2.1 KB
3	feeder.eternal-september.org	6.3 MB	2.9 KB	2.4 MB	8.7 MB	72%	3.5 KB
4	csiph-out.feeder.erje.net	4.6 MB	0.0 KB	4.0 MB	8.7 MB	53%	7.7 KB
5	news-out.aioe.org	3.8 MB	0.0 KB	297.0 KB	4.1 MB	92%	3.6 KB
6	csiph-out.news.weretis.net	3.0 MB	3.5 KB	3.4 MB	6.5 MB	47%	4.7 KB
7	newsfeed.neva.ru	2.3 MB	0.0 KB	809.3 KB	3.0 MB	74%	2.8 KB
8	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	573.4 KB	2.6 MB	78%	2.6 KB
9	csiph-out.news.xs4all.nl	882.6 KB	0.0 KB	4.0 MB	4.9 MB	17%	6.8 KB
10	csiph-out.feed.uzoreto.com	811.4 KB	0.0 KB	19.2 MB	20.0 MB	3%	24.1 KB
11	news-out.neodome.net	527.7 KB	1.7 KB	227.6 KB	757.1 KB	69%	2.8 KB
12	news.freedyn.net	414.7 KB	0.0 KB	1.8 MB	2.2 MB	18%	13.9 KB
13	nntp.pasdenom.info	373.5 KB	0.0 KB	13.8 KB	387.4 KB	96%	2.3 KB
14	news.fcku.it	364.0 KB	0.0 KB	74.5 KB	438.5 KB	83%	2.0 KB
15	news.swapon.de	106.0 KB	0.0 KB	0.0 KB	106.0 KB	100%	2.4 KB
16	csiph-out.news.tnetconsulting.net	100.0 KB	0.0 KB	71.7 KB	171.8 KB	58%	4.0 KB
17	news.samoylyk.net	42.9 KB	0.0 KB	0.0 KB	42.9 KB	100%	2.5 KB
18	news.bbs.nz	10.7 KB	0.0 KB	0.0 KB	10.7 KB	100%	1.8 KB
19	localhost	6.3 KB	0.0 KB	0.0 KB	6.3 KB	100%	2.1 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		44.6 MB	8.1 KB	45.7 MB	90.3 MB	49%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 20 04:15:00 - 04:59:59	254	1.7%	0.09	731.9 KB	1.6%	0.27
Aug 20 05:00:00 - 05:59:59	352	2.3%	0.10	1.1 MB	2.4%	0.31
Aug 20 06:00:00 - 06:59:59	504	3.3%	0.14	1.6 MB	3.4%	0.45
Aug 20 07:00:00 - 07:59:59	578	3.8%	0.16	1.5 MB	3.3%	0.42
Aug 20 08:00:00 - 08:59:59	676	4.4%	0.19	2.0 MB	4.4%	0.57
Aug 20 09:00:00 - 09:59:59	631	4.2%	0.18	1.7 MB	3.7%	0.48
Aug 20 10:00:00 - 10:59:59	699	4.6%	0.19	2.6 MB	5.7%	0.74
Aug 20 11:00:00 - 11:59:59	698	4.6%	0.19	2.2 MB	4.8%	0.63
Aug 20 12:00:00 - 12:59:59	729	4.8%	0.20	1.9 MB	4.3%	0.55
Aug 20 13:00:00 - 13:59:59	798	5.3%	0.22	2.2 MB	4.8%	0.62
Aug 20 14:00:00 - 14:59:59	822	5.4%	0.23	2.2 MB	4.9%	0.64
Aug 20 15:00:00 - 15:59:59	908	6.0%	0.25	2.3 MB	5.1%	0.66
Aug 20 16:00:00 - 16:59:59	833	5.5%	0.23	2.2 MB	4.9%	0.63
Aug 20 17:00:00 - 17:59:59	830	5.5%	0.23	2.2 MB	4.9%	0.64
Aug 20 18:00:00 - 18:59:59	800	5.3%	0.22	2.2 MB	4.9%	0.64
Aug 20 19:00:00 - 19:59:59	744	4.9%	0.21	2.2 MB	4.9%	0.63
Aug 20 20:00:00 - 20:59:59	724	4.8%	0.20	2.0 MB	4.4%	0.57
Aug 20 21:00:00 - 21:59:59	789	5.2%	0.22	2.6 MB	5.8%	0.75
Aug 20 22:00:00 - 22:59:59	643	4.2%	0.18	2.0 MB	4.3%	0.56
Aug 20 23:00:00 - 23:59:59	471	3.1%	0.13	1.6 MB	3.4%	0.44
Aug 21 00:00:00 - 00:59:59	460	3.0%	0.13	1.4 MB	3.1%	0.40
Aug 21 01:00:00 - 01:59:59	407	2.7%	0.11	1.2 MB	2.6%	0.34
Aug 21 02:00:00 - 02:59:59	358	2.4%	0.10	1.0 MB	2.2%	0.29
Aug 21 03:00:00 - 03:59:59	374	2.5%	0.10	2.4 MB	5.4%	0.69
Aug 21 04:00:00 - 04:15:00	111	0.7%	0.12	299.3 KB	0.6%	0.33
TOTAL: 24:00:00	15193	100.0%	0.18	45.5 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feed.uzoreto.com	671	12	0	659
2	news-out.mixmin.net	646	3	0	643
3	newsfeed.fu-berlin.de	457	26	0	431
4	csiph-out.news.xs4all.nl	404	14	0	390
5	csiph-out.news.weretis.net	288	93	3	192
6	newsfeed.neva.ru	284	30	0	254
7	csiph-out.feeder.erje.net	252	43	0	209
8	feeder.eternal-september.org	186	28	2	156
9	newsfeeds.xmission.com	124	7	0	117
10	news.freedyn.net	93	0	0	93
11	news-out.neodome.net	85	0	1	84
12	news-out.aioe.org	80	47	0	33
13	csiph-out.news.tnetconsulting.net	20	20	0	0
14	news.swapon.de	12	0	0	12
15	nntp.pasdenom.info	9	0	0	9
16	news.fcku.it	4	2	0	2
17	news.bbs.nz	1	0	1	0
TOTAL: 17		3616	325	7	3284

Unwanted newsgroups [Top 20]:

Newsgroup	Count
scinet.stats	29
lada.talk	24
alt.politics	22
it-alt.sport.calcio.juventus	16
alt.computer.workshop	15
mozilla.test	13
alt.politics.usa	12
mozilla.support.seamonkey	12
alt.philosophy	12
free.it.religioni.scientology	9
alt.bbs.synchronet	9
alt.windows7.general	8
mozilla.general	6
alt.survival	6
mozilla.support.thunderbird	6
spline.eisfair	6
alt.binaries.ebook	5
oesterreich.tratsch	5
spline.fli4l	4
alt.paranormal.spells	4
TOTAL: 81	325

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1593
EMP (phn path)	355
EMP (phl)	139
0	73
Too many newsgroups	55
EMP (phn nph)	50
HTML Multipart	20
Binary: misplaced binary	9
Scoring filter	7
Binary Payload	4
HTML post	2
Too many newsgroups (low_xpost)	1
TOTAL: 12	2308

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	95
	csiph-out.feed.uzoreto.com	10
	csiph-out.news.xs4all.nl	10
	TOTAL: 3	115
Huge articles
	csiph-out.feed.uzoreto.com	230
	newsfeeds.xmission.com	99
	csiph-out.feeder.erje.net	70
	csiph-out.news.xs4all.nl	41
	news.freedyn.net	33
	csiph-out.news.weretis.net	31
	feeder.eternal-september.org	18
	newsfeed.fu-berlin.de	12
	news-out.mixmin.net	5
	news-out.neodome.net	2
	TOTAL: 11	542
Including strange strings
	newsfeed.fu-berlin.de	293
	news-out.mixmin.net	65
	news-out.aioe.org	24
	newsfeeds.xmission.com	19
	csiph-out.news.weretis.net	13
	news.swapon.de	12
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	2
	news-out.neodome.net	1
	csiph-out.feeder.erje.net	1
	TOTAL: 10	432
No colon-space in header
	csiph-out.news.xs4all.nl	2
	csiph-out.feed.uzoreto.com	2
	csiph-out.feeder.erje.net	1
	TOTAL: 3	5
TOTAL: 4		1094

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18830	1390	13775	28	191	7%	23:59:54
2	news.bbs.nz	28658	626	14143	17	1	2%	23:59:54
3	fu-berlin.de	14311	390	13921	0	0	2%	23:59:45
4	news.samoylyk.net	15764	374	14796	5	1	2%	23:59:50
5	tnet	16353	193	14600	1	0	1%	23:59:54
6	newsfeed.xs4all.nl	15156	60	14700	22	150	0%	23:59:54
7	news.freedyn.net	15312	52	15027	0	0	0%	23:59:54
8	xmission	12694	52	10896	3	0	0%	23:59:43
9	neva.ru	14062	27	13452	120	205	0%	23:59:54
10	usenet.pasdenom.info	15228	19	14610	4	1	0%	23:59:33
11	news.mixmin.net	11642	17	11375	0	0	0%	23:59:07
12	neodome.net	15383	14	14982	0	1	0%	23:59:27
13	aioe	11176	13	10721	0	0	0%	23:59:43
14	erje	14431	7	14227	104	0	0%	23:59:54
15	weretis.net	13660	7	13467	1	0	0%	23:59:54
16	eternal-september	12677	6	12285	1	0	0%	23:59:54
17	news.swapon.de	1434	4	1425	0	0	0%	23:44:32
18	news.uzoreto.com	15078	3	14772	13	1	0%	23:59:54
19	news.fcku.it	1065	0	1062	0	0	0%	20:32:28
TOTAL: 19		262914	3254	234236	319	551	1%	452:13:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.1 MB	102.6 KB	6.2 MB	0.1 KB/s	4.5 KB	23:59:54
2	fu-berlin.de	3.2 MB	0.0 KB	3.2 MB	0.0 KB/s	8.5 KB	23:59:45
3	news.samoylyk.net	3.2 MB	5.7 KB	3.2 MB	0.0 KB/s	8.6 KB	23:59:50
4	news.bbs.nz	2.0 MB	53.8 KB	2.0 MB	0.0 KB/s	3.2 KB	23:59:54
5	news.freedyn.net	676.1 KB	0.0 KB	676.1 KB	0.0 KB/s	13.0 KB	23:59:54
6	tnet	549.8 KB	1.3 KB	551.1 KB	0.0 KB/s	2.8 KB	23:59:54
7	newsfeed.xs4all.nl	145.2 KB	98.6 KB	243.8 KB	0.0 KB/s	3.0 KB	23:59:54
8	news.mixmin.net	143.4 KB	0.0 KB	143.4 KB	0.0 KB/s	8.4 KB	23:59:07
9	usenet.pasdenom.info	143.1 KB	8.5 KB	151.6 KB	0.0 KB/s	6.6 KB	23:59:33
10	xmission	122.7 KB	4.2 KB	126.9 KB	0.0 KB/s	2.3 KB	23:59:43
11	neva.ru	65.0 KB	373.7 KB	438.7 KB	0.0 KB/s	3.0 KB	23:59:54
12	aioe	33.8 KB	0.0 KB	33.8 KB	0.0 KB/s	2.6 KB	23:59:43
13	neodome.net	27.6 KB	0.0 KB	27.6 KB	0.0 KB/s	2.0 KB	23:59:27
14	weretis.net	18.3 KB	0.6 KB	18.9 KB	0.0 KB/s	2.4 KB	23:59:54
15	erje	16.8 KB	381.7 KB	398.5 KB	0.0 KB/s	3.6 KB	23:59:54
16	eternal-september	16.2 KB	17.1 KB	33.4 KB	0.0 KB/s	4.8 KB	23:59:54
17	news.swapon.de	7.7 KB	0.0 KB	7.7 KB	0.0 KB/s	1.9 KB	23:44:32
18	news.uzoreto.com	4.9 KB	113.0 KB	118.0 KB	0.0 KB/s	7.4 KB	23:59:54
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:32:28
TOTAL: 19		16.4 MB	1.1 MB	17.5 MB	0.0 KB/s	5.0 KB	452:13:08

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	047-035-184-231.res.spectrum.com	8	11	26.1 KB	7	3	2	02:14:29
2	94.66.65.45	2	10	19.6 KB	2	0	0	00:00:09
3	a131033.upc-a.chello.nl	23	9	20.9 KB	6	0	0	05:28:43
TOTAL: 3		33	30	66.6 KB	15	3	2	33:27:58

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	10	11	26.1 KB	7	3	2	02:18:32
2	unresolved	74	10	19.6 KB	2	0	0	23:40:35
3	*.upc-a.chello.nl	23	9	20.9 KB	6	0	0	05:28:43
4	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:07
TOTAL: 4		111	30	66.6 KB	15	3	2	33:27:58

NNRP auth users [Top 20]:

User	Conn
ohlandl	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.746	1.088	0.000	23:40:16
a131033.upc-a.chello.nl	1.744	0.311	0.000	05:28:43
047-035-184-231.res.spectrum.com	0.800	0.126	0.005	02:14:29
ip72-222-200-117.ph.ph.cox.net	0.437	0.062	0.000	02:00:07
172-221-041-102.res.spectrum.com	0.228	0.199	0.000	00:04:02
162.243.129.196	0.065	0.014	0.000	00:00:09
94.66.65.45	0.198	0.037	0.000	00:00:09
TOTAL: 7	8.218	1.837	0.005	33:27:58

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	71
ip72-222-200-117.ph.ph.cox.net	4
172-221-041-102.res.spectrum.com	2
162.243.129.196	1
TOTAL: 4	78

NNRP client timeouts [Top 20]:

System	Conn	Peer
047-035-184-231.res.spectrum.com	5	1
ip72-222-200-117.ph.ph.cox.net	4	0
TOTAL: 2	9	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Aug 20 04:15:00 -- Aug 21 04:15:00