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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20441	48.7%	5.2 MB	64.9%
innd	17814	42.5%	2.2 MB	27.5%
innfeed	3295	7.9%	593.0 KB	7.2%
nnrpd	361	0.9%	39.9 KB	0.5%
nocem	27	0.1%	2.6 KB	0.0%
TOTAL: 5	41938	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	297662	58.1%
Negative hits	194442	38.0%
Do not exist	19929	3.9%
Cache misses	317	0.1%
TOTAL: 4	512350	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.838	0.0%	63	0.000	13.302	188.000
article cleanup	00:00:00.127	0.0%	19037	0.000	0.007	0.029
article logging	00:00:00.433	0.0%	19445	0.000	0.022	0.326
article parse	00:00:02.525	0.0%	78279	0.000	0.032	0.335
article write	00:02:44.560	0.2%	15940	0.589	10.324	84.000
artlog/artcncl	00:00:00.001	0.0%	60	0.000	0.017	0.250
artlog/artparse	00:00:00.021	0.0%	947	0.000	0.022	1.000
data move	00:00:00.152	0.0%	499590	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.059	0.0%	63	0.000	0.937	7.833
hishave/artcncl	00:00:00.033	0.0%	63	0.000	0.524	4.833
hishave/artparse	00:00:00.001	0.0%	947	0.000	0.001	0.026
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:27.963	0.0%	512480	0.002	0.055	0.285
history sync	00:00:01.099	0.0%	385	0.000	2.855	80.000
history write	00:00:29.604	0.0%	19036	0.172	1.555	12.122
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.789	0.0%	947	0.000	1.889	34.800
idle	23:52:37.529	99.4%	494736	96.855	173.744	692.732
nntp read	00:00:02.312	0.0%	506441	0.001	0.005	0.015
overview write	00:03:19.452	0.2%	15940	0.625	12.513	111.481
perl filter	00:00:32.391	0.0%	19035	1.288	1.702	3.934
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.275	0.0%	31900	0.000	0.009	0.028
TOTAL: 24:00:56.447	24:00:21.164	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.054	0.0%	16173	0.000	0.003	0.029
article prepare	00:00:00.002	0.0%	14944	0.000	0.000	0.007
article read	00:00:02.908	0.0%	1184	0.000	2.456	40.000
backlog stats	00:00:00.221	0.0%	341083	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.438	0.0%	267000	0.001	0.005	0.013
data write	00:00:01.675	0.0%	249305	0.000	0.007	0.012
idle	23:55:10.809	100.0%	341083	147.822	252.463	1005.384
status file	00:00:01.383	0.0%	261	0.000	5.299	168.000
TOTAL: 23:55:26.990	23:55:18.490	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	13:43:56.750	100.0%	305	362.333	162087.705	600098.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	695	0.000	0.009	0.167
readart	00:00:00.297	0.0%	40	0.000	7.425	48.000
TOTAL: 13:44:08.151	13:43:57.053	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	32536	3397	28842	297	10%	49:16:36
2	feeder.eternal-september.org	2	18474	2088	16294	92	11%	26:23:48
3	newsfeeds.xmission.com	3	15015	2002	13005	8	13%	22:56:23
4	csiph-out.news.xs4all.nl	861	17703	1636	14753	1314	9%	71:26:22
5	news-out.aioe.org	2	15664	1420	14224	20	9%	24:09:01
6	csiph-out.feeder.erje.net	1440	76625	1182	75080	363	1%	118:52:52
7	news-out.mixmin.net	3	16447	1175	15056	216	7%	24:08:54
8	news.freedyn.net	2	19365	906	17434	1025	4%	23:59:20
9	newsfeed.neva.ru	146	21310	866	19974	470	4%	71:23:14
10	newsfeed.fu-berlin.de	12	14876	789	14014	73	5%	24:51:27
11	news.fcku.it	13	2272	314	1945	13	13%	21:50:22
12	csiph-out.feed.uzoreto.com	865	24369	171	24024	174	0%	71:11:50
13	nntp.pasdenom.info	2	11191	79	11103	9	0%	23:58:01
14	news.swapon.de	5	1497	31	1466	0	2%	23:30:31
15	news.samoylyk.net	1	15556	15	15541	0	0%	23:59:55
16	news.bbs.nz	1	14261	9	14252	0	0%	23:59:52
17	localhost	42	8	8	0	0	100%	00:00:02
18	xanadu-bbs.net	12	648	0	648	0	0%	22:06:21
TOTAL: 18		3417	317817	16088	297655	4074	5%	668:04:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	10.1 MB	0.0 KB	6.8 MB	16.9 MB	59%	4.7 KB
2	csiph-out.feeder.erje.net	8.4 MB	0.0 KB	11.5 MB	19.8 MB	42%	13.1 KB
3	feeder.eternal-september.org	5.5 MB	0.0 KB	1.3 MB	6.8 MB	81%	3.2 KB
4	newsfeeds.xmission.com	5.5 MB	0.0 KB	373.8 KB	5.8 MB	93%	3.0 KB
5	csiph-out.news.xs4all.nl	4.2 MB	0.0 KB	13.3 MB	17.5 MB	24%	6.1 KB
6	news-out.aioe.org	3.5 MB	0.0 KB	27.5 KB	3.5 MB	99%	2.5 KB
7	news-out.mixmin.net	3.3 MB	0.0 KB	546.6 KB	3.8 MB	86%	2.8 KB
8	news.freedyn.net	2.4 MB	0.0 KB	58.1 MB	60.5 MB	4%	32.1 KB
9	newsfeed.neva.ru	1.9 MB	3.0 KB	1.4 MB	3.2 MB	57%	2.5 KB
10	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	164.2 KB	2.0 MB	91%	2.4 KB
11	csiph-out.feed.uzoreto.com	644.6 KB	0.0 KB	3.5 MB	4.2 MB	15%	12.4 KB
12	news.fcku.it	471.8 KB	0.0 KB	15.5 KB	487.3 KB	96%	1.5 KB
13	nntp.pasdenom.info	204.4 KB	0.0 KB	12.3 KB	216.7 KB	94%	2.5 KB
14	news.swapon.de	78.7 KB	0.0 KB	0.0 KB	78.7 KB	100%	2.5 KB
15	news.samoylyk.net	55.5 KB	0.0 KB	0.0 KB	55.5 KB	100%	3.7 KB
16	news.bbs.nz	21.4 KB	0.0 KB	0.0 KB	21.4 KB	100%	2.4 KB
17	localhost	13.4 KB	0.0 KB	0.0 KB	13.4 KB	100%	1.7 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		48.1 MB	3.0 KB	96.9 MB	145.0 MB	33%	7.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 16 04:15:00 - 04:59:59	263	1.7%	0.10	1.0 MB	2.1%	0.39
Sep 16 05:00:00 - 05:59:59	302	1.9%	0.08	1.7 MB	3.5%	0.48
Sep 16 06:00:00 - 06:59:59	467	2.9%	0.13	1.6 MB	3.4%	0.46
Sep 16 07:00:00 - 07:59:59	696	4.4%	0.19	1.8 MB	3.8%	0.52
Sep 16 08:00:00 - 08:59:59	760	4.8%	0.21	2.0 MB	4.2%	0.58
Sep 16 09:00:00 - 09:59:59	694	4.4%	0.19	1.6 MB	3.4%	0.46
Sep 16 10:00:00 - 10:59:59	722	4.5%	0.20	2.1 MB	4.3%	0.58
Sep 16 11:00:00 - 11:59:59	652	4.1%	0.18	2.1 MB	4.4%	0.60
Sep 16 12:00:00 - 12:59:59	842	5.3%	0.23	2.3 MB	4.7%	0.64
Sep 16 13:00:00 - 13:59:59	969	6.1%	0.27	2.5 MB	5.2%	0.72
Sep 16 14:00:00 - 14:59:59	906	5.7%	0.25	2.9 MB	6.1%	0.83
Sep 16 15:00:00 - 15:59:59	932	5.8%	0.26	3.3 MB	6.8%	0.92
Sep 16 16:00:00 - 16:59:59	872	5.5%	0.24	2.5 MB	5.2%	0.71
Sep 16 17:00:00 - 17:59:59	873	5.5%	0.24	2.3 MB	4.7%	0.65
Sep 16 18:00:00 - 18:59:59	927	5.8%	0.26	2.6 MB	5.5%	0.75
Sep 16 19:00:00 - 19:59:59	930	5.8%	0.26	2.4 MB	5.0%	0.69
Sep 16 20:00:00 - 20:59:59	960	6.0%	0.27	3.0 MB	6.2%	0.85
Sep 16 21:00:00 - 21:59:59	683	4.3%	0.19	1.8 MB	3.7%	0.51
Sep 16 22:00:00 - 22:59:59	590	3.7%	0.16	2.0 MB	4.2%	0.58
Sep 16 23:00:00 - 23:59:59	490	3.1%	0.14	1.4 MB	2.9%	0.39
Sep 17 00:00:00 - 00:59:59	398	2.5%	0.11	1.2 MB	2.5%	0.34
Sep 17 01:00:00 - 01:59:59	389	2.4%	0.11	1.2 MB	2.5%	0.34
Sep 17 02:00:00 - 02:59:59	325	2.0%	0.09	1.5 MB	3.1%	0.42
Sep 17 03:00:00 - 03:59:59	215	1.3%	0.06	804.3 KB	1.6%	0.22
Sep 17 04:00:00 - 04:15:00	76	0.5%	0.08	353.0 KB	0.7%	0.39
TOTAL: 24:00:00	15933	100.0%	0.18	47.9 MB	100.0%	0.57

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1353	41	0	0	1312
2	news.freedyn.net	1015	3	0	0	1012
3	newsfeed.neva.ru	473	92	1	0	380
4	newsfeed.fu-berlin.de	382	19	0	0	363
5	csiph-out.feeder.erje.net	370	38	0	1	331
6	csiph-out.news.weretis.net	296	120	0	0	176
7	news-out.mixmin.net	237	0	0	0	237
8	csiph-out.feed.uzoreto.com	176	6	0	0	170
9	feeder.eternal-september.org	77	26	0	0	51
10	news-out.aioe.org	26	20	0	0	6
11	news.fcku.it	13	13	0	0	0
12	news.swapon.de	12	0	0	0	12
13	newsfeeds.xmission.com	10	3	0	0	7
14	nntp.pasdenom.info	9	0	0	0	9
TOTAL: 14		4449	381	1	1	4066

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	54
lada.talk	50
lada.auto	34
alt.survival	17
spline.eisfair	14
alt.binaries.pictures.purebeauty	14
mozilla.general	14
mozilla.support.thunderbird	13
mozilla.support.firefox	12
oesterreich.tratsch	9
ba.forsale	8
mozilla.dev.platform	8
alt.politics.usa	7
mozilla.test	7
alt.bbs.synchronet	7
nyc.forsale	6
mozilla.dev.security.policy	6
alt.math.undergrad	5
alt.computer.workshop	4
alt.music.pink-floyd.publius	4
TOTAL: 82	381

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

