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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	44081	60.4%	8.1 MB	71.3%
innd	24547	33.6%	2.6 MB	22.8%
innfeed	3241	4.4%	562.5 KB	4.8%
nnrpd	1060	1.5%	121.6 KB	1.0%
nocem	50	0.1%	5.1 KB	0.0%
controlchan	5	0.0%	0.6 KB	0.0%
TOTAL: 6	72984	100.0%	11.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	432079	55.3%
Negative hits	305291	39.1%
Do not exist	42922	5.5%
Cache misses	645	0.1%
TOTAL: 4	780937	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.788	0.0%	204	0.000	3.863	56.000
article cleanup	00:00:00.287	0.0%	42501	0.000	0.007	0.028
article logging	00:00:00.527	0.0%	43041	0.000	0.012	0.123
article parse	00:00:02.718	0.0%	210298	0.002	0.013	0.027
article write	00:06:22.716	0.4%	23171	0.232	16.517	49.995
artlog/artcncl	00:00:00.004	0.0%	199	0.000	0.020	0.143
artlog/artparse	00:00:00.009	0.0%	860	0.000	0.010	1.000
data move	00:00:00.331	0.0%	776483	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.014	0.0%	201	0.000	0.070	1.000
hishave/artcncl	00:00:00.012	0.0%	204	0.000	0.059	1.000
hishave/artparse	00:00:00.006	0.0%	859	0.000	0.007	0.014
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.378	0.0%	783078	0.001	0.007	0.064
history sync	00:00:01.106	0.0%	354	0.000	3.124	17.667
history write	00:00:25.232	0.0%	42269	0.102	0.597	4.488
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hiswrite/artparse	00:00:00.450	0.0%	802	0.000	0.561	3.000
idle	23:49:08.754	99.2%	786889	16.210	108.972	297.992
nntp read	00:00:02.722	0.0%	805622	0.002	0.003	0.006
overview write	00:01:43.200	0.1%	23171	0.160	4.454	111.643
perl filter	00:01:59.974	0.1%	42269	1.771	2.838	5.007
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.423	0.0%	46390	0.000	0.009	0.032
TOTAL: 24:00:46.781	23:59:54.651	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.088	0.0%	23227	0.000	0.004	0.019
article prepare	00:00:00.004	0.0%	28854	0.000	0.000	0.012
article read	00:00:03.031	0.0%	4449	0.000	0.681	8.800
backlog stats	00:00:00.239	0.0%	448419	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.840	0.0%	335573	0.002	0.005	0.013
data write	00:00:02.278	0.0%	318476	0.004	0.007	0.013
idle	23:54:18.294	100.0%	448419	112.457	191.915	696.889
status file	00:00:00.749	0.0%	304	0.000	2.464	27.833
TOTAL: 23:54:34.362	23:54:26.523	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.504	0.0%	33951	0.000	0.074	1.071
idle	21:03:19.163	99.9%	34425	161.326	2201.864	600017.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.371	0.0%	69006	0.000	0.005	0.167
readart	00:00:08.392	0.0%	2624	0.000	3.198	68.000
TOTAL: 21:04:11.583	21:03:30.430	99.9%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
usenet@film.rlss.okayama-u.ac.jp	0	0	1	0	1	1
TOTAL	0	0	1	0	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1150	130675	7258	115372	8045	5%	94:56:30
2	csiph-out.news.weretis.net	4	55610	4509	49444	1657	8%	51:16:38
3	feeder.eternal-september.org	9	38636	2516	34193	1927	6%	25:54:53
4	news-out.aioe.org	3	22786	2180	20577	29	9%	24:09:37
5	news-out.mixmin.net	3	25827	1895	23402	530	7%	24:08:31
6	newsfeed.neva.ru	147	53882	1253	45207	7422	2%	72:18:26
7	newsfeed.fu-berlin.de	48	24345	1146	22892	307	4%	30:44:04
8	newsfeeds.xmission.com	0	22368	998	21361	9	4%	22:26:24
9	news.redatomik.org	1	24180	718	23453	9	2%	27:24:47
10	news.fcku.it	2	10026	481	9467	78	4%	23:59:48
11	xanadu-bbs.net	9	1145	197	939	9	17%	23:16:37
12	news.swapon.de	1	2168	54	2112	2	2%	25:53:06
13	news.freedyn.net	2	26082	42	25978	62	0%	24:01:59
14	nntp.pasdenom.info	1	23093	13	23080	0	0%	23:59:56
15	news.bbs.geek.nz	1	22206	3	22196	7	0%	23:59:53
16	localhost	33	1	1	0	0	100%	00:00:00
TOTAL: 16		1414	483030	23264	439673	20093	4%	518:31:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	22.7 MB	1.5 MB	43.3 MB	67.6 MB	33%	4.5 KB
2	csiph-out.news.weretis.net	14.2 MB	2.5 KB	55.5 MB	69.8 MB	20%	11.6 KB
3	news-out.mixmin.net	6.8 MB	31.4 KB	1.6 MB	8.5 MB	80%	3.6 KB
4	feeder.eternal-september.org	6.8 MB	379.3 KB	77.8 MB	85.0 MB	7%	19.6 KB
5	news-out.aioe.org	5.2 MB	0.0 KB	74.8 KB	5.2 MB	98%	2.4 KB
6	newsfeed.neva.ru	3.2 MB	802.4 KB	74.7 MB	78.7 MB	4%	9.3 KB
7	newsfeeds.xmission.com	3.0 MB	0.0 KB	497.2 KB	3.5 MB	86%	3.5 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	712.1 KB	3.1 MB	77%	2.2 KB
9	news.redatomik.org	2.2 MB	0.0 KB	350.6 KB	2.5 MB	86%	3.5 KB
10	news.fcku.it	998.2 KB	0.0 KB	1.3 MB	2.2 MB	43%	4.1 KB
11	xanadu-bbs.net	524.8 KB	0.0 KB	134.3 KB	659.1 KB	79%	3.2 KB
12	news.freedyn.net	229.8 KB	0.0 KB	2.0 MB	2.2 MB	10%	21.9 KB
13	news.swapon.de	151.1 KB	0.0 KB	12.1 KB	163.2 KB	92%	2.9 KB
14	nntp.pasdenom.info	30.1 KB	0.0 KB	0.0 KB	30.1 KB	100%	2.3 KB
15	news.bbs.geek.nz	4.5 KB	0.0 KB	1.0 MB	1.0 MB	0%	105.6 KB
16	localhost	3.1 KB	0.0 KB	0.0 KB	3.1 KB	100%	3.1 KB
TOTAL: 16		68.4 MB	2.7 MB	259.1 MB	330.2 MB	20%	7.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 30 04:15:00 - 04:59:59	433	1.9%	0.16	1.6 MB	2.3%	0.59
Sep 30 05:00:00 - 05:59:59	591	2.6%	0.16	2.4 MB	3.5%	0.68
Sep 30 06:00:00 - 06:59:59	721	3.1%	0.20	2.4 MB	3.5%	0.68
Sep 30 07:00:00 - 07:59:59	1063	4.6%	0.30	3.0 MB	4.4%	0.85
Sep 30 08:00:00 - 08:59:59	1086	4.7%	0.30	3.0 MB	4.4%	0.86
Sep 30 09:00:00 - 09:59:59	1115	4.8%	0.31	2.9 MB	4.2%	0.81
Sep 30 10:00:00 - 10:59:59	991	4.3%	0.28	2.5 MB	3.8%	0.73
Sep 30 11:00:00 - 11:59:59	977	4.2%	0.27	2.6 MB	3.9%	0.74
Sep 30 12:00:00 - 12:59:59	1084	4.7%	0.30	2.9 MB	4.3%	0.82
Sep 30 13:00:00 - 13:59:59	1230	5.3%	0.34	3.6 MB	5.3%	1.01
Sep 30 14:00:00 - 14:59:59	1357	5.9%	0.38	3.9 MB	5.7%	1.10
Sep 30 15:00:00 - 15:59:59	1506	6.5%	0.42	4.3 MB	6.4%	1.23
Sep 30 16:00:00 - 16:59:59	1298	5.6%	0.36	3.6 MB	5.3%	1.02
Sep 30 17:00:00 - 17:59:59	1247	5.4%	0.35	3.6 MB	5.3%	1.01
Sep 30 18:00:00 - 18:59:59	1368	5.9%	0.38	4.5 MB	6.7%	1.29
Sep 30 19:00:00 - 19:59:59	1242	5.4%	0.34	3.7 MB	5.5%	1.07
Sep 30 20:00:00 - 20:59:59	1201	5.2%	0.33	3.2 MB	4.7%	0.90
Sep 30 21:00:00 - 21:59:59	1067	4.6%	0.30	3.2 MB	4.7%	0.90
Sep 30 22:00:00 - 22:59:59	855	3.7%	0.24	2.8 MB	4.1%	0.79
Sep 30 23:00:00 - 23:59:59	744	3.2%	0.21	2.1 MB	3.1%	0.61
Oct 1 00:00:00 - 00:59:59	578	2.5%	0.16	1.7 MB	2.5%	0.48
Oct 1 01:00:00 - 01:59:59	474	2.0%	0.13	1.5 MB	2.3%	0.44
Oct 1 02:00:00 - 02:59:59	447	1.9%	0.12	1.3 MB	2.0%	0.38
Oct 1 03:00:00 - 03:59:59	394	1.7%	0.11	1.3 MB	2.0%	0.38
Oct 1 04:00:00 - 04:15:00	82	0.4%	0.09	266.9 KB	0.4%	0.30
TOTAL: 24:00:00	23151	100.0%	0.27	67.8 MB	100.0%	0.80

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	8098	464	136	1	7497
2	newsfeed.neva.ru	7367	834	64	0	6469
3	feeder.eternal-september.org	2009	72	30	0	1907
4	csiph-out.news.weretis.net	1656	229	1	0	1426
5	newsfeed.fu-berlin.de	711	23	0	0	688
6	news-out.mixmin.net	572	4	1	0	567
7	news.fcku.it	90	0	0	0	90
8	news.bbs.geek.nz	75	0	0	0	75
9	news.freedyn.net	63	1	0	0	62
10	news-out.aioe.org	46	27	0	0	19
11	news.redatomik.org	21	0	0	0	21
12	newsfeeds.xmission.com	14	1	0	0	13
13	xanadu-bbs.net	10	0	0	0	10
14	news.swapon.de	3	0	0	0	3
TOTAL: 14		20735	1655	232	1	18847

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	466
lada.talk	239
fa.freebsd.cvs-all	84
lada.auto	73
ut.test	68
alt.survival	63
it-alt.sport.calcio.juventus	36
alt.windows7.general	32
cakewalk.audio	30
gmane.linux.alsa.devel	26
fa.netbsd.bugs	21
fa.openbsd.source-changes	21
mozilla.support.thunderbird	17
mozilla.support.seamonkey	17
fa.freebsd.stable	16
fa.openbsd.tech	16
fa.freebsd.bugs	15
gmane.comp.bacula.user	14
mpc.lists.nanog	14
lada.commerce	13
TOTAL: 149	1655

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

