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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22669	49.9%	5.7 MB	65.6%
innd	18611	41.0%	2.3 MB	27.0%
innfeed	3444	7.6%	574.4 KB	6.5%
nnrpd	636	1.4%	74.3 KB	0.8%
nocem	85	0.2%	10.1 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	45446	100.0%	8.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	313976	57.6%
Negative hits	208988	38.3%
Do not exist	21846	4.0%
Cache misses	364	0.1%
TOTAL: 4	545174	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.928	0.0%	511	0.000	1.816	18.667
article cleanup	00:00:00.120	0.0%	21098	0.000	0.006	0.034
article logging	00:00:00.370	0.0%	21587	0.000	0.017	0.068
article parse	00:00:01.119	0.0%	76028	0.000	0.015	0.133
article write	00:00:35.006	0.0%	17086	0.295	2.049	34.044
artlog/artcncl	00:00:00.009	0.0%	477	0.000	0.019	1.000
artlog/artparse	00:00:00.010	0.0%	630	0.000	0.016	1.000
data move	00:00:00.131	0.0%	520280	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.012	0.0%	210	0.000	0.057	1.000
hishave/artcncl	00:00:00.060	0.0%	511	0.000	0.117	1.000
hishave/artparse	00:00:00.004	0.0%	630	0.000	0.006	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.470	0.0%	547657	0.001	0.010	0.018
history sync	00:00:00.740	0.0%	379	0.000	1.953	19.333
history write	00:00:11.554	0.0%	21094	0.225	0.548	4.120
hiswrite/artcncl	00:00:00.193	0.0%	301	0.000	0.641	1.000
hiswrite/artparse	00:00:00.438	0.0%	630	0.000	0.695	9.167
idle	23:57:28.531	99.8%	529724	89.682	162.818	461.115
nntp read	00:00:02.229	0.0%	531089	0.002	0.004	0.009
overview write	00:01:21.270	0.1%	17086	0.146	4.757	404.357
perl filter	00:00:38.333	0.0%	21090	1.257	1.818	4.729
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.302	0.0%	34250	0.000	0.009	0.035
TOTAL: 24:00:59.801	24:00:26.829	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	17530	0.000	0.003	0.020
article prepare	00:00:00.000	0.0%	19224	0.000	0.000	0.000
article read	00:00:01.439	0.0%	1897	0.000	0.759	43.667
backlog stats	00:00:00.219	0.0%	375176	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.584	0.0%	284746	0.002	0.006	0.013
data write	00:00:01.804	0.0%	271457	0.001	0.007	0.014
idle	23:54:45.778	100.0%	375176	80.189	229.454	718.205
status file	00:00:00.302	0.0%	334	0.000	0.904	33.000
TOTAL: 23:54:58.549	23:54:51.172	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	20:57:23.381	100.0%	503	361.000	149986.841	600107.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.009	0.0%	1182	0.000	0.008	0.125
readart	00:00:00.672	0.0%	53	0.000	12.679	48.000
TOTAL: 20:57:40.180	20:57:24.062	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	79309	3002	75947	360	3%	118:55:24
2	csiph-out.news.weretis.net	4	34650	2390	31942	318	6%	48:58:15
3	feeder.eternal-september.org	8	17891	2141	15656	94	11%	23:00:59
4	csiph-out.news.xs4all.nl	863	19179	1855	15675	1649	9%	71:27:41
5	news-out.aioe.org	3	16656	1464	15176	16	8%	24:06:12
6	newsfeeds.xmission.com	2	13979	1312	12664	3	9%	19:00:24
7	news.freedyn.net	3	20759	1209	18606	944	5%	24:08:59
8	news-out.mixmin.net	1	17528	962	16386	180	5%	23:59:50
9	newsfeed.fu-berlin.de	13	15678	882	14684	112	5%	24:41:02
10	newsfeed.neva.ru	148	21338	851	19806	681	3%	71:03:29
11	csiph-out.feed.uzoreto.com	864	27009	326	26436	247	1%	71:11:35
12	news.fcku.it	12	1817	226	1590	1	12%	21:50:41
13	nntp.pasdenom.info	3	11877	103	11759	15	0%	23:57:36
14	news.swapon.de	3	1579	26	1552	1	1%	22:34:35
15	news.samoylyk.net	2	16592	25	16566	1	0%	24:54:22
16	localhost	44	11	11	0	0	100%	00:00:04
17	news.bbs.nz	2	15209	9	15200	0	0%	23:59:52
18	xanadu-bbs.net	29	400	0	400	0	0%	22:04:00
TOTAL: 18		3444	331461	16794	310045	4622	5%	659:55:00

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	13.6 MB	0.0 KB	11.1 MB	24.7 MB	55%	7.5 KB
2	csiph-out.news.weretis.net	7.9 MB	2.1 KB	3.5 MB	11.4 MB	69%	4.3 KB
3	feeder.eternal-september.org	5.8 MB	0.0 KB	1.2 MB	7.0 MB	82%	3.2 KB
4	csiph-out.news.xs4all.nl	5.4 MB	2.2 KB	10.2 MB	15.5 MB	34%	4.5 KB
5	news-out.aioe.org	3.8 MB	0.0 KB	25.5 KB	3.8 MB	99%	2.7 KB
6	newsfeeds.xmission.com	3.7 MB	0.0 KB	54.8 KB	3.8 MB	98%	2.9 KB
7	news.freedyn.net	3.4 MB	4.6 KB	45.5 MB	48.9 MB	6%	23.2 KB
8	news-out.mixmin.net	2.7 MB	0.0 KB	366.6 KB	3.1 MB	88%	2.8 KB
9	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	368.1 KB	2.4 MB	85%	2.5 KB
10	newsfeed.neva.ru	2.0 MB	0.0 KB	2.0 MB	4.0 MB	49%	2.7 KB
11	csiph-out.feed.uzoreto.com	932.9 KB	0.0 KB	2.3 MB	3.3 MB	27%	5.8 KB
12	news.fcku.it	403.1 KB	0.0 KB	1.1 KB	404.3 KB	99%	1.8 KB
13	nntp.pasdenom.info	238.0 KB	0.0 KB	27.7 KB	265.8 KB	89%	2.3 KB
14	news.samoylyk.net	85.5 KB	0.0 KB	261.1 KB	346.7 KB	24%	13.3 KB
15	news.swapon.de	57.1 KB	0.0 KB	16.8 KB	74.0 KB	77%	2.7 KB
16	news.bbs.nz	20.2 KB	0.0 KB	0.0 KB	20.2 KB	100%	2.2 KB
17	localhost	12.9 KB	0.0 KB	0.0 KB	12.9 KB	100%	1.2 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		52.1 MB	9.0 KB	76.9 MB	129.1 MB	40%	6.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 9 04:15:00 - 04:59:59	299	1.8%	0.11	1.1 MB	2.0%	0.40
Sep 9 05:00:00 - 05:59:59	692	4.1%	0.19	3.1 MB	5.9%	0.89
Sep 9 06:00:00 - 06:59:59	480	2.8%	0.13	1.9 MB	3.6%	0.54
Sep 9 07:00:00 - 07:59:59	579	3.4%	0.16	1.6 MB	3.1%	0.46
Sep 9 08:00:00 - 08:59:59	706	4.1%	0.20	2.2 MB	4.2%	0.63
Sep 9 09:00:00 - 09:59:59	769	4.5%	0.21	2.2 MB	4.2%	0.63
Sep 9 10:00:00 - 10:59:59	699	4.1%	0.19	2.1 MB	4.1%	0.61
Sep 9 11:00:00 - 11:59:59	702	4.1%	0.20	1.7 MB	3.2%	0.48
Sep 9 12:00:00 - 12:59:59	872	5.1%	0.24	2.4 MB	4.6%	0.69
Sep 9 13:00:00 - 13:59:59	884	5.2%	0.25	2.3 MB	4.3%	0.65
Sep 9 14:00:00 - 14:59:59	989	5.8%	0.27	3.2 MB	6.1%	0.92
Sep 9 15:00:00 - 15:59:59	1017	6.0%	0.28	2.9 MB	5.5%	0.83
Sep 9 16:00:00 - 16:59:59	1014	5.9%	0.28	3.0 MB	5.7%	0.86
Sep 9 17:00:00 - 17:59:59	841	4.9%	0.23	2.4 MB	4.6%	0.69
Sep 9 18:00:00 - 18:59:59	1008	5.9%	0.28	3.0 MB	5.6%	0.84
Sep 9 19:00:00 - 19:59:59	905	5.3%	0.25	2.4 MB	4.6%	0.69
Sep 9 20:00:00 - 20:59:59	916	5.4%	0.25	2.6 MB	5.0%	0.75
Sep 9 21:00:00 - 21:59:59	823	4.8%	0.23	2.5 MB	4.8%	0.72
Sep 9 22:00:00 - 22:59:59	690	4.0%	0.19	2.2 MB	4.2%	0.63
Sep 9 23:00:00 - 23:59:59	527	3.1%	0.15	1.9 MB	3.7%	0.55
Sep 10 00:00:00 - 00:59:59	372	2.2%	0.10	1.2 MB	2.2%	0.33
Sep 10 01:00:00 - 01:59:59	420	2.5%	0.12	1.3 MB	2.4%	0.36
Sep 10 02:00:00 - 02:59:59	337	2.0%	0.09	1.3 MB	2.5%	0.38
Sep 10 03:00:00 - 03:59:59	415	2.4%	0.12	1.4 MB	2.7%	0.40
Sep 10 04:00:00 - 04:15:00	117	0.7%	0.13	637.9 KB	1.2%	0.71
TOTAL: 24:00:00	17073	100.0%	0.20	52.8 MB	100.0%	0.63

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1705	32	1	1672
2	news.freedyn.net	957	6	2	949
3	newsfeed.neva.ru	686	102	0	584
4	newsfeed.fu-berlin.de	534	35	0	499
5	csiph-out.feeder.erje.net	361	67	0	294
6	csiph-out.news.weretis.net	303	59	1	243
7	csiph-out.feed.uzoreto.com	248	9	0	239
8	news-out.mixmin.net	182	2	0	180
9	feeder.eternal-september.org	102	20	0	82
10	news-out.aioe.org	21	13	0	8
11	nntp.pasdenom.info	15	0	0	15
12	newsfeeds.xmission.com	4	0	0	4
13	news.swapon.de	2	0	0	2
14	news.fcku.it	1	0	0	1
15	news.samoylyk.net	1	0	0	1
TOTAL: 15		5122	345	4	4773

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	72
spline.eisfair	38
alt.binaries.pictures.purebeauty	28
alt.windows7.general	18
lada.auto	14
alt.survival	9
rocksolid.nodes	9
oesterreich.tratsch	9
mozilla.support.thunderbird	9
alt.politics.usa	8
alt.computer.workshop	7
alt.bbs.synchronet	7
mozilla.general	7
mozilla.support.calendar	7
alt.tv.star-trek	5
alt.religion	4
mozilla.dev.platform	4
alt.music.pink-floyd.publius	4
lada.commerce	4
0	4
TOTAL: 77	345

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3134
EMP (phn path)	212
EMP (phl)	105
EMP (phn nph)	47
Too many newsgroups	40
User-issued cancel	26
HTML Multipart	24
Binary: misplaced rar	15
EMP (fsl)	12
Binary Payload	11
User-issued spam cancel	10
Scoring filter (10)	7
Scoring filter (13)	6
Scoring filter (9)	5
Scoring filter (14)	3
Scoring filter (8)	2
Scoring filter (12)	1
HTML post	1
TOTAL: 18	3661

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	69	0	91	91
TOTAL: 1	69	9	91	91

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	3
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	353
	csiph-out.feeder.erje.net	199
	csiph-out.news.weretis.net	24
	csiph-out.news.xs4all.nl	16
	feeder.eternal-september.org	16
	csiph-out.feed.uzoreto.com	4
	newsfeed.fu-berlin.de	2
	newsfeeds.xmission.com	2
	news.samoylyk.net	1
	news-out.mixmin.net	1
	TOTAL: 10	618