Groups	Count
uk.radio.amateur.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2311
EMP (phn path)	141
EMP (phl)	67
EMP (phn nph)	57
Binary: misplaced rar	18
Too many newsgroups	17
Binary Payload	17
HTML Multipart	13
Scoring filter (10)	11
User-issued cancel	10
Scoring filter (12)	8
Scoring filter (8)	8
Too many newsgroups (low_xpost)	7
User-issued spam cancel	7
Scoring filter (13)	6
Scoring filter (9)	5
Scoring filter (15)	3
Scoring filter (17)	2
Too many newsgroups (meow)	1
Scoring filter (11)	1
TOTAL: 21	2711

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	17	0	19	19
TOTAL: 1	17	3	19	19

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	csiph-out.news.xs4all.nl	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	52
Huge articles
	news.freedyn.net	455
	csiph-out.feeder.erje.net	206
	csiph-out.news.xs4all.nl	131
	csiph-out.news.weretis.net	92
	csiph-out.feed.uzoreto.com	44
	feeder.eternal-september.org	8
	newsfeeds.xmission.com	6
	news-out.mixmin.net	3
	TOTAL: 8	945
Including strange strings
	newsfeed.fu-berlin.de	313
	csiph-out.news.xs4all.nl	39
	csiph-out.news.weretis.net	14
	news.swapon.de	12
	news-out.mixmin.net	11
	csiph-out.feeder.erje.net	9
	news-out.aioe.org	6
	newsfeed.neva.ru	2
	csiph-out.feed.uzoreto.com	2
	newsfeeds.xmission.com	1
	TOTAL: 10	409