Groups	Count
nz.politics.announce	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13665
EMP (phn path)	2656
EMP (phl)	642
EMP (phn nph)	184
Too many newsgroups	70
Binary Payload	43
Scoring filter (18)	26
Scoring filter (16)	15
Scoring filter (19)	15
Scoring filter (15)	14
Scoring filter (14)	14
Scoring filter (8)	12
Scoring filter (11)	12
User-issued cancel	11
Scoring filter (20)	10
Scoring filter (17)	9
HTML Multipart	9
Scoring filter (13)	8
Scoring filter (12)	7
Scoring filter (9)	6
TOTAL: 29	17446

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	45	0	46	46
TOTAL: 1	45	0	46	46

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	feeder.eternal-september.org	424
	csiph-out.news.weretis.net	288
	news.bbs.geek.nz	75
	news-out.mixmin.net	20
	news.fcku.it	18
	news.freedyn.net	18
	newsfeeds.xmission.com	12
	news.redatomik.org	3
	newsfeed.fu-berlin.de	1
	xanadu-bbs.net	1
	TOTAL: 10	860
Including strange strings
	newsfeed.fu-berlin.de	381
	feeder.eternal-september.org	39
	csiph-out.feeder.erje.net	34
	csiph-out.news.weretis.net	30
	news-out.aioe.org	18
	news-out.mixmin.net	16
	news.redatomik.org	13
	newsfeed.neva.ru	8
	news.swapon.de	1
	TOTAL: 9	540
