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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	54288	45.9%	10.0 MB	61.9%
nnrpd	32559	27.5%	2.7 MB	16.9%
innd	28223	23.8%	3.0 MB	18.2%
innfeed	3166	2.7%	487.3 KB	2.9%
nocem	126	0.1%	13.0 KB	0.1%
TOTAL: 5	118362	100.0%	16.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	669654	57.1%
Negative hits	438076	37.3%
Do not exist	53182	4.5%
Cache misses	12735	1.1%
TOTAL: 4	1173647	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.426	0.0%	464	0.000	0.918	27.000
article cleanup	00:00:00.314	0.0%	51998	0.000	0.006	0.024
article logging	00:00:00.683	0.0%	52484	0.000	0.013	0.034
article parse	00:00:28.474	0.0%	270529	0.002	0.105	0.451
article write	00:00:19.316	0.0%	28318	0.211	0.682	10.955
artlog/artcncl	00:00:00.009	0.0%	362	0.000	0.025	1.000
artlog/artparse	00:00:00.026	0.0%	2088	0.000	0.012	0.111
data move	00:00:00.385	0.0%	1160017	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.017	0.0%	352	0.000	0.048	3.000
hishave/artcncl	00:00:00.003	0.0%	464	0.000	0.006	0.091
hishave/artparse	00:00:00.004	0.0%	1294	0.000	0.003	0.062
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:06.019	0.0%	1204413	0.001	0.005	0.127
history sync	00:00:01.120	0.0%	339	0.000	3.304	73.000
history write	00:00:32.891	0.0%	52608	0.098	0.625	5.728
hiswrite/artcncl	00:00:00.035	0.0%	112	0.000	0.312	0.556
hiswrite/artparse	00:00:00.869	0.0%	1294	0.000	0.672	8.667
idle	23:54:36.610	99.6%	1195710	43.599	71.988	230.014
nntp read	00:00:03.886	0.0%	1202953	0.001	0.003	0.005
overview write	00:01:08.122	0.1%	28318	0.243	2.406	37.790
perl filter	00:02:10.272	0.2%	51743	1.127	2.518	3.740
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.530	0.0%	56756	0.000	0.009	0.027
TOTAL: 24:00:39.297	23:59:30.011	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.083	0.0%	45672	0.000	0.002	0.024
article prepare	00:00:00.006	0.0%	30308	0.000	0.000	0.007
article read	00:00:00.554	0.0%	3270	0.000	0.169	3.133
backlog stats	00:00:00.306	0.0%	634724	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.426	0.0%	469943	0.001	0.005	0.009
data write	00:00:03.130	0.0%	448503	0.003	0.007	0.011
idle	23:52:40.089	100.0%	634724	67.110	135.429	346.223
status file	00:00:00.186	0.0%	278	0.000	0.669	1.000
TOTAL: 23:52:59.641	23:52:46.780	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.490	0.0%	5158	0.000	0.095	0.578
idle	32:40:08.126	99.7%	36914	299.000	3186.003	600480.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:23.563	0.0%	166083	0.000	0.142	105.113
readart	00:00:00.408	0.0%	87	0.000	4.690	49.000
TOTAL: 32:46:18.296	32:40:32.587	99.7%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1156	192672	6819	174753	11100	3%	94:53:25
2	usenet.blueworldhosting.com	288	35966	6672	24092	5202	18%	23:45:18
3	feeder.eternal-september.org	9	63458	4008	58737	713	6%	25:57:37
4	news-out.aioe.org	10	29284	2285	26950	49	7%	24:19:00
5	newsfeed.neva.ru	149	73776	1876	67284	4616	2%	72:16:20
6	news-out.mixmin.net	2	33907	1688	31695	524	4%	24:09:50
7	csiph-out.news.weretis.net	4	58820	1634	56895	291	2%	47:05:52
8	newsfeed.fu-berlin.de	33	33224	1131	31869	224	3%	29:21:55
9	au2pb.net	1	31120	778	30240	102	2%	24:24:16
10	newsfeeds.xmission.com	2	31028	485	30522	21	1%	28:01:57
11	news.fcku.it	1	7224	354	6870	0	4%	22:24:46
12	news.swapon.de	3	2440	208	2222	10	8%	23:53:32
13	news.redatomik.org	3	13255	203	13026	26	1%	14:00:35
14	xanadu-bbs.net	1	53368	102	50334	2932	0%	26:29:28
15	news.freedyn.net	10	34930	64	34853	13	0%	24:20:26
16	nntp.pasdenom.info	2	29539	22	29500	17	0%	23:59:30
17	news.bbs.geek.nz	2	9088	2	9084	2	0%	10:15:43
18	localhost	33	0	0	0	0	0%	00:00:00
TOTAL: 18		1709	733099	28331	678926	25842	3%	539:39:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	28.5 MB	655.6 KB	62.8 MB	92.0 MB	30%	5.3 KB
2	usenet.blueworldhosting.com	18.8 MB	0.0 KB	135.7 MB	154.4 MB	12%	13.3 KB
3	feeder.eternal-september.org	10.2 MB	50.2 KB	8.0 MB	18.2 MB	55%	4.0 KB
4	newsfeed.neva.ru	6.0 MB	443.4 KB	28.7 MB	35.1 MB	17%	5.5 KB
5	csiph-out.news.weretis.net	5.6 MB	7.8 KB	6.1 MB	11.7 MB	47%	6.2 KB
6	news-out.mixmin.net	5.4 MB	17.9 KB	883.3 KB	6.3 MB	85%	2.9 KB
7	news-out.aioe.org	5.2 MB	0.0 KB	82.7 KB	5.3 MB	98%	2.3 KB
8	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	486.1 KB	3.1 MB	84%	2.4 KB
9	au2pb.net	1.6 MB	2.8 KB	521.2 KB	2.1 MB	76%	2.5 KB
10	newsfeeds.xmission.com	1.6 MB	0.0 KB	784.5 KB	2.4 MB	67%	4.8 KB
11	news.fcku.it	655.7 KB	0.0 KB	0.0 KB	655.7 KB	100%	1.9 KB
12	news.swapon.de	547.9 KB	4.0 KB	1.0 MB	1.6 MB	34%	7.3 KB
13	news.redatomik.org	529.4 KB	0.0 KB	82.0 KB	611.3 KB	86%	2.7 KB
14	xanadu-bbs.net	426.0 KB	0.0 KB	83.1 MB	83.6 MB	0%	28.2 KB
15	news.freedyn.net	203.4 KB	3.5 KB	32.7 KB	239.6 KB	84%	3.1 KB
16	nntp.pasdenom.info	52.9 KB	0.0 KB	114.1 KB	167.0 KB	31%	4.3 KB
17	news.bbs.geek.nz	3.0 KB	0.0 KB	3.3 KB	6.2 KB	47%	1.6 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		87.9 MB	1.2 MB	328.4 MB	417.4 MB	21%	7.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 9 04:15:00 - 04:59:59	405	1.5%	0.15	1.2 MB	1.4%	0.46
Jan 9 05:00:00 - 05:59:59	602	2.2%	0.17	1.8 MB	2.1%	0.52
Jan 9 06:00:00 - 06:59:59	581	2.1%	0.16	1.8 MB	2.1%	0.51
Jan 9 07:00:00 - 07:59:59	671	2.4%	0.19	2.2 MB	2.5%	0.62
Jan 9 08:00:00 - 08:59:59	891	3.2%	0.25	2.6 MB	3.0%	0.75
Jan 9 09:00:00 - 09:59:59	987	3.6%	0.27	2.9 MB	3.3%	0.81
Jan 9 10:00:00 - 10:59:59	1319	4.8%	0.37	4.9 MB	5.6%	1.39
Jan 9 11:00:00 - 11:59:59	1618	5.8%	0.45	7.0 MB	8.0%	1.98
Jan 9 12:00:00 - 12:59:59	1343	4.8%	0.37	4.3 MB	5.0%	1.23
Jan 9 13:00:00 - 13:59:59	1314	4.7%	0.36	3.8 MB	4.4%	1.09
Jan 9 14:00:00 - 14:59:59	1545	5.6%	0.43	5.1 MB	5.9%	1.44
Jan 9 15:00:00 - 15:59:59	1539	5.5%	0.43	4.2 MB	4.8%	1.19
Jan 9 16:00:00 - 16:59:59	1692	6.1%	0.47	4.9 MB	5.7%	1.40
Jan 9 17:00:00 - 17:59:59	1766	6.4%	0.49	5.1 MB	5.9%	1.44
Jan 9 18:00:00 - 18:59:59	1688	6.1%	0.47	5.0 MB	5.8%	1.43
Jan 9 19:00:00 - 19:59:59	1851	6.7%	0.51	5.5 MB	6.4%	1.58
Jan 9 20:00:00 - 20:59:59	1529	5.5%	0.42	5.0 MB	5.8%	1.43
Jan 9 21:00:00 - 21:59:59	1411	5.1%	0.39	3.7 MB	4.3%	1.06
Jan 9 22:00:00 - 22:59:59	1331	4.8%	0.37	3.8 MB	4.4%	1.09
Jan 9 23:00:00 - 23:59:59	923	3.3%	0.26	2.8 MB	3.2%	0.78
Jan 10 00:00:00 - 00:59:59	836	3.0%	0.23	2.5 MB	2.9%	0.71
Jan 10 01:00:00 - 01:59:59	692	2.5%	0.19	2.4 MB	2.8%	0.69
Jan 10 02:00:00 - 02:59:59	544	2.0%	0.15	1.7 MB	1.9%	0.47
Jan 10 03:00:00 - 03:59:59	529	1.9%	0.15	1.8 MB	2.1%	0.51
Jan 10 04:00:00 - 04:15:00	129	0.5%	0.14	391.0 KB	0.4%	0.43
TOTAL: 24:00:00	27736	100.0%	0.32	86.4 MB	100.0%	1.02

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	11117	1111	67	1	9938
2	usenet.blueworldhosting.com	5210	1217	0	4	3989
3	newsfeed.neva.ru	4602	1088	41	1	3472
4	xanadu-bbs.net	2863	31	0	0	2832
5	feeder.eternal-september.org	713	97	2	0	614
6	newsfeed.fu-berlin.de	615	43	0	0	572
7	news-out.mixmin.net	535	2	3	0	530
8	csiph-out.news.weretis.net	288	200	2	0	86
9	au2pb.net	100	86	1	0	13
10	news-out.aioe.org	67	16	0	10	41
11	news.swapon.de	19	0	0	0	19
12	nntp.pasdenom.info	17	16	0	0	1
13	news.freedyn.net	16	0	1	0	15
14	news.redatomik.org	15	0	0	0	15
15	newsfeeds.xmission.com	12	9	0	0	3
16	news.fcku.it	1	0	0	0	1
17	news.bbs.geek.nz	1	1	0	0	0
TOTAL: 17		26191	3917	117	16	22141