Including strange strings
	newsfeed.fu-berlin.de	410
	csiph-out.news.xs4all.nl	50
	csiph-out.news.weretis.net	10
	news-out.mixmin.net	6
	news-out.aioe.org	5
	csiph-out.feeder.erje.net	3
	news.freedyn.net	2
	csiph-out.feed.uzoreto.com	2
	news.swapon.de	1
	feeder.eternal-september.org	1
	TOTAL: 10	490
TOTAL: 4		1156

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	20816	919	16151	0	0	96	4%	23:59:55
2	news.freedyn.net	16226	525	15241	11	0	0	3%	23:59:39
3	news.samoylyk.net	18103	490	16556	1	0	62	2%	23:59:55
4	fu-berlin.de	15971	478	15492	0	0	0	2%	23:59:54
5	erje	16098	469	13050	307	0	0	2%	23:59:55
6	news.bbs.nz	32410	360	16226	3	0	0	1%	23:59:52
7	usenet.pasdenom.info	17965	234	16131	604	2	117	1%	23:59:55
8	aioe	14957	38	14315	0	0	63	0%	23:59:53
9	newsfeed.xs4all.nl	15613	29	14687	7	0	43	0%	23:59:55
10	neva.ru	15794	28	15305	186	0	0	0%	23:59:55
11	news.uzoreto.com	16567	25	16181	83	0	0	0%	23:59:55
12	xmission	18749	13	15155	0	0	0	0%	23:59:52
13	news.mixmin.net	16060	13	15558	0	0	0	0%	23:59:33
14	weretis.net	15199	12	14028	3	0	0	0%	23:59:55
15	news.swapon.de	1706	12	1660	0	0	11	0%	23:56:19
16	eternal-september	14737	11	14131	0	0	94	0%	23:59:55
17	news.redatomik.org	0	0	0	0	0	16592	0%	23:59:55
18	news.fcku.it	1452	0	1442	0	0	0	0%	21:36:31
TOTAL: 18		268423	3656	231309	1205	2	17078	1%	429:30:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.0 MB	0.0 KB	7.0 MB	0.1 KB/s	7.8 KB	23:59:55
2	news.freedyn.net	6.1 MB	34.4 KB	6.1 MB	0.1 KB/s	11.7 KB	23:59:39
3	news.samoylyk.net	5.9 MB	1.4 KB	5.9 MB	0.1 KB/s	12.4 KB	23:59:55
4	fu-berlin.de	5.9 MB	0.0 KB	5.9 MB	0.1 KB/s	12.6 KB	23:59:54
5	erje	1.2 MB	1.4 MB	2.6 MB	0.0 KB/s	3.5 KB	23:59:55
6	news.bbs.nz	829.1 KB	7.7 KB	836.8 KB	0.0 KB/s	2.3 KB	23:59:52
7	usenet.pasdenom.info	670.0 KB	6.3 MB	7.0 MB	0.1 KB/s	8.5 KB	23:59:55
8	aioe	89.3 KB	0.0 KB	89.3 KB	0.0 KB/s	2.3 KB	23:59:53
9	newsfeed.xs4all.nl	72.8 KB	31.6 KB	104.4 KB	0.0 KB/s	2.9 KB	23:59:55
10	neva.ru	49.9 KB	424.4 KB	474.3 KB	0.0 KB/s	2.2 KB	23:59:55
11	news.uzoreto.com	44.7 KB	296.8 KB	341.5 KB	0.0 KB/s	3.2 KB	23:59:55
12	news.swapon.de	33.0 KB	0.0 KB	33.0 KB	0.0 KB/s	2.7 KB	23:56:19
13	xmission	18.3 KB	0.0 KB	18.3 KB	0.0 KB/s	1.4 KB	23:59:52
14	news.mixmin.net	15.5 KB	0.0 KB	15.5 KB	0.0 KB/s	1.2 KB	23:59:33
15	weretis.net	15.1 KB	4.5 KB	19.6 KB	0.0 KB/s	1.3 KB	23:59:55
16	eternal-september	12.9 KB	0.0 KB	12.9 KB	0.0 KB/s	1.2 KB	23:59:55
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:36:31
TOTAL: 18		27.9 MB	8.5 MB	36.4 MB	0.0 KB/s	7.7 KB	429:30:43

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	37	22	32.9 KB	6	0	0	08:03:30
2	68-190-164-204.dhcp.eucl.wi.charter.com	33	19	27.1 KB	15	11	9	09:41:53
3	ip72-222-200-117.ph.ph.cox.net	7	12	16.9 KB	2	0	0	03:11:54
TOTAL: 3		77	53	76.9 KB	23	11	9	20:57:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	37	22	32.9 KB	6	0	0	08:03:30
2	*.dhcp.eucl.wi.charter.com	33	19	27.1 KB	15	11	9	09:41:53
3	*.ph.ph.cox.net	7	12	16.9 KB	2	0	0	03:11:54
4	unresolved	10	0	0.0 KB	0	0	0	00:00:21
TOTAL: 4		87	53	76.9 KB	23	11	9	20:57:39