TOTAL: 2		1400

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	41250	3131	19326	72	1	0	7%	23:59:55
2	news.freedyn.net	24970	1310	21789	10	0	1	5%	23:59:56
3	xanadu-bbs	26525	799	22139	3	0	117	3%	23:59:56
4	fu-berlin.de	21772	575	21197	0	0	0	2%	23:59:56
5	usenet.pasdenom.info	26104	253	22030	854	0	1	0%	23:59:50
6	news.redatomik.org	24022	53	21771	12	0	0	0%	23:59:43
7	xmission	25686	50	21407	3	0	0	0%	23:59:42
8	eternal-september	21606	27	18813	1	0	1	0%	23:59:56
9	aioe	20409	20	19254	3	0	0	0%	23:57:09
10	news.mixmin.net	21092	7	20206	0	0	0	0%	23:59:49
11	weretis.net	16628	5	15849	0	0	0	0%	23:50:04
12	news.swapon.de	2204	2	2081	7	0	0	0%	23:59:56
13	neva.ru	22800	1	20889	14	0	1	0%	23:59:56
14	news.fcku.it	2933	1	2733	0	0	0	0%	22:17:18
15	bwh	0	0	0	0	0	22525	0%	23:58:42
16	erje	16038	0	15823	3	0	0	0%	23:59:56
17	au2pb	0	0	0	0	0	22533	0%	23:58:43
TOTAL: 17		314039	6234	265307	982	1	45179	1%	406:00:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	8.0 MB	252.1 KB	8.2 MB	0.1 KB/s	2.6 KB	23:59:55
2	news.freedyn.net	7.6 MB	73.7 KB	7.7 MB	0.1 KB/s	5.9 KB	23:59:56
3	xanadu-bbs	6.3 MB	8.7 KB	6.3 MB	0.1 KB/s	8.0 KB	23:59:56
4	fu-berlin.de	5.7 MB	0.0 KB	5.7 MB	0.1 KB/s	10.2 KB	23:59:56
5	usenet.pasdenom.info	694.0 KB	6.3 MB	7.0 MB	0.1 KB/s	6.5 KB	23:59:50
6	news.redatomik.org	190.8 KB	134.0 KB	324.8 KB	0.0 KB/s	5.0 KB	23:59:43
7	xmission	152.4 KB	3.9 KB	156.4 KB	0.0 KB/s	3.0 KB	23:59:42
8	eternal-september	117.2 KB	6.8 KB	124.0 KB	0.0 KB/s	4.4 KB	23:59:56
9	aioe	66.3 KB	22.4 KB	88.7 KB	0.0 KB/s	3.9 KB	23:57:09
10	news.mixmin.net	40.7 KB	0.0 KB	40.7 KB	0.0 KB/s	5.8 KB	23:59:49
11	weretis.net	12.9 KB	0.0 KB	12.9 KB	0.0 KB/s	2.6 KB	23:50:04
12	news.swapon.de	4.6 KB	36.1 KB	40.8 KB	0.0 KB/s	4.5 KB	23:59:56
13	neva.ru	1.9 KB	34.0 KB	35.9 KB	0.0 KB/s	2.4 KB	23:59:56
14	news.fcku.it	1.6 KB	0.0 KB	1.6 KB	0.0 KB/s	1.6 KB	22:17:18
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
16	erje	0.0 KB	36.0 KB	36.0 KB	0.0 KB/s	12.0 KB	23:59:56
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:43
TOTAL: 17		28.9 MB	6.9 MB	35.8 MB	0.0 KB/s	5.1 KB	406:00:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	16	2	8.1 KB	2	0	0	04:02:51
2	68-185-181-64.dhcp.mdsn.wi.charter.com	6	1	3.1 KB	1	1	1	01:00:47
3	dhcp-089-099-203-254.chello.nl	55	1	3.1 KB	1	0	0	08:41:03
TOTAL: 3		77	4	14.3 KB	4	1	1	21:04:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	16	2	8.1 KB	2	0	0	04:02:51
2	*.dhcp.mdsn.wi.charter.com	6	1	3.1 KB	1	1	1	01:00:47
3	*.chello.nl	55	1	3.1 KB	1	0	0	08:41:03
4	*.134.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	02:56:23
5	*.nielsenmedia.com	4	0	0.0 KB	0	0	0	01:37:49
6	unresolved	108	0	0.0 KB	0	0	0	02:45:12
7	*.members.linode.com	1	0	0.0 KB	0	0	0	00:00:01
8	*.2.89.75.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 8		192	4	14.3 KB	4	1	1	21:04:09