TOTAL: 4		1407

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17953	978	14953	0	181	5%	23:59:55
2	news.freedyn.net	15375	467	14531	10	0	3%	23:59:55
3	fu-berlin.de	14961	457	14502	0	0	3%	23:59:34
4	news.samoylyk.net	16496	456	15425	0	0	2%	23:52:55
5	news.bbs.nz	29593	324	15147	3	0	1%	23:59:54
6	usenet.pasdenom.info	16239	126	15257	467	13	0%	23:59:55
7	newsfeed.xs4all.nl	14745	35	13786	3	67	0%	23:56:54
8	aioe	14057	32	13529	0	0	0%	23:59:31
9	neva.ru	14744	27	14205	180	0	0%	23:59:55
10	news.uzoreto.com	15577	13	15204	79	0	0%	23:58:28
11	xmission	16798	13	13369	0	0	0%	23:59:55
12	weretis.net	12239	10	12038	4	0	0%	23:59:55
13	news.mixmin.net	14544	10	14136	0	0	0%	23:59:45
14	news.swapon.de	1689	9	1645	0	0	0%	23:56:49
15	erje	14949	9	14624	53	0	0%	23:59:55
16	eternal-september	13819	8	13377	0	0	0%	23:59:54
17	news.redatomik.org	0	0	0	0	15476	0%	23:59:55
18	news.fcku.it	1831	0	1827	0	0	0%	21:47:25
TOTAL: 18		245609	2974	217555	799	15737	1%	429:30:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.3 MB	0.0 KB	7.3 MB	0.1 KB/s	7.6 KB	23:59:55
2	news.freedyn.net	6.0 MB	18.0 KB	6.1 MB	0.1 KB/s	13.0 KB	23:59:55
3	fu-berlin.de	6.0 MB	0.0 KB	6.0 MB	0.1 KB/s	13.5 KB	23:59:34
4	news.samoylyk.net	5.9 MB	0.0 KB	5.9 MB	0.1 KB/s	13.2 KB	23:52:55
5	news.bbs.nz	751.7 KB	4.8 KB	756.5 KB	0.0 KB/s	2.3 KB	23:59:54
6	usenet.pasdenom.info	292.4 KB	6.1 MB	6.4 MB	0.1 KB/s	11.0 KB	23:59:55
7	newsfeed.xs4all.nl	107.5 KB	10.2 KB	117.7 KB	0.0 KB/s	3.1 KB	23:56:54
8	aioe	62.9 KB	0.0 KB	62.9 KB	0.0 KB/s	2.0 KB	23:59:31
9	neva.ru	56.3 KB	376.2 KB	432.5 KB	0.0 KB/s	2.1 KB	23:59:55
10	news.uzoreto.com	33.0 KB	200.2 KB	233.2 KB	0.0 KB/s	2.5 KB	23:58:28
11	xmission	27.3 KB	0.0 KB	27.3 KB	0.0 KB/s	2.1 KB	23:59:55
12	erje	22.7 KB	147.5 KB	170.2 KB	0.0 KB/s	2.7 KB	23:59:55
13	weretis.net	20.2 KB	4.8 KB	25.1 KB	0.0 KB/s	1.8 KB	23:59:55
14	news.mixmin.net	16.9 KB	0.0 KB	16.9 KB	0.0 KB/s	1.7 KB	23:59:45
15	news.swapon.de	15.3 KB	0.0 KB	15.3 KB	0.0 KB/s	1.7 KB	23:56:49
16	eternal-september	13.4 KB	0.0 KB	13.4 KB	0.0 KB/s	1.7 KB	23:59:54
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:47:25
TOTAL: 18		26.6 MB	6.8 MB	33.5 MB	0.0 KB/s	9.1 KB	429:30:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	23	30	67.1 KB	2	0	0	05:30:00
2	71-13-20-23.dhcp.dlth.mn.charter.com	24	10	17.7 KB	7	8	7	07:13:56
TOTAL: 2		47	40	84.8 KB	9	8	7	13:44:07

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	23	30	67.1 KB	2	0	0	05:30:00
2	*.dhcp.dlth.mn.charter.com	24	10	17.7 KB	7	8	7	07:13:56
3	*.ph.ph.cox.net	2	0	0.0 KB	0	0	0	01:00:07
4	unresolved	2	0	0.0 KB	0	0	0	00:00:02
TOTAL: 4		51	40	84.8 KB	9	8	7	13:44:07

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
TOTAL: 1	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-13-20-23.dhcp.dlth.mn.charter.com	2.120	0.347	0.018	07:13:56
a131033.upc-a.chello.nl	1.636	0.361	0.000	05:30:00
ip72-222-200-117.ph.ph.cox.net	0.235	0.033	0.000	01:00:07
5.87.21.70	0.061	0.023	0.000	00:00:01
94.162.159.235	0.070	0.008	0.000	00:00:01
TOTAL: 5	4.122	0.772	0.018	13:44:07

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	2
94.162.159.235	1
5.87.21.70	1
TOTAL: 3	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-13-20-23.dhcp.dlth.mn.charter.com	9	0
ip72-222-200-117.ph.ph.cox.net	2	0
TOTAL: 2	11	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 16 04:15:00 -- Sep 17 04:15:00