Unwanted newsgroups [Top 20]:

Newsgroup	Count
microsoft.public.windows.chromeffects	454
fa.linux.kernel	249
alt.biology	189
mailing.freebsd.pkg-fallout	182
lada.talk	181
mailing.freebsd.svn-src-all	175
fa.freebsd.cvs-all	173
ut.test	164
mailing.freebsd.svn-src-stable	144
mailing.freebsd.svn-src-stable-10	143
mailing.freebsd.ports-bugs	143
pld.cvs.commit	96
lada.auto	77
mozilla.general	64
mailing.freebsd.svn-ports-all	56
mailing.freebsd.svn-ports-head	55
microsoft.public.windows.chromeeffects	55
alt.windows7.general	50
alt.survival	42
it-alt.sport.calcio.juventus	40
TOTAL: 224	3917

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

Groups	Count
uk.radio.amateur.moderated	13
uk.legal.moderated	3
TOTAL: 2	16

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12632
EMP (phn path)	3427
EMP (phl)	1016
User-issued cancel	956
PFM bot misc	854
EMP (phn nph)	223
Too many newsgroups	58
HTML Multipart	36
Binary Payload	30
Scoring filter (21)	29
Scoring filter (29)	28
Scoring filter (19)	20
Scoring filter (23)	19
Scoring filter (26)	19
Scoring filter (24)	18
Scoring filter (20)	14
Scoring filter (8)	11
Scoring filter (11)	11
Scoring filter (18)	11
Scoring filter (22)	9
TOTAL: 41	19491

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	120	0	122	122
TOTAL: 1	120	0	122	122

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	64
	usenet.blueworldhosting.com	3
	TOTAL: 2	67