NNRP auth users [Top 20]:

User	Conn
ohlandl	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	4.647	1.288	0.000	08:41:03
tempe0.bbox.io	1.675	0.287	0.000	04:02:51
h125.134.22.98.dynamic.ip.windstream.net	0.175	0.109	0.000	02:56:23
5.51.176.30	9.187	3.400	0.000	01:44:53
challenger.nielsenmedia.com	0.339	0.109	0.000	01:37:49
68-185-181-64.dhcp.mdsn.wi.charter.com	0.573	0.149	0.036	01:00:47
47.192.187.101	0.166	0.031	0.000	01:00:03
196.52.22.56	0.302	0.173	0.000	00:00:14
li626-43.members.linode.com	0.096	0.024	0.000	00:00:01
TOTAL: 9	17.160	5.570	0.036	21:04:09

Curious NNRP server explorers [Top 20]:

System	Conn
5.51.176.30	105
challenger.nielsenmedia.com	4
47.192.187.101	2
li626-43.members.linode.com	1
h114.2.89.75.dynamic.ip.windstream.net	1
h125.134.22.98.dynamic.ip.windstream.net	1
196.52.22.56	1
TOTAL: 7	115

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	7	0
challenger.nielsenmedia.com	3	0
47.192.187.101	2	0
h125.134.22.98.dynamic.ip.windstream.net	2	0
TOTAL: 4	14	1

Newsgroup request counts (by category):

Category		Count	Pct
1	muc	2	50.0%
2	comp	2	50.0%
TOTAL: 2		4	100.0%

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

Newsgroup		Count
1	muc.lists.freebsd.stable	2
2	comp.sys.ibm.ps2.hardware	2
TOTAL: 2		4

Daily Usenet report for csiph.com

Sep 30 04:15:00 -- Oct 1 04:15:00