NNRP auth users [Top 20]:

User	Conn
ohlandl	9
TOTAL: 1	9

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
68-190-164-204.dhcp.eucl.wi.charter.com	3.076	0.560	0.028	09:41:53
a131033.upc-a.chello.nl	2.876	0.492	0.000	08:03:30
ip72-222-200-117.ph.ph.cox.net	0.833	0.094	0.000	03:11:54
138.68.208.116	0.073	0.008	0.000	00:00:09
94.167.97.22	0.253	0.070	0.000	00:00:05
5.87.53.90	0.135	0.024	0.000	00:00:02
5.87.46.213	0.079	0.000	0.000	00:00:01
5.87.40.191	0.082	0.000	0.000	00:00:01
5.87.59.124	0.056	0.024	0.000	00:00:01
TOTAL: 9	7.463	1.272	0.028	20:57:39

Curious NNRP server explorers [Top 20]:

System	Conn
94.167.97.22	4
5.87.53.90	2
138.68.208.116	1
5.87.40.191	1
5.87.59.124	1
5.87.46.213	1
TOTAL: 6	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-190-164-204.dhcp.eucl.wi.charter.com	13	0
ip72-222-200-117.ph.ph.cox.net	5	0
TOTAL: 2	18	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 9 04:15:00 -- Sep 10 04:15:00