Bad Message-ID's offered
	csiph-out.feeder.erje.net	3
	usenet.blueworldhosting.com	1
	TOTAL: 2	4
Huge articles
	usenet.blueworldhosting.com	855
	xanadu-bbs.net	350
	csiph-out.news.weretis.net	42
	feeder.eternal-september.org	41
	news.swapon.de	4
	newsfeeds.xmission.com	3
	au2pb.net	3
	news-out.mixmin.net	1
	news.redatomik.org	1
	TOTAL: 9	1300
Including strange strings
	newsfeed.fu-berlin.de	384
	csiph-out.feeder.erje.net	23
	news-out.aioe.org	23
	feeder.eternal-september.org	16
	csiph-out.news.weretis.net	16
	news.swapon.de	12
	news-out.mixmin.net	7
	au2pb.net	2
	news.fcku.it	1
	newsfeed.neva.ru	1
	TOTAL: 10	485
No colon-space in header
	csiph-out.feeder.erje.net	524
	TOTAL: 1	524
TOTAL: 5		2380

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	30514	1367	26291	10	0	4%	23:59:52
2	xanadu-bbs	29357	1230	26363	31	8	4%	23:59:52
3	fu-berlin.de	26186	1063	25110	12	0	4%	23:59:52
4	news.bbs.geek.nz	49763	664	25805	18	0	1%	23:59:52
5	aioe	25847	374	22737	12	50	1%	23:59:48
6	usenet.pasdenom.info	34820	261	25662	1793	11	0%	23:59:52
7	news.redatomik.org	33266	238	26090	26	1141	0%	23:59:49
8	au2pb	26378	60	25690	39	0	0%	23:59:52
9	neva.ru	28744	30	24291	123	0	0%	23:59:52
10	xmission	30388	24	25832	1	0	0%	23:59:52
11	news.mixmin.net	28152	10	24535	5	0	0%	23:59:47
12	news.fcku.it	2383	8	2119	0	0	0%	23:08:16
13	eternal-september	44123	7	38701	9	288	0%	23:59:44
14	weretis.net	24622	6	24111	1	0	0%	23:59:51
15	news.swapon.de	2334	2	2104	6	0	0%	23:51:56
16	bwh	20684	1	19704	5	0	0%	23:59:25
17	erje	19979	0	18763	152	0	0%	23:59:52
TOTAL: 17		457540	5345	383908	2243	1498	1%	406:57:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.4 MB	77.6 KB	10.5 MB	0.1 KB/s	7.8 KB	23:59:52
2	xanadu-bbs	9.7 MB	68.5 KB	9.8 MB	0.1 KB/s	7.9 KB	23:59:52
3	fu-berlin.de	9.3 MB	15.0 KB	9.4 MB	0.1 KB/s	8.9 KB	23:59:52
4	news.bbs.geek.nz	2.2 MB	78.4 KB	2.3 MB	0.0 KB/s	3.5 KB	23:59:52
5	aioe	1.5 MB	81.4 KB	1.6 MB	0.0 KB/s	4.2 KB	23:59:48
6	usenet.pasdenom.info	1.1 MB	10.4 MB	11.5 MB	0.1 KB/s	5.7 KB	23:59:52
7	news.redatomik.org	850.3 KB	123.0 KB	973.3 KB	0.0 KB/s	3.7 KB	23:59:49
8	au2pb	234.2 KB	183.0 KB	417.2 KB	0.0 KB/s	4.2 KB	23:59:52
9	neva.ru	206.9 KB	668.4 KB	875.3 KB	0.0 KB/s	5.7 KB	23:59:52
10	xmission	80.5 KB	1.7 KB	82.3 KB	0.0 KB/s	3.3 KB	23:59:52
11	news.mixmin.net	54.1 KB	41.6 KB	95.7 KB	0.0 KB/s	6.4 KB	23:59:47
12	news.fcku.it	19.6 KB	0.0 KB	19.6 KB	0.0 KB/s	2.4 KB	23:08:16
13	eternal-september	17.1 KB	35.5 KB	52.6 KB	0.0 KB/s	3.3 KB	23:59:44
14	weretis.net	14.6 KB	21.6 KB	36.1 KB	0.0 KB/s	5.2 KB	23:59:51
15	news.swapon.de	7.1 KB	27.3 KB	34.3 KB	0.0 KB/s	4.3 KB	23:51:56
16	bwh	1.8 KB	14.7 KB	16.5 KB	0.0 KB/s	2.7 KB	23:59:25
17	erje	0.0 KB	1.1 MB	1.1 MB	0.0 KB/s	7.5 KB	23:59:52
TOTAL: 17		35.8 MB	12.9 MB	48.7 MB	0.0 KB/s	6.6 KB	406:57:24

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	63	172.8 KB	62	04:09:25
2	tempe0.bbox.io	80	15	40.9 KB	7	05:56:17
3	pool-108-57-58-12.sctnpa.east.verizon.net	4	7	17.8 KB	2	00:12:45
4	dhcp-089-099-203-254.chello.nl	21	2	2.7 KB	2	07:56:30
TOTAL: 4		393	87	234.2 KB	73	32:46:13

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	348	63	172.8 KB	62	15:28:45
2	*.bbox.io	80	15	40.9 KB	7	05:56:17
3	*.sctnpa.east.verizon.net	4	7	17.8 KB	2	00:12:45
4	*.chello.nl	21	2	2.7 KB	2	07:56:30
5	*.tampfl.dsl-w.verizon.net	4	0	0.0 KB	0	02:00:04
6	*.143.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	01:11:43
7	*.dyn.optonline.net	2	0	0.0 KB	0	00:00:07
TOTAL: 7		461	87	234.2 KB	73	32:46:13

NNRP auth users [Top 20]:

User	Conn
moussa	288
kshuff	4
TOTAL: 2	292

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.221	0.814	0.280	0.000	08:25:48
dhcp-089-099-203-254.chello.nl	1.881	0.408	0.000	07:56:30
tempe0.bbox.io	6.559	1.216	0.000	05:56:17
202.89.176.220	28.100	6.628	0.762	04:09:25
63.142.120.8	5.542	1.074	0.000	02:15:31
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.338	0.079	0.000	02:00:04
h55.143.22.98.dynamic.ip.windstream.net	0.246	0.099	0.000	01:11:43
138.108.60.60	0.150	0.030	0.000	00:37:59
pool-108-57-58-12.sctnpa.east.verizon.net	0.581	0.589	0.010	00:12:45
ool-2f14e00c.dyn.optonline.net	0.229	0.096	0.000	00:00:07
TOTAL: 10	44.440	10.499	0.772	32:46:13

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	50
162.212.88.221	8
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4
h55.143.22.98.dynamic.ip.windstream.net	2
ool-2f14e00c.dyn.optonline.net	2
138.108.60.60	2
TOTAL: 6	68

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	13	0
tempe0.bbox.io	11	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4	0
138.108.60.60	1	0
TOTAL: 4	29	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	72	82.8%
2	news	15	17.2%
TOTAL: 2		87	100.0%

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

Newsgroup		Count
1	comp.os.vms	15
2	comp.arch	14
3	news.software.nntp	10
4	comp.arch.embedded	7
5	comp.sys.ibm.ps2.hardware	6
6	news.admin.peering	5
7	comp.ai.philosophy	5
8	comp.sys.sgi.marketplace	4
9	comp.sys.sgi.hardware	3
10	comp.sys.hp.hardware	3
11	comp.arch.fpga	3
12	comp.sys.ibm.pc.games.action	3
13	comp.os.os2.misc	2
14	comp.os.os2.apps	2
15	comp.sys.ibm.as400.misc	2
16	comp.os.linux.networking	1
17	comp.unix.bsd.freebsd.misc	1
18	comp.ai.neural-nets	1
TOTAL: 18		87

Daily Usenet report for csiph.com

Jan 9 04:15:00 -- Jan 10 04:15:00